builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0070 starttime: 1493078746.39 results: success (0) buildid: 20170424155425 builduid: cc59bdfc84954c2eab2764ac026ed9eb revision: abdcc8dfc28397b95338245390e12c56658ad182 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:46.385330) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:46.385756) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:46.386028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:46.413130) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:46.413408) ========= 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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-04-24 17:05:46-- 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% 9.31M=0.001s 2017-04-24 17:05:46 (9.31 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109192 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:46.547315) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:46.547627) ========= 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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.030119 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:46.599087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-24 17:05:46.599442) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev abdcc8dfc28397b95338245390e12c56658ad182 --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 abdcc8dfc28397b95338245390e12c56658ad182 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Kyj7i8Tfku/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.O9s64EIDj3/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-04-24 17:05:46,703 truncating revision to first 12 chars 2017-04-24 17:05:46,703 Setting DEBUG logging. 2017-04-24 17:05:46,703 attempt 1/10 2017-04-24 17:05:46,703 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/abdcc8dfc283?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-24 17:05:48,132 unpacking tar archive at: mozilla-central-abdcc8dfc283/testing/mozharness/ program finished with exit code 0 elapsedTime=1.706336 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-24 17:05:48.330162) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:48.330500) ========= script_repo_revision: abdcc8dfc28397b95338245390e12c56658ad182 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:48.330901) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:48.331190) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-24 17:05:48.351684) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-24 17:05:48.352052) ========= /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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:05:48 INFO - MultiFileLogger online at 20170424 17:05:48 in /builds/slave/test 17:05:48 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 17:05:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:05:48 INFO - {'append_to_log': False, 17:05:48 INFO - 'base_work_dir': '/builds/slave/test', 17:05:48 INFO - 'blob_upload_branch': 'Firefox', 17:05:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:05:48 INFO - 'branch': 'Firefox', 17:05:48 INFO - 'buildbot_json_path': 'buildprops.json', 17:05:48 INFO - 'config_files': ('talos/mac_config.py',), 17:05:48 INFO - 'default_actions': ('clobber', 17:05:48 INFO - 'read-buildbot-config', 17:05:48 INFO - 'download-and-extract', 17:05:48 INFO - 'populate-webroot', 17:05:48 INFO - 'create-virtualenv', 17:05:48 INFO - 'install', 17:05:48 INFO - 'run-tests'), 17:05:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:05:48 INFO - 'download_minidump_stackwalk': True, 17:05:48 INFO - 'download_symbols': 'ondemand', 17:05:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:05:48 INFO - 'tooltool.py': '/tools/tooltool.py', 17:05:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:05:48 INFO - '/tools/misc-python/virtualenv.py')}, 17:05:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:05:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:05:48 INFO - 'gecko_profile': False, 17:05:48 INFO - 'gecko_profile_interval': 0, 17:05:48 INFO - 'installer_path': 'installer.exe', 17:05:48 INFO - 'log_level': 'info', 17:05:48 INFO - 'log_name': 'talos', 17:05:48 INFO - 'log_to_console': True, 17:05:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:05:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:05:48 INFO - 'opt_config_files': (), 17:05:48 INFO - 'pip_index': False, 17:05:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:05:48 INFO - 'cmd': ('bash', 17:05:48 INFO - '-c', 17:05:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:05:48 INFO - 'enabled': True, 17:05:48 INFO - 'halt_on_failure': False, 17:05:48 INFO - 'name': 'check_screen_resolution'},), 17:05:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:05:48 INFO - 'cmd': ('bash', 17:05:48 INFO - '-c', 17:05:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:05:48 INFO - 'enabled': True, 17:05:48 INFO - 'halt_on_failure': False, 17:05:48 INFO - 'name': 'check_screen_resolution'},), 17:05:48 INFO - 'run_cmd_checks_enabled': True, 17:05:48 INFO - 'suite': 'dromaeojs-e10s', 17:05:48 INFO - 'system_bits': '32', 17:05:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:05:48 INFO - 'title': 't-yosemite-r7-0070', 17:05:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:05:48 INFO - 'use_talos_json': True, 17:05:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:05:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:05:48 INFO - 'work_dir': 'build'} 17:05:48 INFO - [mozharness: 2017-04-25 00:05:48.569468Z] Running clobber step. 17:05:48 INFO - Running pre-action listener: _resource_record_pre_action 17:05:48 INFO - Running main action method: clobber 17:05:48 INFO - rmtree: /builds/slave/test/build 17:05:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:05:51 INFO - Running post-action listener: _resource_record_post_action 17:05:51 INFO - [mozharness: 2017-04-25 00:05:51.156136Z] Finished clobber step (success) 17:05:51 INFO - [mozharness: 2017-04-25 00:05:51.156266Z] Running read-buildbot-config step. 17:05:51 INFO - Running pre-action listener: _resource_record_pre_action 17:05:51 INFO - Running main action method: read_buildbot_config 17:05:51 INFO - Using buildbot properties: 17:05:51 INFO - { 17:05:51 INFO - "project": "", 17:05:51 INFO - "product": "firefox", 17:05:51 INFO - "script_repo_revision": "production", 17:05:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:05:51 INFO - "repository": "", 17:05:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:05:51 INFO - "buildid": "20170424155425", 17:05:51 INFO - "pgo_build": "False", 17:05:51 INFO - "basedir": "/builds/slave/test", 17:05:51 INFO - "buildnumber": 39, 17:05:51 INFO - "slavename": "t-yosemite-r7-0070", 17:05:51 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 17:05:51 INFO - "platform": "yosemite_r7", 17:05:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:05:51 INFO - "branch": "mozilla-central", 17:05:51 INFO - "repo_path": "mozilla-central", 17:05:51 INFO - "revision": "abdcc8dfc28397b95338245390e12c56658ad182", 17:05:51 INFO - "stage_platform": "macosx64", 17:05:51 INFO - "builduid": "cc59bdfc84954c2eab2764ac026ed9eb", 17:05:51 INFO - "slavebuilddir": "test" 17:05:51 INFO - } 17:05:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 17:05:51 INFO - Running post-action listener: _resource_record_post_action 17:05:51 INFO - [mozharness: 2017-04-25 00:05:51.159612Z] Finished read-buildbot-config step (success) 17:05:51 INFO - [mozharness: 2017-04-25 00:05:51.159744Z] Running download-and-extract step. 17:05:51 INFO - Running pre-action listener: _resource_record_pre_action 17:05:51 INFO - Running main action method: download_and_extract 17:05:51 INFO - mkdir: /builds/slave/test/build/tests 17:05:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:05:51 INFO - trying https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:05:51 INFO - Downloading https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/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 17:05:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/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 17:05:52 INFO - Downloaded 1325 bytes. 17:05:52 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:05:52 INFO - Using the following test package requirements: 17:05:52 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:05:52 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:05:52 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:05:52 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:05:52 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'jsshell-mac.zip'], 17:05:52 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:05:52 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:05:52 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:05:52 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:05:52 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:05:52 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:05:52 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:05:52 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:05:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:05:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:05:52 INFO - Fetch https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:05:54 INFO - Content-Length response header: 52524514 17:05:54 INFO - Bytes received: 52524514 17:06:10 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 17:06:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:06:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:06:10 INFO - Fetch https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:06:12 INFO - Content-Length response header: 52524514 17:06:12 INFO - Bytes received: 52524514 17:06:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:06:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:06:28 INFO - Fetch https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:06:28 INFO - Content-Length response header: 14408822 17:06:28 INFO - Bytes received: 14408822 17:06:29 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')]} 17:06:29 INFO - trying https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:06:29 INFO - Downloading https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:06:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/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 17:06:31 INFO - Downloaded 71095166 bytes. 17:06:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:06:31 INFO - mkdir: /builds/slave/test/properties 17:06:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:06:31 INFO - Writing to file /builds/slave/test/properties/build_url 17:06:31 INFO - Contents: 17:06:31 INFO - build_url:https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:06:32 INFO - Running post-action listener: _resource_record_post_action 17:06:32 INFO - Running post-action listener: set_extra_try_arguments 17:06:32 INFO - [mozharness: 2017-04-25 00:06:32.019789Z] Finished download-and-extract step (success) 17:06:32 INFO - [mozharness: 2017-04-25 00:06:32.019891Z] Running populate-webroot step. 17:06:32 INFO - Running pre-action listener: _resource_record_pre_action 17:06:32 INFO - Running main action method: populate_webroot 17:06:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:06:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:06:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:06:32 INFO - '--tppagecycles', 17:06:32 INFO - '7'], 17:06:32 INFO - 'tests': ['tsvgm']}}, 17:06:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:06:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:06:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:06:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:06:32 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:06:32 INFO - 'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:32 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:06:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:32 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:06:32 INFO - 'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['damp', 'tps']}, 17:06:32 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:06:32 INFO - 'tests': ['damp', 'tps']}, 17:06:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['dromaeo_dom']}, 17:06:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:06:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:06:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:06:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['a11yr', 17:06:32 INFO - 'ts_paint', 17:06:32 INFO - 'tpaint', 17:06:32 INFO - 'sessionrestore', 17:06:32 INFO - 'sessionrestore_no_auto_restore', 17:06:32 INFO - 'tabpaint']}, 17:06:32 INFO - 'other-e10s': {'tests': ['a11yr', 17:06:32 INFO - 'ts_paint', 17:06:32 INFO - 'tpaint', 17:06:32 INFO - 'sessionrestore', 17:06:32 INFO - 'sessionrestore_no_auto_restore', 17:06:32 INFO - 'tabpaint']}, 17:06:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:06:32 INFO - 'ts_paint', 17:06:32 INFO - 'tpaint', 17:06:32 INFO - 'sessionrestore', 17:06:32 INFO - 'sessionrestore_no_auto_restore', 17:06:32 INFO - 'tabpaint']}, 17:06:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:06:32 INFO - 'ts_paint', 17:06:32 INFO - 'tpaint', 17:06:32 INFO - 'sessionrestore', 17:06:32 INFO - 'sessionrestore_no_auto_restore', 17:06:32 INFO - 'tabpaint']}, 17:06:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['a11yr', 17:06:32 INFO - 'ts_paint', 17:06:32 INFO - 'tpaint', 17:06:32 INFO - 'sessionrestore', 17:06:32 INFO - 'sessionrestore_no_auto_restore', 17:06:32 INFO - 'tabpaint']}, 17:06:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['a11yr', 17:06:32 INFO - 'ts_paint', 17:06:32 INFO - 'tpaint', 17:06:32 INFO - 'sessionrestore', 17:06:32 INFO - 'sessionrestore_no_auto_restore', 17:06:32 INFO - 'tabpaint']}, 17:06:32 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:06:32 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:06:32 INFO - 'bloom_basic_ref']}, 17:06:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['tsvgx', 17:06:32 INFO - 'tsvgr_opacity', 17:06:32 INFO - 'tart', 17:06:32 INFO - 'tscrollx', 17:06:32 INFO - 'cart', 17:06:32 INFO - 'tsvg_static']}, 17:06:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:06:32 INFO - 'tsvgr_opacity', 17:06:32 INFO - 'tart', 17:06:32 INFO - 'tscrollx', 17:06:32 INFO - 'cart', 17:06:32 INFO - 'tsvg_static']}, 17:06:32 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:06:32 INFO - 'talos_options': ['--disable-e10s'], 17:06:32 INFO - 'tests': ['tp5o']}, 17:06:32 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:06:32 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:06:32 INFO - 'talos_options': ['--disable-e10s', 17:06:32 INFO - '--xperf_path', 17:06:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:32 INFO - 'tests': ['tp5n']}, 17:06:32 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:06:32 INFO - 'talos_options': ['--xperf_path', 17:06:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:32 INFO - 'tests': ['tp5n']}}, 17:06:32 INFO - 'talos.zip': {'path': '', 17:06:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:06:32 INFO - Running post-action listener: _resource_record_post_action 17:06:32 INFO - [mozharness: 2017-04-25 00:06:32.038491Z] Finished populate-webroot step (success) 17:06:32 INFO - [mozharness: 2017-04-25 00:06:32.038602Z] Running create-virtualenv step. 17:06:32 INFO - Running pre-action listener: _resource_record_pre_action 17:06:32 INFO - Running main action method: create_virtualenv 17:06:32 INFO - Creating virtualenv /builds/slave/test/build/venv 17:06:32 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 17:06:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:06:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:06:32 INFO - Using real prefix '/tools/python27' 17:06:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:06:32 INFO - Installing distribute.............................................................................................................................................................................................done. 17:06:35 INFO - Installing pip.................done. 17:06:35 INFO - Return code: 0 17:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:06:36 INFO - Reading from file tmpfile_stdout 17:06:36 INFO - Output received: 17:06:36 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) 17:06:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:06: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')]} 17:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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')]} 17:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:36 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:06:36 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 17:06:36 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 17:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:36 INFO - 'HOME': '/Users/cltbld', 17:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:36 INFO - 'LOGNAME': 'cltbld', 17:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:36 INFO - 'MOZ_NO_REMOTE': '1', 17:06:36 INFO - 'NO_EM_RESTART': '1', 17:06:36 INFO - 'PAGER': '/bin/cat', 17:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:36 INFO - 'PWD': '/builds/slave/test', 17:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:36 INFO - 'SHELL': '/bin/bash', 17:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:06:36 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:36 INFO - 'USER': 'cltbld', 17:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:36 INFO - 'XPC_FLAGS': '0x0', 17:06:36 INFO - 'XPC_SERVICE_NAME': '0', 17:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:36 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 17:06:36 INFO - Cleaning up... 17:06:36 INFO - Return code: 0 17:06:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:06: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')]} 17:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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')]} 17:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:36 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:06:36 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 17:06:36 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 17:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:36 INFO - 'HOME': '/Users/cltbld', 17:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:36 INFO - 'LOGNAME': 'cltbld', 17:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:36 INFO - 'MOZ_NO_REMOTE': '1', 17:06:36 INFO - 'NO_EM_RESTART': '1', 17:06:36 INFO - 'PAGER': '/bin/cat', 17:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:36 INFO - 'PWD': '/builds/slave/test', 17:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:36 INFO - 'SHELL': '/bin/bash', 17:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:06:36 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:36 INFO - 'USER': 'cltbld', 17:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:36 INFO - 'XPC_FLAGS': '0x0', 17:06:36 INFO - 'XPC_SERVICE_NAME': '0', 17:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:36 INFO - Downloading/unpacking psutil>=3.1.1 17:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:06:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:40 INFO - Installing collected packages: psutil 17:06:40 INFO - Running setup.py install for psutil 17:06:40 INFO - building 'psutil._psutil_osx' extension 17:06:40 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 17:06:40 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 17:06:40 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 17:06:40 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 17:06:41 INFO - building 'psutil._psutil_posix' extension 17:06:41 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 17:06:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:06:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:06:41 INFO - ^ 17:06:41 INFO - 1 warning generated. 17:06:41 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 17:06:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:41 INFO - Successfully installed psutil 17:06:41 INFO - Cleaning up... 17:06:41 INFO - Return code: 0 17:06:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:41 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:06:41 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 17:06:41 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 17:06:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:06:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:41 INFO - 'HOME': '/Users/cltbld', 17:06:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:41 INFO - 'LOGNAME': 'cltbld', 17:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:41 INFO - 'MOZ_NO_REMOTE': '1', 17:06:41 INFO - 'NO_EM_RESTART': '1', 17:06:41 INFO - 'PAGER': '/bin/cat', 17:06:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:41 INFO - 'PWD': '/builds/slave/test', 17:06:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:41 INFO - 'SHELL': '/bin/bash', 17:06:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:06:41 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:06:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:41 INFO - 'USER': 'cltbld', 17:06:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:41 INFO - 'XPC_FLAGS': '0x0', 17:06:41 INFO - 'XPC_SERVICE_NAME': '0', 17:06:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:06:41 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 17:06:41 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 17:06:41 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 17:06:41 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 17:06:41 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 17:06:41 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 17:06:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:06:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:06:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:06:44 INFO - Installing collected packages: mozsystemmonitor 17:06:44 INFO - Running setup.py install for mozsystemmonitor 17:06:44 INFO - Successfully installed mozsystemmonitor 17:06:44 INFO - Cleaning up... 17:06:44 INFO - Return code: 0 17:06:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:06:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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')]} 17:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:45 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:06:45 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 17:06:45 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 17:06:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:06:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:45 INFO - 'HOME': '/Users/cltbld', 17:06:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:45 INFO - 'LOGNAME': 'cltbld', 17:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:45 INFO - 'MOZ_NO_REMOTE': '1', 17:06:45 INFO - 'NO_EM_RESTART': '1', 17:06:45 INFO - 'PAGER': '/bin/cat', 17:06:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:45 INFO - 'PWD': '/builds/slave/test', 17:06:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:45 INFO - 'SHELL': '/bin/bash', 17:06:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:06:45 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:06:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:45 INFO - 'USER': 'cltbld', 17:06:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:45 INFO - 'XPC_FLAGS': '0x0', 17:06:45 INFO - 'XPC_SERVICE_NAME': '0', 17:06:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:45 INFO - Downloading/unpacking jsonschema==2.5.1 17:06:45 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 17:06:45 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 17:06:45 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 17:06:45 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 17:06:45 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 17:06:45 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 17:06:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:06:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:06:48 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 17:06:48 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 17:06:48 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 17:06:48 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 17:06:48 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 17:06:48 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 17:06:49 INFO - Downloading functools32-3.2.3-2.tar.gz 17:06:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:06:49 INFO - warning: no files found matching '*.txt' 17:06:49 INFO - no previously-included directories found matching 'build' 17:06:49 INFO - no previously-included directories found matching 'dist' 17:06:49 INFO - no previously-included directories found matching '.git*' 17:06:49 INFO - Installing collected packages: jsonschema, functools32 17:06:49 INFO - Running setup.py install for functools32 17:06:49 INFO - warning: no files found matching '*.txt' 17:06:49 INFO - no previously-included directories found matching 'build' 17:06:49 INFO - no previously-included directories found matching 'dist' 17:06:49 INFO - no previously-included directories found matching '.git*' 17:06:49 INFO - Successfully installed jsonschema functools32 17:06:49 INFO - Cleaning up... 17:06:49 INFO - Return code: 0 17:06:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:49 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:06:49 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 17:06:49 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 17:06:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:06:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:49 INFO - 'HOME': '/Users/cltbld', 17:06:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:49 INFO - 'LOGNAME': 'cltbld', 17:06:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:49 INFO - 'MOZ_NO_REMOTE': '1', 17:06:49 INFO - 'NO_EM_RESTART': '1', 17:06:49 INFO - 'PAGER': '/bin/cat', 17:06:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:49 INFO - 'PWD': '/builds/slave/test', 17:06:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:49 INFO - 'SHELL': '/bin/bash', 17:06:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:06:49 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:06:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:49 INFO - 'USER': 'cltbld', 17:06:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:49 INFO - 'XPC_FLAGS': '0x0', 17:06:49 INFO - 'XPC_SERVICE_NAME': '0', 17:06:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:06:49 INFO - Cleaning up... 17:06:49 INFO - Return code: 0 17:06:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:06:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:49 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:06:49 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 17:06:49 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 17:06:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:06:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:49 INFO - 'HOME': '/Users/cltbld', 17:06:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:49 INFO - 'LOGNAME': 'cltbld', 17:06:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:49 INFO - 'MOZ_NO_REMOTE': '1', 17:06:49 INFO - 'NO_EM_RESTART': '1', 17:06:49 INFO - 'PAGER': '/bin/cat', 17:06:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:49 INFO - 'PWD': '/builds/slave/test', 17:06:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:49 INFO - 'SHELL': '/bin/bash', 17:06:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:06:49 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:06:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:49 INFO - 'USER': 'cltbld', 17:06:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:49 INFO - 'XPC_FLAGS': '0x0', 17:06:49 INFO - 'XPC_SERVICE_NAME': '0', 17:06:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:49 INFO - Downloading/unpacking blobuploader==1.2.4 17:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:52 INFO - Downloading blobuploader-1.2.4.tar.gz 17:06:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:06:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:06:52 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 17:06:52 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 17:06:52 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 17:06:52 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 17:06:52 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 17:06:52 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 17:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:06:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:06:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:06:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:06:53 INFO - Downloading docopt-0.6.1.tar.gz 17:06:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:06:54 INFO - Installing collected packages: blobuploader, requests, docopt 17:06:54 INFO - Running setup.py install for blobuploader 17:06:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:06:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:06:54 INFO - Running setup.py install for requests 17:06:54 INFO - Running setup.py install for docopt 17:06:54 INFO - Successfully installed blobuploader requests docopt 17:06:54 INFO - Cleaning up... 17:06:54 INFO - Return code: 0 17:06:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:54 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')]} 17:06:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:54 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')]} 17:06:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:54 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:06:54 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 17:06:54 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 17:06:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:06:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:54 INFO - 'HOME': '/Users/cltbld', 17:06:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:54 INFO - 'LOGNAME': 'cltbld', 17:06:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:54 INFO - 'MOZ_NO_REMOTE': '1', 17:06:54 INFO - 'NO_EM_RESTART': '1', 17:06:54 INFO - 'PAGER': '/bin/cat', 17:06:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:54 INFO - 'PWD': '/builds/slave/test', 17:06:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:54 INFO - 'SHELL': '/bin/bash', 17:06:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:06:54 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:06:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:54 INFO - 'USER': 'cltbld', 17:06:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:54 INFO - 'XPC_FLAGS': '0x0', 17:06:54 INFO - 'XPC_SERVICE_NAME': '0', 17:06:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:06:55 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-SvYE_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:06:55 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-RCdhSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:06:55 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-5p4u64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:06:55 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-WXzoxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:06:55 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ITLcyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:06:55 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-jkenfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:06:55 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-w74L5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:06:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:06:55 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-hufI5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-FSgpsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Y0wO6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-dTqJd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-EsGjmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-_V_p0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-2Kv0WS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-sko7Im-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-1aCez0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:06:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:06:56 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-aD8dc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:06:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:06:56 INFO - Running setup.py install for manifestparser 17:06:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:06:57 INFO - Running setup.py install for mozcrash 17:06:57 INFO - Running setup.py install for mozdebug 17:06:57 INFO - Running setup.py install for mozdevice 17:06:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:06:57 INFO - Running setup.py install for mozfile 17:06:57 INFO - Running setup.py install for mozhttpd 17:06:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:06:57 INFO - Running setup.py install for mozinfo 17:06:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:06:57 INFO - Running setup.py install for mozInstall 17:06:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Running setup.py install for mozleak 17:06:58 INFO - Running setup.py install for mozlog 17:06:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Running setup.py install for moznetwork 17:06:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Running setup.py install for mozprocess 17:06:58 INFO - Running setup.py install for mozprofile 17:06:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:06:58 INFO - Running setup.py install for mozrunner 17:06:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:06:59 INFO - Running setup.py install for mozscreenshot 17:06:59 INFO - Running setup.py install for moztest 17:06:59 INFO - Running setup.py install for mozversion 17:06:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:06:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:06:59 INFO - Cleaning up... 17:06:59 INFO - Return code: 0 17:06:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:59 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')]} 17:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:59 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')]} 17:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:59 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:06:59 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 17:06:59 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 17:06:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:06:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:06:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:06:59 INFO - 'HOME': '/Users/cltbld', 17:06:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:06:59 INFO - 'LOGNAME': 'cltbld', 17:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:59 INFO - 'MOZ_NO_REMOTE': '1', 17:06:59 INFO - 'NO_EM_RESTART': '1', 17:06:59 INFO - 'PAGER': '/bin/cat', 17:06:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:59 INFO - 'PWD': '/builds/slave/test', 17:06:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:06:59 INFO - 'SHELL': '/bin/bash', 17:06:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:06:59 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:06:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:06:59 INFO - 'USER': 'cltbld', 17:06:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:06:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:59 INFO - 'XPC_FLAGS': '0x0', 17:06:59 INFO - 'XPC_SERVICE_NAME': '0', 17:06:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:06:59 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-vQh1Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:06:59 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)) 17:06:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:06:59 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-YRdHnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Ms9vNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-g82OiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-77K4Zp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:07:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-bQMfU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:07:00 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)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ALYTcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:07:00 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)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Hd0CxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:07:00 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)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-7nppOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:07:00 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)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-xTgqMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:07:00 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)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-VWwPux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:07:00 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)) 17:07:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:07:00 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-5PWp8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:07:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:07:01 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-K8j5vO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:07:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:07:01 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-_8WvMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:07:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:01 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-0axPCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:07:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:07:01 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-gVfoKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:07:01 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)) 17:07:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:07:01 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-5xFG0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:07:01 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)) 17:07:01 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)) 17:07:01 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)) 17:07:01 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)) 17:07:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:07:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:07:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:07:01 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 17:07:01 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 17:07:01 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 17:07:01 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 17:07:01 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 17:07:01 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 17:07:04 INFO - Downloading blessings-1.6.tar.gz 17:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:07:04 INFO - Installing collected packages: blessings 17:07:04 INFO - Running setup.py install for blessings 17:07:04 INFO - Successfully installed blessings 17:07:04 INFO - Cleaning up... 17:07:04 INFO - Return code: 0 17:07:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:07:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:07:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:07:04 INFO - Reading from file tmpfile_stdout 17:07:04 INFO - Current package versions: 17:07:04 INFO - blessings == 1.6 17:07:04 INFO - blobuploader == 1.2.4 17:07:04 INFO - docopt == 0.6.1 17:07:04 INFO - functools32 == 3.2.3-2 17:07:04 INFO - jsonschema == 2.5.1 17:07:04 INFO - manifestparser == 1.1 17:07:04 INFO - mozInstall == 1.12 17:07:04 INFO - mozcrash == 1.0 17:07:04 INFO - mozdebug == 0.1 17:07:04 INFO - mozdevice == 0.50 17:07:04 INFO - mozfile == 1.2 17:07:04 INFO - mozhttpd == 0.7 17:07:04 INFO - mozinfo == 0.9 17:07:04 INFO - mozleak == 0.1 17:07:04 INFO - mozlog == 3.4 17:07:04 INFO - moznetwork == 0.27 17:07:04 INFO - mozprocess == 0.25 17:07:04 INFO - mozprofile == 0.28 17:07:04 INFO - mozrunner == 6.13 17:07:04 INFO - mozscreenshot == 0.1 17:07:04 INFO - mozsystemmonitor == 0.3 17:07:04 INFO - moztest == 0.8 17:07:04 INFO - mozversion == 1.4 17:07:04 INFO - psutil == 3.1.1 17:07:04 INFO - requests == 1.2.3 17:07:04 INFO - wsgiref == 0.1.2 17:07:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:07:04 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')]} 17:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:04 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')]} 17:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:04 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 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:07:04 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 17:07:04 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 17:07:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:07:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:04 INFO - 'HOME': '/Users/cltbld', 17:07:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:04 INFO - 'LOGNAME': 'cltbld', 17:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:04 INFO - 'MOZ_NO_REMOTE': '1', 17:07:04 INFO - 'NO_EM_RESTART': '1', 17:07:04 INFO - 'PAGER': '/bin/cat', 17:07:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:04 INFO - 'PWD': '/builds/slave/test', 17:07:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:04 INFO - 'SHELL': '/bin/bash', 17:07:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:07:04 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:07:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:04 INFO - 'USER': 'cltbld', 17:07:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:04 INFO - 'XPC_FLAGS': '0x0', 17:07:04 INFO - 'XPC_SERVICE_NAME': '0', 17:07:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:07:05 INFO - Cleaning up... 17:07:05 INFO - Return code: 0 17:07:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:07:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d6a6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88214be4c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, '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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 17:07:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:07:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:07:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:05 INFO - 'HOME': '/Users/cltbld', 17:07:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:05 INFO - 'LOGNAME': 'cltbld', 17:07:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:05 INFO - 'MOZ_NO_REMOTE': '1', 17:07:05 INFO - 'NO_EM_RESTART': '1', 17:07:05 INFO - 'PAGER': '/bin/cat', 17:07:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:05 INFO - 'PWD': '/builds/slave/test', 17:07:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:05 INFO - 'SHELL': '/bin/bash', 17:07:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:07:05 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:07:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:05 INFO - 'USER': 'cltbld', 17:07:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:05 INFO - 'XPC_FLAGS': '0x0', 17:07:05 INFO - 'XPC_SERVICE_NAME': '0', 17:07:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:07:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:07:05 INFO - Cleaning up... 17:07:05 INFO - Return code: 0 17:07:05 INFO - Running post-action listener: _resource_record_post_action 17:07:05 INFO - Running post-action listener: _start_resource_monitoring 17:07:05 INFO - Starting resource monitoring. 17:07:05 INFO - [mozharness: 2017-04-25 00:07:05.382135Z] Finished create-virtualenv step (success) 17:07:05 INFO - [mozharness: 2017-04-25 00:07:05.383112Z] Running install step. 17:07:05 INFO - Running pre-action listener: _resource_record_pre_action 17:07:05 INFO - Running main action method: install 17:07:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:07:05 INFO - Reading from file tmpfile_stdout 17:07:05 INFO - Detecting whether we're running mozinstall >=1.0... 17:07:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:07:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:07:05 INFO - Reading from file tmpfile_stdout 17:07:05 INFO - Output received: 17:07:05 INFO - Usage: mozinstall [options] installer 17:07:05 INFO - Options: 17:07:05 INFO - -h, --help show this help message and exit 17:07:05 INFO - -d DEST, --destination=DEST 17:07:05 INFO - Directory to install application into. [default: 17:07:05 INFO - "/builds/slave/test"] 17:07:05 INFO - --app=APP Application being installed. [default: firefox] 17:07:05 INFO - mkdir: /builds/slave/test/build/application 17:07:05 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'] 17:07:05 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 17:07:25 INFO - Reading from file tmpfile_stdout 17:07:25 INFO - Output received: 17:07:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:07:25 INFO - Running post-action listener: _resource_record_post_action 17:07:26 INFO - [mozharness: 2017-04-25 00:07:26.000116Z] Finished install step (success) 17:07:26 INFO - [mozharness: 2017-04-25 00:07:26.000275Z] Running run-tests step. 17:07:26 INFO - Running pre-action listener: _resource_record_pre_action 17:07:26 INFO - Running main action method: run_tests 17:07:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:07:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:07:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:07:26 INFO - 2017-04-24 17:07:26.015 screenresolution[1629:7444] starting screenresolution argv=screenresolution get 17:07:26 INFO - 2017-04-24 17:07:26.017 screenresolution[1629:7444] Display 0: 1600x1200x32@60 17:07:26 INFO - 2017-04-24 17:07:26.023 screenresolution[1630:7446] starting screenresolution argv=screenresolution list 17:07:26 INFO - Available Modes on Display 0 17:07:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:07:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:07:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:07:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:07:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:07:26 INFO - Intel Iris: 17:07:26 INFO - Chipset Model: Intel Iris 17:07:26 INFO - Type: GPU 17:07:26 INFO - Bus: Built-In 17:07:26 INFO - VRAM (Dynamic, Max): 1536 MB 17:07:26 INFO - Vendor: Intel (0x8086) 17:07:26 INFO - Device ID: 0x0a2e 17:07:26 INFO - Revision ID: 0x0009 17:07:26 INFO - Displays: 17:07:26 INFO - Display: 17:07:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:07:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:07:26 INFO - Display Serial Number: 1600x1200 60 17:07:26 INFO - Main Display: Yes 17:07:26 INFO - Mirror: Off 17:07:26 INFO - Online: Yes 17:07:26 INFO - Rotation: Supported 17:07:26 INFO - Return code: 0 17:07:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:07:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:07:26 INFO - Python 2.7.3 17:07:26 INFO - Return code: 0 17:07:26 INFO - grabbing minidump binary from tooltool 17:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:07:26 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d481d30>, '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 0x10d3a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d476be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:07:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:07:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:07:26 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:07:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:07:26 INFO - Return code: 0 17:07:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:07:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:07:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:07:26 INFO - ENV: RUST_BACKTRACE is now 1 17:07:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:07:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:07:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 17:07:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --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 17:07:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Kyj7i8Tfku/Render', 17:07:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:07:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:07:26 INFO - 'HOME': '/Users/cltbld', 17:07:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:07:26 INFO - 'LOGNAME': 'cltbld', 17:07:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:07:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:26 INFO - 'MOZ_NO_REMOTE': '1', 17:07:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:07:26 INFO - 'NO_EM_RESTART': '1', 17:07:26 INFO - 'PAGER': '/bin/cat', 17:07:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:26 INFO - 'PWD': '/builds/slave/test', 17:07:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:07:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:07:26 INFO - 'RUST_BACKTRACE': '1', 17:07:26 INFO - 'SHELL': '/bin/bash', 17:07:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O9s64EIDj3/Listeners', 17:07:26 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 17:07:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:07:26 INFO - 'USER': 'cltbld', 17:07:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:07:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:26 INFO - 'XPC_FLAGS': '0x0', 17:07:26 INFO - 'XPC_SERVICE_NAME': '0', 17:07:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:07:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 17:07:26 INFO - mozversion application_buildid: 20170424155425 17:07:26 INFO - mozversion application_changeset: abdcc8dfc28397b95338245390e12c56658ad182 17:07:26 INFO - mozversion application_display_name: Nightly 17:07:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:07:26 INFO - mozversion application_name: Firefox 17:07:26 INFO - mozversion application_remotingname: firefox 17:07:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:07:26 INFO - mozversion application_vendor: Mozilla 17:07:26 INFO - mozversion application_version: 55.0a1 17:07:26 INFO - mozversion platform_buildid: 20170424155425 17:07:26 INFO - mozversion platform_changeset: abdcc8dfc28397b95338245390e12c56658ad182 17:07:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:07:26 INFO - mozversion platform_version: 55.0a1 17:07:26 INFO - using testdate: 1493078846 17:07:26 INFO - actual date: 1493078846 17:07:26 INFO - starting webserver on 'localhost:49242' 17:07:26 INFO - SUITE-START | Running 2 tests 17:07:26 INFO - TEST-START | dromaeo_css 17:07:26 INFO - operating with platform_type : mac_ 17:07:26 INFO - Initialising browser for dromaeo_css test... 17:07:26 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmptWJwIa/profile http://localhost:49242/getInfo.html) 17:07:29 INFO - PID 1640 | 2017-04-24 17:07:29.581 plugin-container[1642:7621] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b4b, name = 'com.apple.tsm.portname' 17:07:29 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:07:29 INFO - PID 1640 | 2017-04-24 17:07:29.584 plugin-container[1642:7621] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 17:07:29 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:07:29 INFO - PID 1640 | 2017-04-24 17:07:29.584 plugin-container[1642:7621] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:07:29 INFO - PID 1640 | __metrics Screen width/height:1600/1200 17:07:29 INFO - PID 1640 | colorDepth:24 17:07:29 INFO - PID 1640 | Browser inner width/height: 1024/689 17:07:29 INFO - PID 1640 | __metrics 17:07:29 ERROR - PID 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 17:07:30 INFO - TEST-INFO | 1640: exit 0 17:07:30 INFO - Browser initialized. 17:07:30 INFO - Running cycle 1/1 for dromaeo_css test... 17:07:30 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmptWJwIa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:07:32 INFO - PID 1643 | 2017-04-24 17:07:32.899 plugin-container[1644:7759] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 17:07:32 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:07:32 INFO - PID 1643 | 2017-04-24 17:07:32.900 plugin-container[1644:7759] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 17:07:32 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:07:32 INFO - PID 1643 | 2017-04-24 17:07:32.900 plugin-container[1644:7759] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:07:42 INFO - PID 1643 | RSS: Main: 235880448 17:07:42 INFO - PID 1643 | 17:10:46 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 17:10:48 INFO - PID 1643 | RSS: Main: 257069056 17:10:48 INFO - PID 1643 | 17:12:31 INFO - PID 1643 | ************************* 17:12:31 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 17:12:31 INFO - PID 1643 | 17:12:31 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:12:31 INFO - PID 1643 | Full stack: 17:12:31 INFO - PID 1643 | ************************* 17:12:31 INFO - PID 1643 | ************************* 17:12:31 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 17:12:31 INFO - PID 1643 | 17:12:31 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:12:31 INFO - PID 1643 | Full stack: 17:12:31 INFO - PID 1643 | ************************* 17:12:31 INFO - PID 1643 | ************************* 17:12:31 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 17:12:31 INFO - PID 1643 | 17:12:31 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:12:31 INFO - PID 1643 | Full stack: 17:12:31 INFO - PID 1643 | ************************* 17:12:31 INFO - PID 1643 | ************************* 17:12:31 INFO - PID 1643 | A coding exception was thrown and uncaught in a Task. 17:12:31 INFO - PID 1643 | 17:12:31 ERROR - PID 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 17:12:31 INFO - PID 1643 | Full stack: 17:12:31 INFO - PID 1643 | ************************* 17:13:50 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 17:13:51 INFO - PID 1643 | RSS: Main: 259792896 17:13:51 INFO - PID 1643 | 17:16:52 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 17:16:53 INFO - PID 1643 | RSS: Main: 269512704 17:16:53 INFO - PID 1643 | 17:19:55 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 17:19:55 INFO - PID 1643 | RSS: Main: 261914624 17:19:55 INFO - PID 1643 | 17:22:58 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 17:22:59 INFO - PID 1643 | RSS: Main: 271024128 17:22:59 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 17:26:01 INFO - PID 1643 | RSS: Main: 266313728 17:26:01 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | __start_tp_report 17:26:01 INFO - PID 1643 | _x_x_mozilla_page_load 17:26:01 INFO - PID 1643 | _x_x_mozilla_page_load_details 17:26:01 INFO - PID 1643 | |i|pagename|runs| 17:26:01 INFO - PID 1643 | |0;dojo.html;3005.95491067634;3611.999999999998;3627.1113577173587;3639.8908032759027;3649.069487280744;6116.663583502906;6183.6598987055695;6218.626882387054;6224.15973843531;6260.624362538251;4685.344051832738;6180.536459765524;6198.566100372976;6259.593126446778;6294.811155665326;4037.454943582623;5394.7842086316505;5428.728563571815;5476.069068258385;5574.968630803314;5409.56723462122;5465.316835395575;5553.416891226424;5564.944350556483;5581.2186293918885;5362.2224777407055;5456.91814622781;5503.834884953418;5521.613487055908;5537.224788529609;5438.619296649236;5463.9180412293845;5478.643888147258;5497.8075767348355;5534.501894829485;5368.516833484966;5399.83800485986;5419.593530485229;5420.91868621971;5471.972640136773;5018.996200759862;6059.575829691923;6113.081058499965;6123.343628574293;6125.509959203253;6642.833929151761;6699.196096468406;6713.630750308731;6714.194296684429;6719.832004199885;6785.321467853225;6800.523963322569;6801.489888258351;6825.146856642919;6842.726290948356;6118.4364438206485;6135.906433916183;6285;6314.55798094129;6345.8730825384155;6755.864882702318;6822.9317706823285;6839.62382068991;6844.623545705034;6891.931080689229;6298.464630506458;6415.67921603918;6502.60984340941;6513;6581.80254592363;6684.498662600325;6733;6764.864702705919;6780.457563395015;6792.252852186256;6346.333634968354;6463.64449955257;6628.5360024798765;6650.334966503311;6662.967240077795;6421.454176395058;6592.934070659232;6642.767503137464;6676.031975363545;6694.698738556776;5282.498162674539;5285.180796976475;5316.016536940679;5318.069337865858;5346.4386239445075;4640.883977900579;4710.105080034851;4731.976340118346;4754.4532378776885;4758.2386818108935;4966.528179822911;6108.251008765781;6207;6309.116723658692;6320.399562041599;6759.9522074078595;6785.626790526469;6803.319668033256;6807.829804254834;6816.727330906808;6276.529260305495;6304.810855674337;6341.8097457076365;6354.237491500957;6379.213820130371;4589.380433641482;5708.029634962067;5740.024195886709;5792.073268277056;5793.797217097382;5228.895422091461;5789.565782566408;6899.655017249117;6924.376806087577;6998.545094568837;5094.439611642791;6302.613425046666;6452.516061295515;6601.108850305147;6640.269570347355;6689.765858195135;6758.357955994173;6871.622060786704;6872.828179295558;6882.105326307548;5576.749046293007;6482.708278127401;6611.603303801788;6626.436752875867;6675.866482670416;6535.150430444012;6742.4943129266485;6765.1205343305055;6785.321467853175;6791.185057793096;6354.682265886595;6445.871082578416;6458.67706614658;6508.121403610452;6541.280459149585;6449.226092868886;6503.284638689645;6586.275509693834;6602.141721576164;6603.372679595431;5256.868578285574;6136.478399336106;6279.937200627935;6387.648679322682;6457.966725323926;6539.673016349258;6584.572002819801;6591.406773390418;6601.273859875506;6605.867882642417;6395.456386207225;6411.935880641134;6413.172245909894;6433.292337842927;6441.838954026 17:26:01 INFO - PID 1643 | |1;ext.html;798.8801679748037;1226.3990644584146;1237.461704158691;1240.4727990603997;1259.4395494005166;37495.625043749555;38275.42586861196;38297.042573935665;38302.80848595757;38369;37821.05447363817;38065.61934380655;38134.809325953334;38236.617633823655;38300.80849595755;8325.791855203608;8531.189536993976;8534.061253262136;8541.316694664443;8594.914050859474;8538.957305213466;8612.1387861214;8635.222829945304;8695.087015863339;8723.520206388646;8583.828323433527;8622.094680058597;8649.740507784745;8691.348148888826;8704.04255531891;8641.265492433151;8659.307255419551;8660.913390866073;8698.173673501007;8703.52130632815;8372.790680232982;8392.24469797715;8452.957735211347;8456.704015359495;8488.066312705598;37427.31577079038;37578.62421375779;37609.81195094034;37610.43584346237;37664.81167594145;3663.523741913561;3671.7429779915415;3692.981535092334;3724.802366603717;3725.2177042820767;30384.696153038407;30636.54045189324;30680.846595766878;30725.846370768228;30760.230994225323;2835.6814081452226;3004.2639553309577;3170.984145079283;3182.7612929030415;3191.7925334853394;233534.83232583897;238241.61758382368;279517;281026;281807;38823.41764873529;47399.28901066487;48018.75990620095;48116.759416202694;48257.75871120622;48717;48902.51097489051;48912.26631600529;48963.26555101676;49062.01875962461;12443.377831108408;13579.506254312215;13893.930530347283;13900.582982510541;14067.71864562703;11278.266912650815;11329.60346387889;11452.94273528627;11464.082873370111;11492.195546311845;11011.779764404828;13060.673483162807;13214.933925330311;13345.866541334462;13373.66565835862;5921.437463441052;5926.140709597084;5943.732532036069;5953.255843019622;5955.910661340084;856.5203486047833;1016.4714348538719;1022.1873610479687;1023.5240613114937;1060.4697651174413;20408;22013;23151.073957041528;23970.52058958811;24000.87999560026;1096.276457538037;1104.9502772375101;1110.639042311252;1124.6907100547414;1154.9133814963911;12117.454714537867;12146.45340959659;12210.26738395699;12223.816642750366;12288.139830211978;321398;323580;323787;323996;324100;53534.19698704522;53863;53971;54071.18893216605;54085.72957135268;1472.5214305350803;1480.4374337751585;1508.0122519749582;1518.916459594733;1540.5455390659781;18349.724754128965;19613.70579441338;22737.545249095256;23076.384653074125;23570.93930773085;37773.62226377701;37929.81035094807;37935.05162370963;38045.4293185608;38130;281057;284523;284643;285000;286796;34039.48940765841;34185.316293674485;34306.65693343035;34315;34606.480902786985;43982.340264896695;44277.11445771131;44558.55441445674;44781.77609112064;44966.550334496234;30608;30615.54076688896;31109.377812444076;31448;31590.684093158776;21071.473213169793;29829.55255671123;30162.396752065273;30911;30922.917697880464;36752.713655021944;37321.81339093287;37376.43935340918;37387.43918841165;37432.877013689635;29233.246005239755;29837.850810745807;30499.847500762353;30529;30862.69137308688;19033.90483047576;20222.898885505478;20639.587208256053;23848.523029539658;24064.398390040387 17:26:01 INFO - PID 1643 | |2;jquery.html;369.366536390091;411.7241448229686;414.76565740356716;423.7266962808988;436.2627160099432;20325.390238292857;21115.788842111575;21716.3485095447;21842;21981.780182198192;21202.681959770576;21587.136514539437;21627;21794.80128592927;21808;5866.413358664131;6121.112438696387;6124.663143527104;6135.3864613538735;6143.7235324410285;5241.68549887008;5887.293524777033;6014;6149.16986206863;6180.876382472348;5629.9437005629825;6062.18160548327;6137.324894261627;6153.077038444247;6160.691965401735;5979.910301345483;5989.251343582052;6099.725512351911;6122.785702500436;6126.632402055869;5307.575393968474;5392.164214546751;5428.782848686048;5462.8361149165585;5464;23523.76476235233;27520.58719119215;28142;28312.009079682113;28547.57178642322;2171.9782802172094;2246.629306164481;2253.0537174386795;2287.9542409151722;2291.6906217660735;14916.552503424915;17862.19620117098;17962.19170137347;18222.453326400228;18456.35402760897;2076;2141.2184552638173;2164.6644770060666;2168.8048075673264;2169.4793249620134;159776.20111899482;161314;168253;171297;174730;12199.634010979686;12254.754904901853;12387;12392.256464612152;12647.683807904876;15819.604509887346;15970.9201453992;16061.036337819769;16143.192840357935;16179.271932763055;8666.306695464347;8702.912970870337;8851.33614978879;8863.335249856287;9011.098890111067;6902.275261097613;8356.33149348063;8381.371397145238;8511.787205319793;8562.143785621389;6998.580085194802;7551.395888328921;7563.924360756432;7799.844003120019;7865.724699635485;2058.835293176542;2098.6537221358303;2106.2101711858054;2115.936521904345;2290.690756747851;834.4242472693943;937.864009718587;960.5533426956359;975.7024107647094;1007.4912169354576;16182.43361482366;16336.918315408348;16521.752173717403;18853.811461885205;20111.59776804485;978.2907392140642;990.5443495991924;1003.4330603209163;1008.7931973945324;1013.5641674080216;8991.370604057784;9760.755981100387;10827.621033263955;10869.782604347869;10870;445632;523408;524443;536269;536916;130382;132040.33979830038;132677;133124.6687533112;133956.9906451404;1145.7765735681628;1151.0791366906442;1173.9589114380956;1206.1113632849451;1228.4901765767286;20757.896210519455;21141.26005589797;21794.782052179275;21801;21883.577567458065;48963.020739584295;50685.49314506955;58985.41014589799;59184.112238315596;59386.70306648439;56644.43355566391;75979.24020759722;76866.61566692131;77419.61290193514;78247.60876195582;7414.22150674193;7662.578558179353;8589.184027517376;8653;8867.157620026088;13783.586492405244;13802.723945521235;13933.930330348283;14173.433062677377;14291.142531448146;11604.477798499256;11655.41722913834;11888.881111189123;12005.699857503283;12119.636410907688;9210.585523651318;10705.785884282426;11147.386893720923;11258.887411126112;11289.491972861077;11556.94221528887;11849.348285844362;11969.940150299193;12082.637520874388;12150;7334.449916256409;7619.352355049883;8414.789630259047;8466.57667116654;8608.612612432331;11625.24435911663;11745.530178792753;11908.166428349932;11947.82078268844;12086 17:26:01 INFO - PID 1643 | |3;mootools.html;1415.5611760354288;1440.5062039646054;1459.46729443753;1485.6657252118275;1491.724031054255;11346.43267836608;11640.883591164085;11888.405579721011;11896.46465909034;12052.517899284017;11800.822987655192;11868.881311186886;11951.223170493911;11969.40152992349;12047.819282710767;2477.6159695247266;2525.7600527949817;2527.279725278296;2530.5318516074494;2531.1014589820625;2468.8642124683133;2509.2597683683357;2509.9749002510016;2515.2328539795353;2525.6211568264725;2453.852768833876;2476.427468557461;2512.1207577347964;2515.245426372081;2524.00301880757;2438.4635380216414;2481.6525686403916;2490.7882829959476;2491.3397949543387;2493.339265094751;2439.121916110199;2459.114718701266;2467.4448249143798;2481.838680485763;2493.164789795421;10260.486975651262;10948.069414099811;11536.884631153665;11739.765204695857;11847.763044739057;6338.651374174418;6479.2224933007865;6573.1783527059115;6576.375244351779;6590.901136482957;3827.158025234444;4108.301588729923;4148.170365926797;4204.075103477261;4222.915541689149;1224.1736827641396;1239.4856134704087;1282.1217466035796;1286.6526037969695;1289.8387701537308;15841.287142078634;16215.756763648555;16245.83754162455;16288.592785180464;16430;5295.814646487354;5400.810971615974;5426.213199086111;5537.7784888604;5543.586385471679;9281.350305478687;9857.950710246403;12050.578229762092;12224.938875305745;12301.32342721159;6341.841453963688;6396.840078998062;6411.198600174978;6417.390347917034;6422.967885160544;5850;5948.226730525004;5963.389884731098;6011.458968692751;6099.054646529795;4164.416981622575;4273.91452170961;4293.484781826201;4320.4599425071865;4344.956550434518;1068.8984546468228;1201.6481458359347;1211.2006075989805;1235.2403271987662;1250.3998080921208;663.2737152817657;797.6928882380209;815.6370415165313;815.8613035783931;822.761399194239;6619.602823830585;6830.521863469608;6946.0622815920215;6983.8603227935155;6992.930070699228;927.0544045073987;949.7578117579973;954.9092836180552;955.3933252384786;966.1884017425256;4070.9796451017555;4362.585554372329;4363.47638283408;4459.732416055047;4476.798544065459;14291.785623215661;14976.400943962119;15035.248237588077;15061.924690376478;15141.772873406688;14264.500742473963;14744.262786860614;14876.776848347283;14921.328540215716;14924.104553726811;7407.444441666897;8299.917000830035;8490.278326342206;8532.146785321542;8572.61423235956;6104.17593624855;6606.504512161703;6821.590704557742;6974.895376569254;7030.086088808423;12057.216280941711;12402;12567;12722.809157862454;12793.104482686123;11629.592964246549;12657.873421266038;12778.297193654438;12913.548025819051;13143.605691828878;8748;9547.904520954702;9582.616695332108;9607.663731769355;9639.759006024626;8153.1846815317995;8465.83068338642;10349.793004140025;10452.372857628567;10704.625338113128;9024.323175761976;9035.186833184782;9060.139286767746;9130.360874738704;9194.35639505255;8785.824283514421;8894.4663320201;8914.242289405473;9091;9169.35814492979;8106.959465202636;8191.13993030747;8238.588070596326;8260.669573217005;8287.875681864656;5872.823815285548;5888.528917686661;5939.970300148471;5948.405159484017;5996.520278377632;5309.522143006993;5785.756062446594;6066.575339726177;6179.3820617937845;6304.621722696653 17:26:01 INFO - PID 1643 | |4;prototype.html;384.68648051837766;388.5318191579146;390.4280229463836;395.6182284095848;397.19766072533486;4766.475687674353;4782.7608619569055;5451.182322651604;6171.691415429227;6184.845378865531;5186.858891043964;5380.816220431509;5772.797952071678;6035.305939816923;6069.271687397508;3965.702572307074;4570.97714511427;4584.037352156043;4585.9541404586025;4586.54134586542;4518.2319005769095;4585.9541404586025;4588.564086411786;4592.609628181609;4617.930731039037;4375.4093197418415;4550.317452382136;4562;4580.58774710276;4587.541245875419;3888.4361767543687;4296.978515107436;4525.7554172602795;4562.350119904064;4568.360429539867;4008.6793056555407;4025.4364388985564;4028.9194216115807;4037.576054514263;4053.5135783706146;5673.8297851064535;5930;5933.22868027158;6005.159277701126;6020.397960203988;5274.604404669665;5318.973405132988;5374.3013408256675;5378.1663842104535;5397.244385785953;6565.671716414208;6595.241547222083;6605.339466053404;6608.339166083401;6618.602883826986;3548.946765798515;3733.9253214935547;4181.581841815824;4193.391958166048;4399.142167277382;6213.794103093427;6376.489880809524;6706.597604143767;6974.814281572145;6983.162020557515;4402.085092984562;4425.424694789656;4445.266346125014;4461.955380446219;4472.843450479218;3823.617638236154;4515.187266292099;4534.342520334533;4535.095259990811;4535.569120933506;4206.558311377323;5104.626348747775;5157.839486115594;5295.17316525798;5375.763574377915;6550.967245163743;6556.7705130321165;6571.934280657228;6577.375149360803;6578;1370.5888233529902;1452.128722766353;1520.657851983317;1541.7764424158433;1555.3234343060806;1009.9040591143829;1021.2085633633993;1106.590561492253;1163.313644949484;1179.8761130081389;5368.302120724281;6188.824123416536;6212.875742485125;6226.820612785489;6228.5017198624;5584.984108581401;6035.486983606443;6454.257760357619;6820.35206655367;6835.350641689031;5103.923441148386;5924.496417804535;6333.20834895638;6359.841003974845;6364.140840986408;2753.518134326485;3293.847153496305;3531.52324436203;3538.663826936437;3569.39320315547;6138.447539721446;6314.285571557748;6695.598264104169;6888.138982627172;7064.682089305994;5220.885541355757;6664.1336626239245;6989.475789315821;7003.404710599656;7003.809352410102;5147.794088236504;5538.695371754511;6359.586626869239;6563.803085907335;7039.19049309336;5912.349641539513;6805.761798337035;6840.41856442208;6849.1096157499205;6850.246472888078;5528.889422211614;5933.970330148321;6372.299047104908;6737.292584278776;6770.255271920183;7064.717411303491;7106.822329441598;7144.3927266182955;7152.320529549685;7175.31834475724;4458.7547684877645;4460.8661740147845;4482.79827407774;4492.752898590609;4498.707584007029;4868.926966095441;5414.648047876875;6810.216825065181;6899.412720229914;6950.582965022115;5439.5648348132845;6265.749370025148;6578.8355291118105;6594.637294948824;6611.33886611335;5162.561182299547;6345.650989195638;6374;6476.4495017924;6621.768238111643;6120.7551697931585;6200.410960958701;6409.647469389229;6450.4841362278885;6466.612003279818;4979.858660775756;5210.53105220532;5970.402959703995;6504.38208370223;6519.674016299261;6371.553991220755;6419.325970773001;6429.292777794532;6455.193100862392;6504.284528701934 17:26:01 INFO - PID 1643 | |5;yui.html;834.2825170353499;839.8026463781013;840.6595328891797;860.8077812230064;867.5488745852155;16459.506214813573;16579.58551036223;16923.223061578527;18503.352382666577;19081.427557173272;17606;17970.01164935928;18159.818401815977;18206.81793182068;18478.445646630622;967.6564819489085;989.1098011789411;990.6334656177224;990.8464188050864;991.3110388280148;969.3021024862088;984.2322988069318;986.9555869985868;987.5407935310079;988.0317289056728;1038.9921775877388;1043.5564884923915;1046.2101113659176;1046.502911117221;1046.7854089911589;1024.7079582319004;1035.715178325959;1036.0985942230232;1038.9168866490663;1041.7656027393814;717.5945590741214;739.3345988610293;750.3846845586621;751.1737089201889;753.6909866954577;16537.669246615;16583.336666533323;16631.750523742154;16687;16757.83242167587;13764.862351376458;16813.075280859546;16908;17268.740968885475;17379.913100434416;12509.74980500394;12791.168574042658;12811.103222774316;12820.935895320557;12828.358582070952;3475.1138459692615;3583.5341405617364;3625.329314076878;3631.1285291530107;3644.2711457708565;87920.12079879183;90008;113877;114513;117589;13029.56674765794;13401;13676.863231367757;13721.313934303445;13758.65603359924;20701.58596828085;25002;25545;25962.87018564895;26044.869775651;7752.0697516298405;7805.960970195113;7892.329152022027;7949.880751788728;7969.521828690297;3298.6371499135075;3440.7785236241275;3451.7238620910266;3894.1043559981363;3896.941545876814;4280.571942805695;4342.82628694855;4360.825566977349;4369.6722745794195;4401.097774956126;701.3057073497303;789.6999140326639;808.5633757770738;814.3241109878786;820.9917900821035;614.0666187395134;628.6510986402591;668.762589280808;673.3771261583016;694.9687264073128;10876.728081798019;11906.285622862482;11918.761624767456;11938.820917686242;12037.62192058811;633.762339122829;642.7975187815916;645.9483241340776;649.1787888321277;660.8711301296297;5537.1140617501005;5975.40245975399;6029.216201893726;6030.125631783367;6083.908741368883;111412.4429377848;114258.42870785759;114571.42714286376;116174.41912790382;116272;73643.26356736472;98361;98569;101140.49429752804;102900;87016.25967480741;87174;87257;87837;89224.38573249655;9668.564914579001;12237.877621223673;12521.56174533887;12531.686707832378;12544.24734515932;25394;33911.15222119368;38670.6132938667;40458.595414045485;42774;51146;56274.718626406604;56457;56520;56986.29041128773;14466.20435876037;15541.222938853238;16408.835911641207;16730.4144354947;16769.748453772958;14461.204633745245;18206.75894749969;18267.177976991334;18283.81716182821;18478.983655899054;13898.652533686738;16695.998240105633;16897.239624216694;16953.491395258163;17084.48746537606;14096.718065638835;16591.419300324913;16735.58161045935;16872.409465669134;16898.23957921872;16191.733157358214;16310.347586096423;16486.917565412095;16646.667066658356;16936.915315423837;9608.423494590346;10186.490675466346;11127.887211278807;11660.30038197711;12042.939785301018;12914.612561623166;12915.289659068465;12918.289494077915;12984.805227921777;13034.47862085506 17:26:01 INFO - PID 1643 | __end_tp_report 17:26:01 INFO - PID 1643 | __start_cc_report 17:26:01 INFO - PID 1643 | _x_x_mozilla_cycle_collect,421 17:26:01 INFO - PID 1643 | __end_cc_report 17:26:01 INFO - PID 1643 | __startTimestamp1493079961504__endTimestamp 17:26:01 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | ------- Summary: start ------- 17:26:01 INFO - PID 1643 | Number of tests: 6 17:26:01 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6086.08 Median:6346.33 stddev:772.42 (12.2%) stddev-sans-first:736.03 17:26:01 INFO - PID 1643 | Values: 3006.0 3612.0 3627.1 3639.9 3649.1 6116.7 6183.7 6218.6 6224.2 6260.6 4685.3 6180.5 6198.6 6259.6 6294.8 4037.5 5394.8 5428.7 5476.1 5575.0 5409.6 5465.3 5553.4 5564.9 5581.2 5362.2 5456.9 5503.8 5521.6 5537.2 5438.6 5463.9 5478.6 5497.8 5534.5 5368.5 5399.8 5419.6 5420.9 5472.0 5019.0 6059.6 6113.1 6123.3 6125.5 6642.8 6699.2 6713.6 6714.2 6719.8 6785.3 6800.5 6801.5 6825.1 6842.7 6118.4 6135.9 6285.0 6314.6 6345.9 6755.9 6822.9 6839.6 6844.6 6891.9 6298.5 6415.7 6502.6 6513.0 6581.8 6684.5 6733.0 6764.9 6780.5 6792.3 6346.3 6463.6 6628.5 6650.3 6663.0 6421.5 6592.9 6642.8 6676.0 6694.7 5282.5 5285.2 5316.0 5318.1 5346.4 4640.9 4710.1 4732.0 4754.5 4758.2 4966.5 6108.3 6207.0 6309.1 6320.4 6760.0 6785.6 6803.3 6807.8 6816.7 6276.5 6304.8 6341.8 6354.2 6379.2 4589.4 5708.0 5740.0 5792.1 5793.8 5228.9 5789.6 6899.7 6924.4 6998.5 5094.4 6302.6 6452.5 6601.1 6640.3 6689.8 6758.4 6871.6 6872.8 6882.1 5576.7 6482.7 6611.6 6626.4 6675.9 6535.2 6742.5 6765.1 6785.3 6791.2 6354.7 6445.9 6458.7 6508.1 6541.3 6449.2 6503.3 6586.3 6602.1 6603.4 5256.9 6136.5 6279.9 6387.6 6458.0 6539.7 6584.6 6591.4 6601.3 6605.9 6395.5 6411.9 6413.2 6433.3 6441.8 17:26:01 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:43999.68 Median:323891.50 stddev:76449.44 (23.6%) stddev-sans-first:76595.09 17:26:01 INFO - PID 1643 | Values: 798.9 1226.4 1237.5 1240.5 1259.4 37495.6 38275.4 38297.0 38302.8 38369.0 37821.1 38065.6 38134.8 38236.6 38300.8 8325.8 8531.2 8534.1 8541.3 8594.9 8539.0 8612.1 8635.2 8695.1 8723.5 8583.8 8622.1 8649.7 8691.3 8704.0 8641.3 8659.3 8660.9 8698.2 8703.5 8372.8 8392.2 8453.0 8456.7 8488.1 37427.3 37578.6 37609.8 37610.4 37664.8 3663.5 3671.7 3693.0 3724.8 3725.2 30384.7 30636.5 30680.8 30725.8 30760.2 2835.7 3004.3 3171.0 3182.8 3191.8 233534.8 238241.6 279517.0 281026.0 281807.0 38823.4 47399.3 48018.8 48116.8 48257.8 48717.0 48902.5 48912.3 48963.3 49062.0 12443.4 13579.5 13893.9 13900.6 14067.7 11278.3 11329.6 11452.9 11464.1 11492.2 11011.8 13060.7 13214.9 13345.9 13373.7 5921.4 5926.1 5943.7 5953.3 5955.9 856.5 1016.5 1022.2 1023.5 1060.5 20408.0 22013.0 23151.1 23970.5 24000.9 1096.3 1105.0 1110.6 1124.7 1154.9 12117.5 12146.5 12210.3 12223.8 12288.1 321398.0 323580.0 323787.0 323996.0 324100.0 53534.2 53863.0 53971.0 54071.2 54085.7 1472.5 1480.4 1508.0 1518.9 1540.5 18349.7 19613.7 22737.5 23076.4 23570.9 37773.6 37929.8 37935.1 38045.4 38130.0 281057.0 284523.0 284643.0 285000.0 286796.0 34039.5 34185.3 34306.7 34315.0 34606.5 43982.3 44277.1 44558.6 44781.8 44966.6 30608.0 30615.5 31109.4 31448.0 31590.7 21071.5 29829.6 30162.4 30911.0 30922.9 36752.7 37321.8 37376.4 37387.4 37432.9 29233.2 29837.9 30499.8 30529.0 30862.7 19033.9 20222.9 20639.6 23848.5 24064.4 17:26:01 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:34652.85 Median:12114.20 stddev:88433.35 (730.0%) stddev-sans-first:88643.97 17:26:01 INFO - PID 1643 | Values: 369.4 411.7 414.8 423.7 436.3 20325.4 21115.8 21716.3 21842.0 21981.8 21202.7 21587.1 21627.0 21794.8 21808.0 5866.4 6121.1 6124.7 6135.4 6143.7 5241.7 5887.3 6014.0 6149.2 6180.9 5629.9 6062.2 6137.3 6153.1 6160.7 5979.9 5989.3 6099.7 6122.8 6126.6 5307.6 5392.2 5428.8 5462.8 5464.0 23523.8 27520.6 28142.0 28312.0 28547.6 2172.0 2246.6 2253.1 2288.0 2291.7 14916.6 17862.2 17962.2 18222.5 18456.4 2076.0 2141.2 2164.7 2168.8 2169.5 159776.2 161314.0 168253.0 171297.0 174730.0 12199.6 12254.8 12387.0 12392.3 12647.7 15819.6 15970.9 16061.0 16143.2 16179.3 8666.3 8702.9 8851.3 8863.3 9011.1 6902.3 8356.3 8381.4 8511.8 8562.1 6998.6 7551.4 7563.9 7799.8 7865.7 2058.8 2098.7 2106.2 2115.9 2290.7 834.4 937.9 960.6 975.7 1007.5 16182.4 16336.9 16521.8 18853.8 20111.6 978.3 990.5 1003.4 1008.8 1013.6 8991.4 9760.8 10827.6 10869.8 10870.0 445632.0 523408.0 524443.0 536269.0 536916.0 130382.0 132040.3 132677.0 133124.7 133957.0 1145.8 1151.1 1174.0 1206.1 1228.5 20757.9 21141.3 21794.8 21801.0 21883.6 48963.0 50685.5 58985.4 59184.1 59386.7 56644.4 75979.2 76866.6 77419.6 78247.6 7414.2 7662.6 8589.2 8653.0 8867.2 13783.6 13802.7 13933.9 14173.4 14291.1 11604.5 11655.4 11888.9 12005.7 12119.6 9210.6 10705.8 11147.4 11258.9 11289.5 11556.9 11849.3 11969.9 12082.6 12150.0 7334.4 7619.4 8414.8 8466.6 8608.6 11625.2 11745.5 11908.2 11947.8 12086.0 17:26:01 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:6980.19 Median:4184.25 stddev:4369.43 (104.4%) stddev-sans-first:4361.68 17:26:01 INFO - PID 1643 | Values: 1415.6 1440.5 1459.5 1485.7 1491.7 11346.4 11640.9 11888.4 11896.5 12052.5 11800.8 11868.9 11951.2 11969.4 12047.8 2477.6 2525.8 2527.3 2530.5 2531.1 2468.9 2509.3 2510.0 2515.2 2525.6 2453.9 2476.4 2512.1 2515.2 2524.0 2438.5 2481.7 2490.8 2491.3 2493.3 2439.1 2459.1 2467.4 2481.8 2493.2 10260.5 10948.1 11536.9 11739.8 11847.8 6338.7 6479.2 6573.2 6576.4 6590.9 3827.2 4108.3 4148.2 4204.1 4222.9 1224.2 1239.5 1282.1 1286.7 1289.8 15841.3 16215.8 16245.8 16288.6 16430.0 5295.8 5400.8 5426.2 5537.8 5543.6 9281.4 9858.0 12050.6 12224.9 12301.3 6341.8 6396.8 6411.2 6417.4 6423.0 5850.0 5948.2 5963.4 6011.5 6099.1 4164.4 4273.9 4293.5 4320.5 4345.0 1068.9 1201.6 1211.2 1235.2 1250.4 663.3 797.7 815.6 815.9 822.8 6619.6 6830.5 6946.1 6983.9 6992.9 927.1 949.8 954.9 955.4 966.2 4071.0 4362.6 4363.5 4459.7 4476.8 14291.8 14976.4 15035.2 15061.9 15141.8 14264.5 14744.3 14876.8 14921.3 14924.1 7407.4 8299.9 8490.3 8532.1 8572.6 6104.2 6606.5 6821.6 6974.9 7030.1 12057.2 12402.0 12567.0 12722.8 12793.1 11629.6 12657.9 12778.3 12913.5 13143.6 8748.0 9547.9 9582.6 9607.7 9639.8 8153.2 8465.8 10349.8 10452.4 10704.6 9024.3 9035.2 9060.1 9130.4 9194.4 8785.8 8894.5 8914.2 9091.0 9169.4 8107.0 8191.1 8238.6 8260.7 8287.9 5872.8 5888.5 5940.0 5948.4 5996.5 5309.5 5785.8 6066.6 6179.4 6304.6 17:26:01 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5220.50 Median:5533.79 stddev:1640.58 (29.6%) stddev-sans-first:1604.53 17:26:01 INFO - PID 1643 | Values: 384.7 388.5 390.4 395.6 397.2 4766.5 4782.8 5451.2 6171.7 6184.8 5186.9 5380.8 5772.8 6035.3 6069.3 3965.7 4571.0 4584.0 4586.0 4586.5 4518.2 4586.0 4588.6 4592.6 4617.9 4375.4 4550.3 4562.0 4580.6 4587.5 3888.4 4297.0 4525.8 4562.4 4568.4 4008.7 4025.4 4028.9 4037.6 4053.5 5673.8 5930.0 5933.2 6005.2 6020.4 5274.6 5319.0 5374.3 5378.2 5397.2 6565.7 6595.2 6605.3 6608.3 6618.6 3548.9 3733.9 4181.6 4193.4 4399.1 6213.8 6376.5 6706.6 6974.8 6983.2 4402.1 4425.4 4445.3 4462.0 4472.8 3823.6 4515.2 4534.3 4535.1 4535.6 4206.6 5104.6 5157.8 5295.2 5375.8 6551.0 6556.8 6571.9 6577.4 6578.0 1370.6 1452.1 1520.7 1541.8 1555.3 1009.9 1021.2 1106.6 1163.3 1179.9 5368.3 6188.8 6212.9 6226.8 6228.5 5585.0 6035.5 6454.3 6820.4 6835.4 5103.9 5924.5 6333.2 6359.8 6364.1 2753.5 3293.8 3531.5 3538.7 3569.4 6138.4 6314.3 6695.6 6888.1 7064.7 5220.9 6664.1 6989.5 7003.4 7003.8 5147.8 5538.7 6359.6 6563.8 7039.2 5912.3 6805.8 6840.4 6849.1 6850.2 5528.9 5934.0 6372.3 6737.3 6770.3 7064.7 7106.8 7144.4 7152.3 7175.3 4458.8 4460.9 4482.8 4492.8 4498.7 4868.9 5414.6 6810.2 6899.4 6950.6 5439.6 6265.7 6578.8 6594.6 6611.3 5162.6 6345.7 6374.0 6476.4 6621.8 6120.8 6200.4 6409.6 6450.5 6466.6 4979.9 5210.5 5970.4 6504.4 6519.7 6371.6 6419.3 6429.3 6455.2 6504.3 17:26:01 INFO - PID 1643 | 17:26:01 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:21567.07 Median:18491.17 stddev:30364.11 (164.2%) stddev-sans-first:30409.38 17:26:01 INFO - PID 1643 | Values: 834.3 839.8 840.7 860.8 867.5 16459.5 16579.6 16923.2 18503.4 19081.4 17606.0 17970.0 18159.8 18206.8 18478.4 967.7 989.1 990.6 990.8 991.3 969.3 984.2 987.0 987.5 988.0 1039.0 1043.6 1046.2 1046.5 1046.8 1024.7 1035.7 1036.1 1038.9 1041.8 717.6 739.3 750.4 751.2 753.7 16537.7 16583.3 16631.8 16687.0 16757.8 13764.9 16813.1 16908.0 17268.7 17379.9 12509.7 12791.2 12811.1 12820.9 12828.4 3475.1 3583.5 3625.3 3631.1 3644.3 87920.1 90008.0 113877.0 114513.0 117589.0 13029.6 13401.0 13676.9 13721.3 13758.7 20701.6 25002.0 25545.0 25962.9 26044.9 7752.1 7806.0 7892.3 7949.9 7969.5 3298.6 3440.8 3451.7 3894.1 3896.9 4280.6 4342.8 4360.8 4369.7 4401.1 701.3 789.7 808.6 814.3 821.0 614.1 628.7 668.8 673.4 695.0 10876.7 11906.3 11918.8 11938.8 12037.6 633.8 642.8 645.9 649.2 660.9 5537.1 5975.4 6029.2 6030.1 6083.9 111412.4 114258.4 114571.4 116174.4 116272.0 73643.3 98361.0 98569.0 101140.5 102900.0 87016.3 87174.0 87257.0 87837.0 89224.4 9668.6 12237.9 12521.6 12531.7 12544.2 25394.0 33911.2 38670.6 40458.6 42774.0 51146.0 56274.7 56457.0 56520.0 56986.3 14466.2 15541.2 16408.8 16730.4 16769.7 14461.2 18206.8 18267.2 18283.8 18479.0 13898.7 16696.0 16897.2 16953.5 17084.5 14096.7 16591.4 16735.6 16872.4 16898.2 16191.7 16310.3 16486.9 16646.7 16936.9 9608.4 10186.5 11127.9 11660.3 12042.9 12914.6 12915.3 12918.3 12984.8 13034.5 17:26:01 INFO - PID 1643 | -------- Summary: end -------- 17:26:01 INFO - PID 1643 | 17:26:02 INFO - TEST-INFO | 1643: exit 0 17:26:02 INFO - TEST-OK | dromaeo_css | took 1115634ms 17:26:02 INFO - TEST-START | kraken 17:26:02 INFO - operating with platform_type : mac_ 17:26:02 INFO - Initialising browser for kraken test... 17:26:02 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmp1rg8qA/profile http://localhost:49242/getInfo.html) 17:26:05 INFO - PID 1655 | 2017-04-24 17:26:05.045 plugin-container[1656:10219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 17:26:05 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:05 INFO - PID 1655 | 2017-04-24 17:26:05.049 plugin-container[1656:10219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x971b, name = 'com.apple.CFPasteboardClient' 17:26:05 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:05 INFO - PID 1655 | 2017-04-24 17:26:05.049 plugin-container[1656:10219] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:26:05 INFO - PID 1655 | __metrics Screen width/height:1600/1200 17:26:05 INFO - PID 1655 | colorDepth:24 17:26:05 INFO - PID 1655 | Browser inner width/height: 1024/689 17:26:05 INFO - PID 1655 | __metrics 17:26:05 ERROR - PID 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3403: TypeError: this.tabs is undefined 17:26:06 INFO - TEST-INFO | 1655: exit 0 17:26:06 INFO - Browser initialized. 17:26:06 INFO - Running cycle 1/1 for kraken test... 17:26:06 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmp1rg8qA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:08 INFO - PID 1657 | 2017-04-24 17:26:08.719 plugin-container[1658:10342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 17:26:08 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:08 INFO - PID 1657 | 2017-04-24 17:26:08.719 plugin-container[1658:10342] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 17:26:08 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:08 INFO - PID 1657 | 2017-04-24 17:26:08.719 plugin-container[1658:10342] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:26:18 INFO - PID 1657 | RSS: Main: 236969984 17:26:18 INFO - PID 1657 | 17:26:21 INFO - PID 1657 | 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) 17:26:22 INFO - PID 1657 | RSS: Main: 249270272 17:26:22 INFO - PID 1657 | 17:26:24 INFO - PID 1657 | 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) 17:26:25 INFO - PID 1657 | RSS: Main: 257466368 17:26:25 INFO - PID 1657 | 17:26:28 INFO - PID 1657 | 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) 17:26:29 INFO - PID 1657 | RSS: Main: 260055040 17:26:29 INFO - PID 1657 | 17:26:31 INFO - PID 1657 | 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) 17:26:32 INFO - PID 1657 | RSS: Main: 262045696 17:26:32 INFO - PID 1657 | 17:26:33 INFO - PID 1657 | 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) 17:26:34 INFO - PID 1657 | RSS: Main: 262578176 17:26:34 INFO - PID 1657 | 17:26:37 INFO - PID 1657 | 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) 17:26:38 INFO - PID 1657 | RSS: Main: 268193792 17:26:38 INFO - PID 1657 | 17:26:41 INFO - PID 1657 | 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) 17:26:41 INFO - PID 1657 | RSS: Main: 270249984 17:26:41 INFO - PID 1657 | 17:26:44 INFO - PID 1657 | 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) 17:26:45 INFO - PID 1657 | RSS: Main: 269664256 17:26:45 INFO - PID 1657 | 17:26:46 INFO - PID 1657 | 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) 17:26:47 INFO - PID 1657 | RSS: Main: 268984320 17:26:47 INFO - PID 1657 | 17:26:49 INFO - PID 1657 | 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) 17:26:50 INFO - PID 1657 | RSS: Main: 269053952 17:26:50 INFO - PID 1657 | 17:26:52 INFO - PID 1657 | 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) 17:26:52 INFO - PID 1657 | RSS: Main: 268746752 17:26:52 INFO - PID 1657 | 17:26:55 INFO - PID 1657 | 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) 17:26:56 INFO - PID 1657 | RSS: Main: 269197312 17:26:56 INFO - PID 1657 | 17:26:59 INFO - PID 1657 | 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) 17:27:00 INFO - PID 1657 | RSS: Main: 269361152 17:27:00 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | 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) 17:27:02 INFO - PID 1657 | RSS: Main: 271138816 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | __start_tp_report 17:27:02 INFO - PID 1657 | _x_x_mozilla_page_load 17:27:02 INFO - PID 1657 | _x_x_mozilla_page_load_details 17:27:02 INFO - PID 1657 | |i|pagename|runs| 17:27:02 INFO - PID 1657 | |0;ai-astar;101;117;115;112;114;138;167;147;114;116 17:27:02 INFO - PID 1657 | |1;audio-beat-detection;145;110;112;162;137;150;98;134;165;144 17:27:02 INFO - PID 1657 | |2;audio-dft;204;199;213;218;213;203;198;202;203;196 17:27:02 INFO - PID 1657 | |3;audio-fft;82;58;58;85;63;59;61;62;81;59 17:27:02 INFO - PID 1657 | |4;audio-oscillator;87;69;80;69;67;67;85;69;71;91 17:27:02 INFO - PID 1657 | |5;imaging-gaussian-blur;93;94;93;92;93;92;92;92;94;92 17:27:02 INFO - PID 1657 | |6;imaging-darkroom;99;99;98;98;99;98;98;105;92;160 17:27:02 INFO - PID 1657 | |7;imaging-desaturate;91;90;102;92;108;88;130;99;123;75 17:27:02 INFO - PID 1657 | |8;json-parse-financial;52;51;50;50;52;52;51;50;52;53 17:27:02 INFO - PID 1657 | |9;json-stringify-tinderbox;59;66;86;85;88;57;54;55;55;57 17:27:02 INFO - PID 1657 | |10;stanford-crypto-aes;85;82;85;81;85;83;81;86;80;81 17:27:02 INFO - PID 1657 | |11;stanford-crypto-ccm;136;157;157;128;156;153;168;156;206;140 17:27:02 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;187;187;181;187;184;183;188;185;229;236 17:27:02 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;70;71;63;98;95;64;62;89;74;62 17:27:02 INFO - PID 1657 | __end_tp_report 17:27:02 INFO - PID 1657 | __start_cc_report 17:27:02 INFO - PID 1657 | _x_x_mozilla_cycle_collect,1021 17:27:02 INFO - PID 1657 | __end_cc_report 17:27:02 INFO - PID 1657 | __startTimestamp1493080022513__endTimestamp 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | ------- Summary: start ------- 17:27:02 INFO - PID 1657 | Number of tests: 14 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:124.10 Median:116.50 stddev:20.11 (17.3%) stddev-sans-first:19.52 17:27:02 INFO - PID 1657 | Values: 101.0 117.0 115.0 112.0 114.0 138.0 167.0 147.0 114.0 116.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:147.50 stddev:22.50 (15.3%) stddev-sans-first:23.62 17:27:02 INFO - PID 1657 | Values: 145.0 110.0 112.0 162.0 137.0 150.0 98.0 134.0 165.0 144.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:204.90 Median:203.50 stddev:7.31 (3.6%) stddev-sans-first:7.75 17:27:02 INFO - PID 1657 | Values: 204.0 199.0 213.0 218.0 213.0 203.0 198.0 202.0 203.0 196.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:66.80 Median:62.50 stddev:11.11 (17.8%) stddev-sans-first:10.34 17:27:02 INFO - PID 1657 | Values: 82.0 58.0 58.0 85.0 63.0 59.0 61.0 62.0 81.0 59.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:75.50 stddev:9.28 (12.3%) stddev-sans-first:8.86 17:27:02 INFO - PID 1657 | Values: 87.0 69.0 80.0 69.0 67.0 67.0 85.0 69.0 71.0 91.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:92.70 Median:93.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 17:27:02 INFO - PID 1657 | Values: 93.0 94.0 93.0 92.0 93.0 92.0 92.0 92.0 94.0 92.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:104.60 Median:98.00 stddev:19.71 (20.1%) stddev-sans-first:20.80 17:27:02 INFO - PID 1657 | Values: 99.0 99.0 98.0 98.0 99.0 98.0 98.0 105.0 92.0 160.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:99.80 Median:89.00 stddev:16.71 (18.8%) stddev-sans-first:17.41 17:27:02 INFO - PID 1657 | Values: 91.0 90.0 102.0 92.0 108.0 88.0 130.0 99.0 123.0 75.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:51.30 Median:52.00 stddev:1.06 (2.0%) stddev-sans-first:1.09 17:27:02 INFO - PID 1657 | Values: 52.0 51.0 50.0 50.0 52.0 52.0 51.0 50.0 52.0 53.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:62.50 stddev:14.30 (22.9%) stddev-sans-first:14.93 17:27:02 INFO - PID 1657 | Values: 59.0 66.0 86.0 85.0 88.0 57.0 54.0 55.0 55.0 57.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:84.00 stddev:2.18 (2.6%) stddev-sans-first:2.18 17:27:02 INFO - PID 1657 | Values: 85.0 82.0 85.0 81.0 85.0 83.0 81.0 86.0 80.0 81.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:155.70 Median:156.50 stddev:21.33 (13.6%) stddev-sans-first:21.40 17:27:02 INFO - PID 1657 | Values: 136.0 157.0 157.0 128.0 156.0 153.0 168.0 156.0 206.0 140.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.70 Median:187.00 stddev:20.11 (10.8%) stddev-sans-first:21.13 17:27:02 INFO - PID 1657 | Values: 187.0 187.0 181.0 187.0 184.0 183.0 188.0 185.0 229.0 236.0 17:27:02 INFO - PID 1657 | 17:27:02 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:72.50 stddev:14.02 (19.3%) stddev-sans-first:14.76 17:27:02 INFO - PID 1657 | Values: 70.0 71.0 63.0 98.0 95.0 64.0 62.0 89.0 74.0 62.0 17:27:02 INFO - PID 1657 | -------- Summary: end -------- 17:27:02 INFO - PID 1657 | 17:27:03 INFO - TEST-INFO | 1657: exit 0 17:27:03 INFO - TEST-OK | kraken | took 61348ms 17:27:03 INFO - SUITE-END | took 1176s 17:27:03 INFO - Completed test suite (00:19:37) 17:27:03 INFO - javascript benchmark 17:27:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7786.647504263416, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3005.95491067634, 3611.999999999998, 3627.1113577173587, 3639.8908032759027, 3649.069487280744, 6116.663583502906, 6183.6598987055695, 6218.626882387054, 6224.15973843531, 6260.624362538251, 4685.344051832738, 6180.536459765524, 6198.566100372976, 6259.593126446778, 6294.811155665326, 4037.454943582623, 5394.7842086316505, 5428.728563571815, 5476.069068258385, 5574.968630803314, 5409.56723462122, 5465.316835395575, 5553.416891226424, 5564.944350556483, 5581.2186293918885, 5362.2224777407055, 5456.91814622781, 5503.834884953418, 5521.613487055908, 5537.224788529609, 5438.619296649236, 5463.9180412293845, 5478.643888147258, 5497.8075767348355, 5534.501894829485, 5368.516833484966, 5399.83800485986, 5419.593530485229, 5420.91868621971, 5471.972640136773, 5018.996200759862, 6059.575829691923, 6113.081058499965, 6123.343628574293, 6125.509959203253, 6642.833929151761, 6699.196096468406, 6713.630750308731, 6714.194296684429, 6719.832004199885, 6785.321467853225, 6800.523963322569, 6801.489888258351, 6825.146856642919, 6842.726290948356, 6118.4364438206485, 6135.906433916183, 6285.0, 6314.55798094129, 6345.8730825384155, 6755.864882702318, 6822.9317706823285, 6839.62382068991, 6844.623545705034, 6891.931080689229, 6298.464630506458, 6415.67921603918, 6502.60984340941, 6513.0, 6581.80254592363, 6684.498662600325, 6733.0, 6764.864702705919, 6780.457563395015, 6792.252852186256, 6346.333634968354, 6463.64449955257, 6628.5360024798765, 6650.334966503311, 6662.967240077795, 6421.454176395058, 6592.934070659232, 6642.767503137464, 6676.031975363545, 6694.698738556776, 5282.498162674539, 5285.180796976475, 5316.016536940679, 5318.069337865858, 5346.4386239445075, 4640.883977900579, 4710.105080034851, 4731.976340118346, 4754.4532378776885, 4758.2386818108935, 4966.528179822911, 6108.251008765781, 6207.0, 6309.116723658692, 6320.399562041599, 6759.9522074078595, 6785.626790526469, 6803.319668033256, 6807.829804254834, 6816.727330906808, 6276.529260305495, 6304.810855674337, 6341.8097457076365, 6354.237491500957, 6379.213820130371, 4589.380433641482, 5708.029634962067, 5740.024195886709, 5792.073268277056, 5793.797217097382, 5228.895422091461, 5789.565782566408, 6899.655017249117, 6924.376806087577, 6998.545094568837, 5094.439611642791, 6302.613425046666, 6452.516061295515, 6601.108850305147, 6640.269570347355, 6689.765858195135, 6758.357955994173, 6871.622060786704, 6872.828179295558, 6882.105326307548, 5576.749046293007, 6482.708278127401, 6611.603303801788, 6626.436752875867, 6675.866482670416, 6535.150430444012, 6742.4943129266485, 6765.1205343305055, 6785.321467853175, 6791.185057793096, 6354.682265886595, 6445.871082578416, 6458.67706614658, 6508.121403610452, 6541.280459149585, 6449.226092868886, 6503.284638689645, 6586.275509693834, 6602.141721576164, 6603.372679595431, 5256.868578285574, 6136.478399336106, 6279.937200627935, 6387.648679322682, 6457.966725323926, 6539.673016349258, 6584.572002819801, 6591.406773390418, 6601.273859875506, 6605.867882642417, 6395.456386207225, 6411.935880641134, 6413.172245909894, 6433.292337842927, 6441.838954026], "value": 6036.7102112467655, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [798.8801679748037, 1226.3990644584146, 1237.461704158691, 1240.4727990603997, 1259.4395494005166, 37495.625043749555, 38275.42586861196, 38297.042573935665, 38302.80848595757, 38369.0, 37821.05447363817, 38065.61934380655, 38134.809325953334, 38236.617633823655, 38300.80849595755, 8325.791855203608, 8531.189536993976, 8534.061253262136, 8541.316694664443, 8594.914050859474, 8538.957305213466, 8612.1387861214, 8635.222829945304, 8695.087015863339, 8723.520206388646, 8583.828323433527, 8622.094680058597, 8649.740507784745, 8691.348148888826, 8704.04255531891, 8641.265492433151, 8659.307255419551, 8660.913390866073, 8698.173673501007, 8703.52130632815, 8372.790680232982, 8392.24469797715, 8452.957735211347, 8456.704015359495, 8488.066312705598, 37427.31577079038, 37578.62421375779, 37609.81195094034, 37610.43584346237, 37664.81167594145, 3663.523741913561, 3671.7429779915415, 3692.981535092334, 3724.802366603717, 3725.2177042820767, 30384.696153038407, 30636.54045189324, 30680.846595766878, 30725.846370768228, 30760.230994225323, 2835.6814081452226, 3004.2639553309577, 3170.984145079283, 3182.7612929030415, 3191.7925334853394, 233534.83232583897, 238241.61758382368, 279517.0, 281026.0, 281807.0, 38823.41764873529, 47399.28901066487, 48018.75990620095, 48116.759416202694, 48257.75871120622, 48717.0, 48902.51097489051, 48912.26631600529, 48963.26555101676, 49062.01875962461, 12443.377831108408, 13579.506254312215, 13893.930530347283, 13900.582982510541, 14067.71864562703, 11278.266912650815, 11329.60346387889, 11452.94273528627, 11464.082873370111, 11492.195546311845, 11011.779764404828, 13060.673483162807, 13214.933925330311, 13345.866541334462, 13373.66565835862, 5921.437463441052, 5926.140709597084, 5943.732532036069, 5953.255843019622, 5955.910661340084, 856.5203486047833, 1016.4714348538719, 1022.1873610479687, 1023.5240613114937, 1060.4697651174413, 20408.0, 22013.0, 23151.073957041528, 23970.52058958811, 24000.87999560026, 1096.276457538037, 1104.9502772375101, 1110.639042311252, 1124.6907100547414, 1154.9133814963911, 12117.454714537867, 12146.45340959659, 12210.26738395699, 12223.816642750366, 12288.139830211978, 321398.0, 323580.0, 323787.0, 323996.0, 324100.0, 53534.19698704522, 53863.0, 53971.0, 54071.18893216605, 54085.72957135268, 1472.5214305350803, 1480.4374337751585, 1508.0122519749582, 1518.916459594733, 1540.5455390659781, 18349.724754128965, 19613.70579441338, 22737.545249095256, 23076.384653074125, 23570.93930773085, 37773.62226377701, 37929.81035094807, 37935.05162370963, 38045.4293185608, 38130.0, 281057.0, 284523.0, 284643.0, 285000.0, 286796.0, 34039.48940765841, 34185.316293674485, 34306.65693343035, 34315.0, 34606.480902786985, 43982.340264896695, 44277.11445771131, 44558.55441445674, 44781.77609112064, 44966.550334496234, 30608.0, 30615.54076688896, 31109.377812444076, 31448.0, 31590.684093158776, 21071.473213169793, 29829.55255671123, 30162.396752065273, 30911.0, 30922.917697880464, 36752.713655021944, 37321.81339093287, 37376.43935340918, 37387.43918841165, 37432.877013689635, 29233.246005239755, 29837.850810745807, 30499.847500762353, 30529.0, 30862.69137308688, 19033.90483047576, 20222.898885505478, 20639.587208256053, 23848.523029539658, 24064.398390040387], "value": 17382.271701012647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.366536390091, 411.7241448229686, 414.76565740356716, 423.7266962808988, 436.2627160099432, 20325.390238292857, 21115.788842111575, 21716.3485095447, 21842.0, 21981.780182198192, 21202.681959770576, 21587.136514539437, 21627.0, 21794.80128592927, 21808.0, 5866.413358664131, 6121.112438696387, 6124.663143527104, 6135.3864613538735, 6143.7235324410285, 5241.68549887008, 5887.293524777033, 6014.0, 6149.16986206863, 6180.876382472348, 5629.9437005629825, 6062.18160548327, 6137.324894261627, 6153.077038444247, 6160.691965401735, 5979.910301345483, 5989.251343582052, 6099.725512351911, 6122.785702500436, 6126.632402055869, 5307.575393968474, 5392.164214546751, 5428.782848686048, 5462.8361149165585, 5464.0, 23523.76476235233, 27520.58719119215, 28142.0, 28312.009079682113, 28547.57178642322, 2171.9782802172094, 2246.629306164481, 2253.0537174386795, 2287.9542409151722, 2291.6906217660735, 14916.552503424915, 17862.19620117098, 17962.19170137347, 18222.453326400228, 18456.35402760897, 2076.0, 2141.2184552638173, 2164.6644770060666, 2168.8048075673264, 2169.4793249620134, 159776.20111899482, 161314.0, 168253.0, 171297.0, 174730.0, 12199.634010979686, 12254.754904901853, 12387.0, 12392.256464612152, 12647.683807904876, 15819.604509887346, 15970.9201453992, 16061.036337819769, 16143.192840357935, 16179.271932763055, 8666.306695464347, 8702.912970870337, 8851.33614978879, 8863.335249856287, 9011.098890111067, 6902.275261097613, 8356.33149348063, 8381.371397145238, 8511.787205319793, 8562.143785621389, 6998.580085194802, 7551.395888328921, 7563.924360756432, 7799.844003120019, 7865.724699635485, 2058.835293176542, 2098.6537221358303, 2106.2101711858054, 2115.936521904345, 2290.690756747851, 834.4242472693943, 937.864009718587, 960.5533426956359, 975.7024107647094, 1007.4912169354576, 16182.43361482366, 16336.918315408348, 16521.752173717403, 18853.811461885205, 20111.59776804485, 978.2907392140642, 990.5443495991924, 1003.4330603209163, 1008.7931973945324, 1013.5641674080216, 8991.370604057784, 9760.755981100387, 10827.621033263955, 10869.782604347869, 10870.0, 445632.0, 523408.0, 524443.0, 536269.0, 536916.0, 130382.0, 132040.33979830038, 132677.0, 133124.6687533112, 133956.9906451404, 1145.7765735681628, 1151.0791366906442, 1173.9589114380956, 1206.1113632849451, 1228.4901765767286, 20757.896210519455, 21141.26005589797, 21794.782052179275, 21801.0, 21883.577567458065, 48963.020739584295, 50685.49314506955, 58985.41014589799, 59184.112238315596, 59386.70306648439, 56644.43355566391, 75979.24020759722, 76866.61566692131, 77419.61290193514, 78247.60876195582, 7414.22150674193, 7662.578558179353, 8589.184027517376, 8653.0, 8867.157620026088, 13783.586492405244, 13802.723945521235, 13933.930330348283, 14173.433062677377, 14291.142531448146, 11604.477798499256, 11655.41722913834, 11888.881111189123, 12005.699857503283, 12119.636410907688, 9210.585523651318, 10705.785884282426, 11147.386893720923, 11258.887411126112, 11289.491972861077, 11556.94221528887, 11849.348285844362, 11969.940150299193, 12082.637520874388, 12150.0, 7334.449916256409, 7619.352355049883, 8414.789630259047, 8466.57667116654, 8608.612612432331, 11625.24435911663, 11745.530178792753, 11908.166428349932, 11947.82078268844, 12086.0], "value": 10243.580697661553, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.5611760354288, 1440.5062039646054, 1459.46729443753, 1485.6657252118275, 1491.724031054255, 11346.43267836608, 11640.883591164085, 11888.405579721011, 11896.46465909034, 12052.517899284017, 11800.822987655192, 11868.881311186886, 11951.223170493911, 11969.40152992349, 12047.819282710767, 2477.6159695247266, 2525.7600527949817, 2527.279725278296, 2530.5318516074494, 2531.1014589820625, 2468.8642124683133, 2509.2597683683357, 2509.9749002510016, 2515.2328539795353, 2525.6211568264725, 2453.852768833876, 2476.427468557461, 2512.1207577347964, 2515.245426372081, 2524.00301880757, 2438.4635380216414, 2481.6525686403916, 2490.7882829959476, 2491.3397949543387, 2493.339265094751, 2439.121916110199, 2459.114718701266, 2467.4448249143798, 2481.838680485763, 2493.164789795421, 10260.486975651262, 10948.069414099811, 11536.884631153665, 11739.765204695857, 11847.763044739057, 6338.651374174418, 6479.2224933007865, 6573.1783527059115, 6576.375244351779, 6590.901136482957, 3827.158025234444, 4108.301588729923, 4148.170365926797, 4204.075103477261, 4222.915541689149, 1224.1736827641396, 1239.4856134704087, 1282.1217466035796, 1286.6526037969695, 1289.8387701537308, 15841.287142078634, 16215.756763648555, 16245.83754162455, 16288.592785180464, 16430.0, 5295.814646487354, 5400.810971615974, 5426.213199086111, 5537.7784888604, 5543.586385471679, 9281.350305478687, 9857.950710246403, 12050.578229762092, 12224.938875305745, 12301.32342721159, 6341.841453963688, 6396.840078998062, 6411.198600174978, 6417.390347917034, 6422.967885160544, 5850.0, 5948.226730525004, 5963.389884731098, 6011.458968692751, 6099.054646529795, 4164.416981622575, 4273.91452170961, 4293.484781826201, 4320.4599425071865, 4344.956550434518, 1068.8984546468228, 1201.6481458359347, 1211.2006075989805, 1235.2403271987662, 1250.3998080921208, 663.2737152817657, 797.6928882380209, 815.6370415165313, 815.8613035783931, 822.761399194239, 6619.602823830585, 6830.521863469608, 6946.0622815920215, 6983.8603227935155, 6992.930070699228, 927.0544045073987, 949.7578117579973, 954.9092836180552, 955.3933252384786, 966.1884017425256, 4070.9796451017555, 4362.585554372329, 4363.47638283408, 4459.732416055047, 4476.798544065459, 14291.785623215661, 14976.400943962119, 15035.248237588077, 15061.924690376478, 15141.772873406688, 14264.500742473963, 14744.262786860614, 14876.776848347283, 14921.328540215716, 14924.104553726811, 7407.444441666897, 8299.917000830035, 8490.278326342206, 8532.146785321542, 8572.61423235956, 6104.17593624855, 6606.504512161703, 6821.590704557742, 6974.895376569254, 7030.086088808423, 12057.216280941711, 12402.0, 12567.0, 12722.809157862454, 12793.104482686123, 11629.592964246549, 12657.873421266038, 12778.297193654438, 12913.548025819051, 13143.605691828878, 8748.0, 9547.904520954702, 9582.616695332108, 9607.663731769355, 9639.759006024626, 8153.1846815317995, 8465.83068338642, 10349.793004140025, 10452.372857628567, 10704.625338113128, 9024.323175761976, 9035.186833184782, 9060.139286767746, 9130.360874738704, 9194.35639505255, 8785.824283514421, 8894.4663320201, 8914.242289405473, 9091.0, 9169.35814492979, 8106.959465202636, 8191.13993030747, 8238.588070596326, 8260.669573217005, 8287.875681864656, 5872.823815285548, 5888.528917686661, 5939.970300148471, 5948.405159484017, 5996.520278377632, 5309.522143006993, 5785.756062446594, 6066.575339726177, 6179.3820617937845, 6304.621722696653], "value": 5315.86206342866, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.68648051837766, 388.5318191579146, 390.4280229463836, 395.6182284095848, 397.19766072533486, 4766.475687674353, 4782.7608619569055, 5451.182322651604, 6171.691415429227, 6184.845378865531, 5186.858891043964, 5380.816220431509, 5772.797952071678, 6035.305939816923, 6069.271687397508, 3965.702572307074, 4570.97714511427, 4584.037352156043, 4585.9541404586025, 4586.54134586542, 4518.2319005769095, 4585.9541404586025, 4588.564086411786, 4592.609628181609, 4617.930731039037, 4375.4093197418415, 4550.317452382136, 4562.0, 4580.58774710276, 4587.541245875419, 3888.4361767543687, 4296.978515107436, 4525.7554172602795, 4562.350119904064, 4568.360429539867, 4008.6793056555407, 4025.4364388985564, 4028.9194216115807, 4037.576054514263, 4053.5135783706146, 5673.8297851064535, 5930.0, 5933.22868027158, 6005.159277701126, 6020.397960203988, 5274.604404669665, 5318.973405132988, 5374.3013408256675, 5378.1663842104535, 5397.244385785953, 6565.671716414208, 6595.241547222083, 6605.339466053404, 6608.339166083401, 6618.602883826986, 3548.946765798515, 3733.9253214935547, 4181.581841815824, 4193.391958166048, 4399.142167277382, 6213.794103093427, 6376.489880809524, 6706.597604143767, 6974.814281572145, 6983.162020557515, 4402.085092984562, 4425.424694789656, 4445.266346125014, 4461.955380446219, 4472.843450479218, 3823.617638236154, 4515.187266292099, 4534.342520334533, 4535.095259990811, 4535.569120933506, 4206.558311377323, 5104.626348747775, 5157.839486115594, 5295.17316525798, 5375.763574377915, 6550.967245163743, 6556.7705130321165, 6571.934280657228, 6577.375149360803, 6578.0, 1370.5888233529902, 1452.128722766353, 1520.657851983317, 1541.7764424158433, 1555.3234343060806, 1009.9040591143829, 1021.2085633633993, 1106.590561492253, 1163.313644949484, 1179.8761130081389, 5368.302120724281, 6188.824123416536, 6212.875742485125, 6226.820612785489, 6228.5017198624, 5584.984108581401, 6035.486983606443, 6454.257760357619, 6820.35206655367, 6835.350641689031, 5103.923441148386, 5924.496417804535, 6333.20834895638, 6359.841003974845, 6364.140840986408, 2753.518134326485, 3293.847153496305, 3531.52324436203, 3538.663826936437, 3569.39320315547, 6138.447539721446, 6314.285571557748, 6695.598264104169, 6888.138982627172, 7064.682089305994, 5220.885541355757, 6664.1336626239245, 6989.475789315821, 7003.404710599656, 7003.809352410102, 5147.794088236504, 5538.695371754511, 6359.586626869239, 6563.803085907335, 7039.19049309336, 5912.349641539513, 6805.761798337035, 6840.41856442208, 6849.1096157499205, 6850.246472888078, 5528.889422211614, 5933.970330148321, 6372.299047104908, 6737.292584278776, 6770.255271920183, 7064.717411303491, 7106.822329441598, 7144.3927266182955, 7152.320529549685, 7175.31834475724, 4458.7547684877645, 4460.8661740147845, 4482.79827407774, 4492.752898590609, 4498.707584007029, 4868.926966095441, 5414.648047876875, 6810.216825065181, 6899.412720229914, 6950.582965022115, 5439.5648348132845, 6265.749370025148, 6578.8355291118105, 6594.637294948824, 6611.33886611335, 5162.561182299547, 6345.650989195638, 6374.0, 6476.4495017924, 6621.768238111643, 6120.7551697931585, 6200.410960958701, 6409.647469389229, 6450.4841362278885, 6466.612003279818, 4979.858660775756, 5210.53105220532, 5970.402959703995, 6504.38208370223, 6519.674016299261, 6371.553991220755, 6419.325970773001, 6429.292777794532, 6455.193100862392, 6504.284528701934], "value": 4720.02498335044, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.2825170353499, 839.8026463781013, 840.6595328891797, 860.8077812230064, 867.5488745852155, 16459.506214813573, 16579.58551036223, 16923.223061578527, 18503.352382666577, 19081.427557173272, 17606.0, 17970.01164935928, 18159.818401815977, 18206.81793182068, 18478.445646630622, 967.6564819489085, 989.1098011789411, 990.6334656177224, 990.8464188050864, 991.3110388280148, 969.3021024862088, 984.2322988069318, 986.9555869985868, 987.5407935310079, 988.0317289056728, 1038.9921775877388, 1043.5564884923915, 1046.2101113659176, 1046.502911117221, 1046.7854089911589, 1024.7079582319004, 1035.715178325959, 1036.0985942230232, 1038.9168866490663, 1041.7656027393814, 717.5945590741214, 739.3345988610293, 750.3846845586621, 751.1737089201889, 753.6909866954577, 16537.669246615, 16583.336666533323, 16631.750523742154, 16687.0, 16757.83242167587, 13764.862351376458, 16813.075280859546, 16908.0, 17268.740968885475, 17379.913100434416, 12509.74980500394, 12791.168574042658, 12811.103222774316, 12820.935895320557, 12828.358582070952, 3475.1138459692615, 3583.5341405617364, 3625.329314076878, 3631.1285291530107, 3644.2711457708565, 87920.12079879183, 90008.0, 113877.0, 114513.0, 117589.0, 13029.56674765794, 13401.0, 13676.863231367757, 13721.313934303445, 13758.65603359924, 20701.58596828085, 25002.0, 25545.0, 25962.87018564895, 26044.869775651, 7752.0697516298405, 7805.960970195113, 7892.329152022027, 7949.880751788728, 7969.521828690297, 3298.6371499135075, 3440.7785236241275, 3451.7238620910266, 3894.1043559981363, 3896.941545876814, 4280.571942805695, 4342.82628694855, 4360.825566977349, 4369.6722745794195, 4401.097774956126, 701.3057073497303, 789.6999140326639, 808.5633757770738, 814.3241109878786, 820.9917900821035, 614.0666187395134, 628.6510986402591, 668.762589280808, 673.3771261583016, 694.9687264073128, 10876.728081798019, 11906.285622862482, 11918.761624767456, 11938.820917686242, 12037.62192058811, 633.762339122829, 642.7975187815916, 645.9483241340776, 649.1787888321277, 660.8711301296297, 5537.1140617501005, 5975.40245975399, 6029.216201893726, 6030.125631783367, 6083.908741368883, 111412.4429377848, 114258.42870785759, 114571.42714286376, 116174.41912790382, 116272.0, 73643.26356736472, 98361.0, 98569.0, 101140.49429752804, 102900.0, 87016.25967480741, 87174.0, 87257.0, 87837.0, 89224.38573249655, 9668.564914579001, 12237.877621223673, 12521.56174533887, 12531.686707832378, 12544.24734515932, 25394.0, 33911.15222119368, 38670.6132938667, 40458.595414045485, 42774.0, 51146.0, 56274.718626406604, 56457.0, 56520.0, 56986.29041128773, 14466.20435876037, 15541.222938853238, 16408.835911641207, 16730.4144354947, 16769.748453772958, 14461.204633745245, 18206.75894749969, 18267.177976991334, 18283.81716182821, 18478.983655899054, 13898.652533686738, 16695.998240105633, 16897.239624216694, 16953.491395258163, 17084.48746537606, 14096.718065638835, 16591.419300324913, 16735.58161045935, 16872.409465669134, 16898.23957921872, 16191.733157358214, 16310.347586096423, 16486.917565412095, 16646.667066658356, 16936.915315423837, 9608.423494590346, 10186.490675466346, 11127.887211278807, 11660.30038197711, 12042.939785301018, 12914.612561623166, 12915.289659068465, 12918.289494077915, 12984.805227921777, 13034.47862085506], "value": 8264.098829598981, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1529.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 117.0, 115.0, 112.0, 114.0, 138.0, 167.0, 147.0, 114.0, 116.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 110.0, 112.0, 162.0, 137.0, 150.0, 98.0, 134.0, 165.0, 144.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 199.0, 213.0, 218.0, 213.0, 203.0, 198.0, 202.0, 203.0, 196.0], "value": 204.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 58.0, 85.0, 63.0, 59.0, 61.0, 62.0, 81.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 69.0, 80.0, 69.0, 67.0, 67.0, 85.0, 69.0, 71.0, 91.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 92.0, 93.0, 92.0, 92.0, 92.0, 94.0, 92.0], "value": 92.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 98.0, 98.0, 99.0, 98.0, 98.0, 105.0, 92.0, 160.0], "value": 104.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 102.0, 92.0, 108.0, 88.0, 130.0, 99.0, 123.0, 75.0], "value": 99.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 50.0, 52.0, 52.0, 51.0, 50.0, 52.0, 53.0], "value": 51.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 66.0, 86.0, 85.0, 88.0, 57.0, 54.0, 55.0, 55.0, 57.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 81.0, 85.0, 83.0, 81.0, 86.0, 80.0, 81.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 157.0, 157.0, 128.0, 156.0, 153.0, 168.0, 156.0, 206.0, 140.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 181.0, 187.0, 184.0, 183.0, 188.0, 185.0, 229.0, 236.0], "value": 194.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 63.0, 98.0, 95.0, 64.0, 62.0, 89.0, 74.0, 62.0], "value": 74.8, "unit": "score"}]}]} 17:27:03 INFO - Return code: 0 17:27:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:27:03 ERROR - # TBPL SUCCESS # 17:27:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:27:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:27:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:27:03 INFO - 2017-04-24 17:27:03.833 screenresolution[1662:10736] starting screenresolution argv=screenresolution get 17:27:03 INFO - 2017-04-24 17:27:03.836 screenresolution[1662:10736] Display 0: 1600x1200x32@60 17:27:03 INFO - 2017-04-24 17:27:03.842 screenresolution[1663:10739] starting screenresolution argv=screenresolution list 17:27:03 INFO - Available Modes on Display 0 17:27:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:27:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:27:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:27:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:27:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:27:04 INFO - Intel Iris: 17:27:04 INFO - Chipset Model: Intel Iris 17:27:04 INFO - Type: GPU 17:27:04 INFO - Bus: Built-In 17:27:04 INFO - VRAM (Dynamic, Max): 1536 MB 17:27:04 INFO - Vendor: Intel (0x8086) 17:27:04 INFO - Device ID: 0x0a2e 17:27:04 INFO - Revision ID: 0x0009 17:27:04 INFO - Displays: 17:27:04 INFO - Display: 17:27:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:27:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:27:04 INFO - Display Serial Number: 1600x1200 60 17:27:04 INFO - Main Display: Yes 17:27:04 INFO - Mirror: Off 17:27:04 INFO - Online: Yes 17:27:04 INFO - Rotation: Supported 17:27:04 INFO - Return code: 0 17:27:04 INFO - Running post-action listener: _resource_record_post_action 17:27:04 INFO - [mozharness: 2017-04-25 00:27:04.086263Z] Finished run-tests step (success) 17:27:04 INFO - Running post-run listener: _resource_record_post_run 17:27:04 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 37693952; Write bytes: 513684480; Read time: 388; Write time: 1607 17:27:04 INFO - TinderboxPrint: CPU usage
54.0% 17:27:04 INFO - TinderboxPrint: I/O read bytes / time
37,693,952 / 388 17:27:04 INFO - TinderboxPrint: I/O write bytes / time
513,684,480 / 1,607 17:27:04 INFO - TinderboxPrint: CPU idle
2,206.0 (46.0%) 17:27:04 INFO - TinderboxPrint: CPU system
965.3 (20.1%) 17:27:04 INFO - TinderboxPrint: CPU user
1,622.4 (33.8%) 17:27:04 INFO - TinderboxPrint: Swap in / out
246,321,152 / 0 17:27:04 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203555328; Write bytes: 198240256; Read time: 15945; Write time: 416 17:27:04 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 35850752; Write bytes: 300903424; Read time: 363; Write time: 1139 17:27:04 INFO - Running post-run listener: _upload_blobber_files 17:27:04 INFO - Blob upload gear active. 17:27:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:27:04 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 17:27:04 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'] 17:27:04 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 17:27:04 INFO - (blobuploader) - INFO - Open directory for files ... 17:27:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:27:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:27:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:06 INFO - (blobuploader) - INFO - Done attempting. 17:27:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:27:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:27:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:07 INFO - (blobuploader) - INFO - Done attempting. 17:27:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:27:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:27:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:08 INFO - (blobuploader) - INFO - Done attempting. 17:27:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:27:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:27:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:27:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:27:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:27:10 INFO - (blobuploader) - INFO - Done attempting. 17:27:10 INFO - (blobuploader) - INFO - Iteration through files over. 17:27:10 INFO - Return code: 0 17:27:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:27:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:27:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c0bf6325728bd3dae73fd32ac55d809872346c203134d9adba9165eac23180686f15d456147936aee77cd38c51d0acfe329fe0e70e63de41b4c2156c1fdd89", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78a86cfb8a34b44744535a605d5d3da56653d41cdf6e9347ecc422c4ec4a7d24b184a870a856a8471ff16886ab474e0202b1915ea73ce48aba7429f16f2bdd3e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c9632fea2c1d98025c611e4069fd7197fa3b585c4437b1e1369f7e7c177bba1f3b74f3ec3c129dbc761e7654ab9e6fcbd1582edcfd782fe39ad2fccdbc5004", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da29d4649283a107a379af216d7735d698e4b7f7016fa0fe08da3ad9a861de9548cf85c3f0972ad23320276e388e3edffad7d780960cfe1d8128ee51ee5e8f7c"} 17:27:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:27:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:27:10 INFO - Contents: 17:27:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c0bf6325728bd3dae73fd32ac55d809872346c203134d9adba9165eac23180686f15d456147936aee77cd38c51d0acfe329fe0e70e63de41b4c2156c1fdd89", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78a86cfb8a34b44744535a605d5d3da56653d41cdf6e9347ecc422c4ec4a7d24b184a870a856a8471ff16886ab474e0202b1915ea73ce48aba7429f16f2bdd3e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c9632fea2c1d98025c611e4069fd7197fa3b585c4437b1e1369f7e7c177bba1f3b74f3ec3c129dbc761e7654ab9e6fcbd1582edcfd782fe39ad2fccdbc5004", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da29d4649283a107a379af216d7735d698e4b7f7016fa0fe08da3ad9a861de9548cf85c3f0972ad23320276e388e3edffad7d780960cfe1d8128ee51ee5e8f7c"} 17:27:10 INFO - Running post-run listener: copy_logs_to_upload_dir 17:27:10 INFO - Copying logs to upload dir... 17:27:10 INFO - mkdir: /builds/slave/test/build/upload/logs 17:27:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.728111 ========= master_lag: 1.90 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-24 17:27:11.975492) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 17:27:11.978662) ========= 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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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/89c0bf6325728bd3dae73fd32ac55d809872346c203134d9adba9165eac23180686f15d456147936aee77cd38c51d0acfe329fe0e70e63de41b4c2156c1fdd89", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78a86cfb8a34b44744535a605d5d3da56653d41cdf6e9347ecc422c4ec4a7d24b184a870a856a8471ff16886ab474e0202b1915ea73ce48aba7429f16f2bdd3e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c9632fea2c1d98025c611e4069fd7197fa3b585c4437b1e1369f7e7c177bba1f3b74f3ec3c129dbc761e7654ab9e6fcbd1582edcfd782fe39ad2fccdbc5004", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da29d4649283a107a379af216d7735d698e4b7f7016fa0fe08da3ad9a861de9548cf85c3f0972ad23320276e388e3edffad7d780960cfe1d8128ee51ee5e8f7c"} build_url:https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011215 build_url: 'https://queue.taskcluster.net/v1/task/XddGYYKbRw2n_FDTcPa_Yw/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/89c0bf6325728bd3dae73fd32ac55d809872346c203134d9adba9165eac23180686f15d456147936aee77cd38c51d0acfe329fe0e70e63de41b4c2156c1fdd89", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78a86cfb8a34b44744535a605d5d3da56653d41cdf6e9347ecc422c4ec4a7d24b184a870a856a8471ff16886ab474e0202b1915ea73ce48aba7429f16f2bdd3e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d7c9632fea2c1d98025c611e4069fd7197fa3b585c4437b1e1369f7e7c177bba1f3b74f3ec3c129dbc761e7654ab9e6fcbd1582edcfd782fe39ad2fccdbc5004", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da29d4649283a107a379af216d7735d698e4b7f7016fa0fe08da3ad9a861de9548cf85c3f0972ad23320276e388e3edffad7d780960cfe1d8128ee51ee5e8f7c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-24 17:27:12.015465) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 17:27:12.015761) ========= 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.Kyj7i8Tfku/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.O9s64EIDj3/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005167 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-24 17:27:12.063869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 17:27:12.064183) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-24 17:27:12.064496) ========= ========= Total master_lag: 2.03 =========