builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0073 starttime: 1494514653.65 results: success (0) revision: 4c580a771776f77c667fd457e2915568c2fcd0a7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:33.646557) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:33.647011) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:33.647295) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:33.673060) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:33.673386) ========= 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.TXuC28l5Up/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.DUuSqT7anv/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-05-11 07:57:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.2M=0s 2017-05-11 07:57:33 (28.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135509 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:33.835868) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:33.836632) ========= 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.TXuC28l5Up/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.DUuSqT7anv/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.040766 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:33.900635) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 07:57:33.901005) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4c580a771776f77c667fd457e2915568c2fcd0a7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 4c580a771776f77c667fd457e2915568c2fcd0a7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TXuC28l5Up/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.DUuSqT7anv/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-05-11 07:57:34,008 truncating revision to first 12 chars 2017-05-11 07:57:34,008 Setting DEBUG logging. 2017-05-11 07:57:34,008 attempt 1/10 2017-05-11 07:57:34,008 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4c580a771776?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 07:57:35,678 unpacking tar archive at: mozilla-central-4c580a771776/testing/mozharness/ program finished with exit code 0 elapsedTime=1.941148 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 07:57:35.864450) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:35.864775) ========= script_repo_revision: 4c580a771776f77c667fd457e2915568c2fcd0a7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:35.865153) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:35.865441) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 07:57:35.884598) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 26 secs) (at 2017-05-11 07:57:35.885001) ========= /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.TXuC28l5Up/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.DUuSqT7anv/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:57:36 INFO - MultiFileLogger online at 20170511 07:57:36 in /builds/slave/test 07:57:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:57:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:57:36 INFO - {'append_to_log': False, 07:57:36 INFO - 'base_work_dir': '/builds/slave/test', 07:57:36 INFO - 'blob_upload_branch': 'Firefox', 07:57:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:57:36 INFO - 'branch': 'Firefox', 07:57:36 INFO - 'buildbot_json_path': 'buildprops.json', 07:57:36 INFO - 'config_files': ('talos/mac_config.py',), 07:57:36 INFO - 'default_actions': ('clobber', 07:57:36 INFO - 'read-buildbot-config', 07:57:36 INFO - 'download-and-extract', 07:57:36 INFO - 'populate-webroot', 07:57:36 INFO - 'create-virtualenv', 07:57:36 INFO - 'install', 07:57:36 INFO - 'run-tests'), 07:57:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:57:36 INFO - 'download_minidump_stackwalk': True, 07:57:36 INFO - 'download_symbols': 'ondemand', 07:57:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:57:36 INFO - 'tooltool.py': '/tools/tooltool.py', 07:57:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:57:36 INFO - '/tools/misc-python/virtualenv.py')}, 07:57:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:57:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:57:36 INFO - 'gecko_profile': False, 07:57:36 INFO - 'gecko_profile_interval': 0, 07:57:36 INFO - 'installer_path': 'installer.exe', 07:57:36 INFO - 'log_level': 'info', 07:57:36 INFO - 'log_name': 'talos', 07:57:36 INFO - 'log_to_console': True, 07:57:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:57:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:57:36 INFO - 'opt_config_files': (), 07:57:36 INFO - 'pip_index': False, 07:57:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:57:36 INFO - 'cmd': ('bash', 07:57:36 INFO - '-c', 07:57:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:57:36 INFO - 'enabled': True, 07:57:36 INFO - 'halt_on_failure': False, 07:57:36 INFO - 'name': 'check_screen_resolution'},), 07:57:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:57:36 INFO - 'cmd': ('bash', 07:57:36 INFO - '-c', 07:57:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:57:36 INFO - 'enabled': True, 07:57:36 INFO - 'halt_on_failure': False, 07:57:36 INFO - 'name': 'check_screen_resolution'},), 07:57:36 INFO - 'run_cmd_checks_enabled': True, 07:57:36 INFO - 'suite': 'dromaeojs-e10s', 07:57:36 INFO - 'system_bits': '32', 07:57:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:57:36 INFO - 'title': 't-yosemite-r7-0073', 07:57:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:57:36 INFO - 'use_talos_json': True, 07:57:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:57:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:57:36 INFO - 'work_dir': 'build'} 07:57:36 INFO - [mozharness: 2017-05-11 14:57:36.105258Z] Running clobber step. 07:57:36 INFO - Running pre-action listener: _resource_record_pre_action 07:57:36 INFO - Running main action method: clobber 07:57:36 INFO - rmtree: /builds/slave/test/build 07:57:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:57:38 INFO - Running post-action listener: _resource_record_post_action 07:57:38 INFO - [mozharness: 2017-05-11 14:57:38.560409Z] Finished clobber step (success) 07:57:38 INFO - [mozharness: 2017-05-11 14:57:38.560544Z] Running read-buildbot-config step. 07:57:38 INFO - Running pre-action listener: _resource_record_pre_action 07:57:38 INFO - Running main action method: read_buildbot_config 07:57:38 INFO - Using buildbot properties: 07:57:38 INFO - { 07:57:38 INFO - "project": "", 07:57:38 INFO - "product": "firefox", 07:57:38 INFO - "who": "ryanvm@gmail.com", 07:57:38 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:57:38 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:57:38 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:57:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:57:38 INFO - "stage_platform": "macosx64", 07:57:38 INFO - "basedir": "/builds/slave/test", 07:57:38 INFO - "buildnumber": 70, 07:57:38 INFO - "platform": "yosemite_r7", 07:57:38 INFO - "parent_task_id": "G98BLq8zSRmtZIeIu7xZ4Q", 07:57:38 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:57:38 INFO - "slavebuilddir": "test", 07:57:38 INFO - "taskId": "ZvPaeG8lQnmFw4RdyRqcjA", 07:57:38 INFO - "branch": "mozilla-central", 07:57:38 INFO - "script_repo_revision": "production", 07:57:38 INFO - "revision": "4c580a771776f77c667fd457e2915568c2fcd0a7", 07:57:38 INFO - "slavename": "t-yosemite-r7-0073", 07:57:38 INFO - "repo_path": "mozilla-central" 07:57:38 INFO - } 07:57:38 INFO - Finding installer, test and symbols from parent task. 07:57:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZvPaeG8lQnmFw4RdyRqcjA'}, attempt #1 07:57:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZvPaeG8lQnmFw4RdyRqcjA'}, attempt #1 07:57:39 INFO - Task dependencies: OMNSBXopTRKoyqBMltWu6A 07:57:39 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q'}, attempt #1 07:57:40 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:57:40 INFO - Set test_url: https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/test_packages.json 07:57:40 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/target.crashreporter-symbols.zip 07:57:40 INFO - Running post-action listener: _resource_record_post_action 07:57:40 INFO - [mozharness: 2017-05-11 14:57:40.282774Z] Finished read-buildbot-config step (success) 07:57:40 INFO - [mozharness: 2017-05-11 14:57:40.282893Z] Running download-and-extract step. 07:57:40 INFO - Running pre-action listener: _resource_record_pre_action 07:57:40 INFO - Running main action method: download_and_extract 07:57:40 INFO - mkdir: /builds/slave/test/build/tests 07:57:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:57:40 INFO - trying https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:57:40 INFO - Downloading https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:57:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 07:57:41 INFO - Downloaded 1325 bytes. 07:57:41 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:57:41 INFO - Using the following test package requirements: 07:57:41 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:57:41 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:57:41 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:57:41 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:57:41 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'jsshell-mac.zip'], 07:57:41 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:57:41 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:57:41 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:57:41 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:57:41 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:57:41 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:57:41 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:57:41 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:57:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:57:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:57:41 INFO - Fetch https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:57:42 INFO - Content-Length response header: 52709693 07:57:42 INFO - Bytes received: 52709693 07:57:59 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:57:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:57:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:57:59 INFO - Fetch https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:58:01 INFO - Content-Length response header: 52709693 07:58:01 INFO - Bytes received: 52709693 07:58:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:58:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:58:16 INFO - Fetch https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:58:17 INFO - Content-Length response header: 14409352 07:58:17 INFO - Bytes received: 14409352 07:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:58:18 INFO - trying https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:58:18 INFO - Downloading https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:58:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 07:58:20 INFO - Downloaded 75538339 bytes. 07:58:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:58:20 INFO - mkdir: /builds/slave/test/properties 07:58:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:58:20 INFO - Writing to file /builds/slave/test/properties/build_url 07:58:20 INFO - Contents: 07:58:20 INFO - build_url:https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:58:20 INFO - Running post-action listener: _resource_record_post_action 07:58:20 INFO - Running post-action listener: set_extra_try_arguments 07:58:20 INFO - [mozharness: 2017-05-11 14:58:20.354090Z] Finished download-and-extract step (success) 07:58:20 INFO - [mozharness: 2017-05-11 14:58:20.354267Z] Running populate-webroot step. 07:58:20 INFO - Running pre-action listener: _resource_record_pre_action 07:58:20 INFO - Running main action method: populate_webroot 07:58:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:58:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:58:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:58:20 INFO - '--tppagecycles', 07:58:20 INFO - '7'], 07:58:20 INFO - 'tests': ['tsvgm']}}, 07:58:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:58:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:58:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:58:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:58:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:58:20 INFO - 'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:58:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:58:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:58:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:58:20 INFO - 'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['damp', 'tps']}, 07:58:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:58:20 INFO - 'tests': ['damp', 'tps']}, 07:58:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['dromaeo_dom']}, 07:58:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:58:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:58:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:58:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['a11yr', 07:58:20 INFO - 'ts_paint', 07:58:20 INFO - 'tpaint', 07:58:20 INFO - 'sessionrestore', 07:58:20 INFO - 'sessionrestore_no_auto_restore', 07:58:20 INFO - 'tabpaint']}, 07:58:20 INFO - 'other-e10s': {'tests': ['a11yr', 07:58:20 INFO - 'ts_paint', 07:58:20 INFO - 'tpaint', 07:58:20 INFO - 'sessionrestore', 07:58:20 INFO - 'sessionrestore_no_auto_restore', 07:58:20 INFO - 'tabpaint']}, 07:58:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:58:20 INFO - 'ts_paint', 07:58:20 INFO - 'tpaint', 07:58:20 INFO - 'sessionrestore', 07:58:20 INFO - 'sessionrestore_no_auto_restore', 07:58:20 INFO - 'tabpaint']}, 07:58:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:58:20 INFO - 'ts_paint', 07:58:20 INFO - 'tpaint', 07:58:20 INFO - 'sessionrestore', 07:58:20 INFO - 'sessionrestore_no_auto_restore', 07:58:20 INFO - 'tabpaint']}, 07:58:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['a11yr', 07:58:20 INFO - 'ts_paint', 07:58:20 INFO - 'tpaint', 07:58:20 INFO - 'sessionrestore', 07:58:20 INFO - 'sessionrestore_no_auto_restore', 07:58:20 INFO - 'tabpaint']}, 07:58:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['a11yr', 07:58:20 INFO - 'ts_paint', 07:58:20 INFO - 'tpaint', 07:58:20 INFO - 'sessionrestore', 07:58:20 INFO - 'sessionrestore_no_auto_restore', 07:58:20 INFO - 'tabpaint']}, 07:58:20 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:58:20 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:58:20 INFO - 'bloom_basic_ref']}, 07:58:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['tsvgx', 07:58:20 INFO - 'tsvgr_opacity', 07:58:20 INFO - 'tart', 07:58:20 INFO - 'tscrollx', 07:58:20 INFO - 'cart', 07:58:20 INFO - 'tsvg_static']}, 07:58:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:58:20 INFO - 'tsvgr_opacity', 07:58:20 INFO - 'tart', 07:58:20 INFO - 'tscrollx', 07:58:20 INFO - 'cart', 07:58:20 INFO - 'tsvg_static']}, 07:58:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:58:20 INFO - 'talos_options': ['--disable-e10s'], 07:58:20 INFO - 'tests': ['tp5o']}, 07:58:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:58:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:58:20 INFO - 'talos_options': ['--disable-e10s', 07:58:20 INFO - '--xperf_path', 07:58:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:58:20 INFO - 'tests': ['tp5n']}, 07:58:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:58:20 INFO - 'talos_options': ['--xperf_path', 07:58:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:58:20 INFO - 'tests': ['tp5n']}}, 07:58:20 INFO - 'talos.zip': {'path': '', 07:58:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:58:20 INFO - Running post-action listener: _resource_record_post_action 07:58:20 INFO - [mozharness: 2017-05-11 14:58:20.375416Z] Finished populate-webroot step (success) 07:58:20 INFO - [mozharness: 2017-05-11 14:58:20.375542Z] Running create-virtualenv step. 07:58:20 INFO - Running pre-action listener: _resource_record_pre_action 07:58:20 INFO - Running main action method: create_virtualenv 07:58:20 INFO - Creating virtualenv /builds/slave/test/build/venv 07:58:20 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:58:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:58:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:58:20 INFO - Using real prefix '/tools/python27' 07:58:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:58:21 INFO - Installing distribute.............................................................................................................................................................................................done. 07:58:24 INFO - Installing pip.................done. 07:58:24 INFO - Return code: 0 07:58:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:58:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:58:24 INFO - Reading from file tmpfile_stdout 07:58:24 INFO - Output received: 07:58:24 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:58:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:58:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:58:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:58:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:58:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:58:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:24 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:58:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:58:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:58:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 07:58:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:58:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:58:24 INFO - 'HOME': '/Users/cltbld', 07:58:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:58:24 INFO - 'LOGNAME': 'cltbld', 07:58:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:24 INFO - 'MOZ_NO_REMOTE': '1', 07:58:24 INFO - 'NO_EM_RESTART': '1', 07:58:24 INFO - 'PAGER': '/bin/cat', 07:58:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:58:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:24 INFO - 'PWD': '/builds/slave/test', 07:58:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:58:24 INFO - 'SHELL': '/bin/bash', 07:58:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 07:58:24 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 07:58:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:58:24 INFO - 'USER': 'cltbld', 07:58:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:58:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:58:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:24 INFO - 'XPC_FLAGS': '0x0', 07:58:24 INFO - 'XPC_SERVICE_NAME': '0', 07:58:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:58:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:58:24 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:58:24 INFO - Cleaning up... 07:58:24 INFO - Return code: 0 07:58:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:58:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:58:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:58:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:58:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:58:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:58:24 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:58:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:58:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:58:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 07:58:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:58:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:58:24 INFO - 'HOME': '/Users/cltbld', 07:58:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:58:24 INFO - 'LOGNAME': 'cltbld', 07:58:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:58:24 INFO - 'MOZ_NO_REMOTE': '1', 07:58:24 INFO - 'NO_EM_RESTART': '1', 07:58:24 INFO - 'PAGER': '/bin/cat', 07:58:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:58:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:58:24 INFO - 'PWD': '/builds/slave/test', 07:58:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:58:24 INFO - 'SHELL': '/bin/bash', 07:58:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 07:58:24 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 07:58:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:58:24 INFO - 'USER': 'cltbld', 07:58:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:58:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:58:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:58:24 INFO - 'XPC_FLAGS': '0x0', 07:58:24 INFO - 'XPC_SERVICE_NAME': '0', 07:58:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:58:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:58:24 INFO - Downloading/unpacking psutil>=3.1.1 07:58:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:58:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:58:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:58:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:58:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:58:24 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 08:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:00:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:57 INFO - Installing collected packages: psutil 08:00:57 INFO - Running setup.py install for psutil 08:00:57 INFO - building 'psutil._psutil_osx' extension 08:00:57 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 08:00:58 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 08:00:58 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 08:00:58 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 08:00:58 INFO - building 'psutil._psutil_posix' extension 08:00:58 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 08:00:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:00:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:00:58 INFO - ^ 08:00:58 INFO - 1 warning generated. 08:00:58 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 08:00:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:58 INFO - Successfully installed psutil 08:00:58 INFO - Cleaning up... 08:00:58 INFO - Return code: 0 08:00:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:59 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:00:59 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 08:00:59 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 08:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:00:59 INFO - 'HOME': '/Users/cltbld', 08:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:00:59 INFO - 'LOGNAME': 'cltbld', 08:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:59 INFO - 'MOZ_NO_REMOTE': '1', 08:00:59 INFO - 'NO_EM_RESTART': '1', 08:00:59 INFO - 'PAGER': '/bin/cat', 08:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:59 INFO - 'PWD': '/builds/slave/test', 08:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:00:59 INFO - 'SHELL': '/bin/bash', 08:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:00:59 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:00:59 INFO - 'USER': 'cltbld', 08:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:59 INFO - 'XPC_FLAGS': '0x0', 08:00:59 INFO - 'XPC_SERVICE_NAME': '0', 08:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:00:59 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 08:00:59 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 08:00:59 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 08:00:59 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 08:00:59 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 08:00:59 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 08:03:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:03:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:03:32 INFO - Installing collected packages: mozsystemmonitor 08:03:32 INFO - Running setup.py install for mozsystemmonitor 08:03:32 INFO - Successfully installed mozsystemmonitor 08:03:32 INFO - Cleaning up... 08:03:32 INFO - Return code: 0 08:03:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:03:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:03:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:03:33 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:03:33 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 08:03:33 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 08:03:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:03:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:03:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:03:33 INFO - 'HOME': '/Users/cltbld', 08:03:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:03:33 INFO - 'LOGNAME': 'cltbld', 08:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:03:33 INFO - 'MOZ_NO_REMOTE': '1', 08:03:33 INFO - 'NO_EM_RESTART': '1', 08:03:33 INFO - 'PAGER': '/bin/cat', 08:03:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:03:33 INFO - 'PWD': '/builds/slave/test', 08:03:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:03:33 INFO - 'SHELL': '/bin/bash', 08:03:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:03:33 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:03:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:03:33 INFO - 'USER': 'cltbld', 08:03:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:03:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:03:33 INFO - 'XPC_FLAGS': '0x0', 08:03:33 INFO - 'XPC_SERVICE_NAME': '0', 08:03:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:03:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:03:34 INFO - Downloading/unpacking jsonschema==2.5.1 08:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:03:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:03:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:03:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:06:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:06:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:06:06 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 08:06:06 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 08:06:06 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 08:06:06 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 08:06:06 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 08:06:06 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 08:08:37 INFO - Downloading functools32-3.2.3-2.tar.gz 08:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:08:37 INFO - warning: no files found matching '*.txt' 08:08:37 INFO - no previously-included directories found matching 'build' 08:08:37 INFO - no previously-included directories found matching 'dist' 08:08:37 INFO - no previously-included directories found matching '.git*' 08:08:37 INFO - Installing collected packages: jsonschema, functools32 08:08:37 INFO - Running setup.py install for functools32 08:08:37 INFO - warning: no files found matching '*.txt' 08:08:37 INFO - no previously-included directories found matching 'build' 08:08:37 INFO - no previously-included directories found matching 'dist' 08:08:37 INFO - no previously-included directories found matching '.git*' 08:08:37 INFO - Successfully installed jsonschema functools32 08:08:37 INFO - Cleaning up... 08:08:37 INFO - Return code: 0 08:08:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:08:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:08:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:38 INFO - 'HOME': '/Users/cltbld', 08:08:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:38 INFO - 'LOGNAME': 'cltbld', 08:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:38 INFO - 'MOZ_NO_REMOTE': '1', 08:08:38 INFO - 'NO_EM_RESTART': '1', 08:08:38 INFO - 'PAGER': '/bin/cat', 08:08:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:38 INFO - 'PWD': '/builds/slave/test', 08:08:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:38 INFO - 'SHELL': '/bin/bash', 08:08:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:08:38 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:08:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:38 INFO - 'USER': 'cltbld', 08:08:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:38 INFO - 'XPC_FLAGS': '0x0', 08:08:38 INFO - 'XPC_SERVICE_NAME': '0', 08:08:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:08:39 INFO - Cleaning up... 08:08:39 INFO - Return code: 0 08:08:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:08:39 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')]} 08:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:08:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:39 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')]} 08:08:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:08:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:08:39 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:08:39 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 08:08:39 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 08:08:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:08:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:08:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:08:39 INFO - 'HOME': '/Users/cltbld', 08:08:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:08:39 INFO - 'LOGNAME': 'cltbld', 08:08:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:08:39 INFO - 'MOZ_NO_REMOTE': '1', 08:08:39 INFO - 'NO_EM_RESTART': '1', 08:08:39 INFO - 'PAGER': '/bin/cat', 08:08:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:08:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:08:39 INFO - 'PWD': '/builds/slave/test', 08:08:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:08:39 INFO - 'SHELL': '/bin/bash', 08:08:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:08:39 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:08:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:08:39 INFO - 'USER': 'cltbld', 08:08:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:08:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:08:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:08:39 INFO - 'XPC_FLAGS': '0x0', 08:08:39 INFO - 'XPC_SERVICE_NAME': '0', 08:08:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:08:39 INFO - Downloading/unpacking blobuploader==1.2.4 08:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:08:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:08:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:08:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:11:12 INFO - Downloading blobuploader-1.2.4.tar.gz 08:11:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:11:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:11:12 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 08:11:12 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 08:11:12 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 08:11:12 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 08:11:12 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 08:11:12 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 08:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:13:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:13:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:13:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:13:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:13:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:13 INFO - Downloading docopt-0.6.1.tar.gz 08:16:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:16:13 INFO - Installing collected packages: blobuploader, requests, docopt 08:16:13 INFO - Running setup.py install for blobuploader 08:16:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:16:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:16:14 INFO - Running setup.py install for requests 08:16:14 INFO - Running setup.py install for docopt 08:16:14 INFO - Successfully installed blobuploader requests docopt 08:16:14 INFO - Cleaning up... 08:16:14 INFO - Return code: 0 08:16:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:14 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')]} 08:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:14 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')]} 08:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:15 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:16:15 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 08:16:15 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 08:16:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:16:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:15 INFO - 'HOME': '/Users/cltbld', 08:16:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:15 INFO - 'LOGNAME': 'cltbld', 08:16:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:15 INFO - 'MOZ_NO_REMOTE': '1', 08:16:15 INFO - 'NO_EM_RESTART': '1', 08:16:15 INFO - 'PAGER': '/bin/cat', 08:16:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:15 INFO - 'PWD': '/builds/slave/test', 08:16:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:15 INFO - 'SHELL': '/bin/bash', 08:16:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:16:15 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:16:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:15 INFO - 'USER': 'cltbld', 08:16:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:15 INFO - 'XPC_FLAGS': '0x0', 08:16:15 INFO - 'XPC_SERVICE_NAME': '0', 08:16:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-OnH5RU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-uAmfr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-M5jmNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-aoHAOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-8jRUsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-DKtfav-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-1VE46c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-JDpAvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-pWHq7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-6LPHSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-c02fEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-uzxKBP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-gf42UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-tc2nIq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-UukrpX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-_aoeba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-KX13lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:16:18 INFO - Running setup.py install for manifestparser 08:16:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:16:18 INFO - Running setup.py install for mozcrash 08:16:18 INFO - Running setup.py install for mozdebug 08:16:18 INFO - Running setup.py install for mozdevice 08:16:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:16:18 INFO - Running setup.py install for mozfile 08:16:18 INFO - Running setup.py install for mozhttpd 08:16:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:16:18 INFO - Running setup.py install for mozinfo 08:16:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Running setup.py install for mozInstall 08:16:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Running setup.py install for mozleak 08:16:19 INFO - Running setup.py install for mozlog 08:16:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Running setup.py install for moznetwork 08:16:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Running setup.py install for mozprocess 08:16:19 INFO - Running setup.py install for mozprofile 08:16:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:16:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:16:20 INFO - Running setup.py install for mozrunner 08:16:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:16:20 INFO - Running setup.py install for mozscreenshot 08:16:20 INFO - Running setup.py install for moztest 08:16:20 INFO - Running setup.py install for mozversion 08:16:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:16:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:16:20 INFO - Cleaning up... 08:16:20 INFO - Return code: 0 08:16:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:20 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')]} 08:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:20 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')]} 08:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:20 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:16:20 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 08:16:20 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 08:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:20 INFO - 'HOME': '/Users/cltbld', 08:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:20 INFO - 'LOGNAME': 'cltbld', 08:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:20 INFO - 'MOZ_NO_REMOTE': '1', 08:16:20 INFO - 'NO_EM_RESTART': '1', 08:16:20 INFO - 'PAGER': '/bin/cat', 08:16:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:20 INFO - 'PWD': '/builds/slave/test', 08:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:20 INFO - 'SHELL': '/bin/bash', 08:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:16:20 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:20 INFO - 'USER': 'cltbld', 08:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:20 INFO - 'XPC_FLAGS': '0x0', 08:16:20 INFO - 'XPC_SERVICE_NAME': '0', 08:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:20 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-YjyDki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-I4SUkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-jGiHl3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-ERvdOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-RLRwQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-aitiK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-hy_AV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-W0Saea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-crWmSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-W106at-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:21 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)) 08:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-XylJe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:22 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)) 08:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-lII11m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:22 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)) 08:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-8MQ8nm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:22 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)) 08:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-ozIrpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:22 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)) 08:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-GabMmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:22 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)) 08:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-BiYb8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:22 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)) 08:16:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-tDzMX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:22 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)) 08:16:22 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)) 08:16:22 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)) 08:16:22 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)) 08:16:22 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)) 08:16:22 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)) 08:16:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:18:55 INFO - Downloading blessings-1.6.tar.gz 08:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:18:55 INFO - Installing collected packages: blessings 08:18:55 INFO - Running setup.py install for blessings 08:18:55 INFO - Successfully installed blessings 08:18:55 INFO - Cleaning up... 08:18:55 INFO - Return code: 0 08:18:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:18:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:18:56 INFO - Reading from file tmpfile_stdout 08:18:56 INFO - Current package versions: 08:18:56 INFO - blessings == 1.6 08:18:56 INFO - blobuploader == 1.2.4 08:18:56 INFO - docopt == 0.6.1 08:18:56 INFO - functools32 == 3.2.3-2 08:18:56 INFO - jsonschema == 2.5.1 08:18:56 INFO - manifestparser == 1.1 08:18:56 INFO - mozInstall == 1.12 08:18:56 INFO - mozcrash == 1.0 08:18:56 INFO - mozdebug == 0.1 08:18:56 INFO - mozdevice == 0.50 08:18:56 INFO - mozfile == 1.2 08:18:56 INFO - mozhttpd == 0.7 08:18:56 INFO - mozinfo == 0.9 08:18:56 INFO - mozleak == 0.1 08:18:56 INFO - mozlog == 3.4 08:18:56 INFO - moznetwork == 0.27 08:18:56 INFO - mozprocess == 0.25 08:18:56 INFO - mozprofile == 0.28 08:18:56 INFO - mozrunner == 6.13 08:18:56 INFO - mozscreenshot == 0.1 08:18:56 INFO - mozsystemmonitor == 0.3 08:18:56 INFO - moztest == 0.8 08:18:56 INFO - mozversion == 1.4 08:18:56 INFO - psutil == 3.1.1 08:18:56 INFO - requests == 1.2.3 08:18:56 INFO - wsgiref == 0.1.2 08:18:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:18:56 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')]} 08:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:56 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')]} 08:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:57 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:18:57 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 08:18:57 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 08:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:57 INFO - 'HOME': '/Users/cltbld', 08:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:57 INFO - 'LOGNAME': 'cltbld', 08:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:57 INFO - 'MOZ_NO_REMOTE': '1', 08:18:57 INFO - 'NO_EM_RESTART': '1', 08:18:57 INFO - 'PAGER': '/bin/cat', 08:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:57 INFO - 'PWD': '/builds/slave/test', 08:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:57 INFO - 'SHELL': '/bin/bash', 08:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:18:57 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:57 INFO - 'USER': 'cltbld', 08:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:57 INFO - 'XPC_FLAGS': '0x0', 08:18:57 INFO - 'XPC_SERVICE_NAME': '0', 08:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 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)) 08:18:57 INFO - Cleaning up... 08:18:57 INFO - Return code: 0 08:18:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:18:57 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 0x103b58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103b5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdd88d92000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, '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.TXuC28l5Up/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.DUuSqT7anv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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 08:18:57 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 08:18:57 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 08:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:18:57 INFO - 'HOME': '/Users/cltbld', 08:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:18:57 INFO - 'LOGNAME': 'cltbld', 08:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:18:57 INFO - 'MOZ_NO_REMOTE': '1', 08:18:57 INFO - 'NO_EM_RESTART': '1', 08:18:57 INFO - 'PAGER': '/bin/cat', 08:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:18:57 INFO - 'PWD': '/builds/slave/test', 08:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:18:57 INFO - 'SHELL': '/bin/bash', 08:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:18:57 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:18:57 INFO - 'USER': 'cltbld', 08:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:18:57 INFO - 'XPC_FLAGS': '0x0', 08:18:57 INFO - 'XPC_SERVICE_NAME': '0', 08:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:18:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:18:57 INFO - Cleaning up... 08:18:57 INFO - Return code: 0 08:18:57 INFO - Running post-action listener: _resource_record_post_action 08:18:57 INFO - Running post-action listener: _start_resource_monitoring 08:18:57 INFO - Starting resource monitoring. 08:18:57 INFO - [mozharness: 2017-05-11 15:18:57.625655Z] Finished create-virtualenv step (success) 08:18:57 INFO - [mozharness: 2017-05-11 15:18:57.626613Z] Running install step. 08:18:57 INFO - Running pre-action listener: _resource_record_pre_action 08:18:57 INFO - Running main action method: install 08:18:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:18:57 INFO - Reading from file tmpfile_stdout 08:18:57 INFO - Detecting whether we're running mozinstall >=1.0... 08:18:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:18:57 INFO - Reading from file tmpfile_stdout 08:18:57 INFO - Output received: 08:18:57 INFO - Usage: mozinstall [options] installer 08:18:57 INFO - Options: 08:18:57 INFO - -h, --help show this help message and exit 08:18:57 INFO - -d DEST, --destination=DEST 08:18:57 INFO - Directory to install application into. [default: 08:18:57 INFO - "/builds/slave/test"] 08:18:57 INFO - --app=APP Application being installed. [default: firefox] 08:18:57 INFO - mkdir: /builds/slave/test/build/application 08:18:57 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'] 08:18:57 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 08:19:18 INFO - Reading from file tmpfile_stdout 08:19:18 INFO - Output received: 08:19:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:19:18 INFO - Running post-action listener: _resource_record_post_action 08:19:18 INFO - [mozharness: 2017-05-11 15:19:18.623976Z] Finished install step (success) 08:19:18 INFO - [mozharness: 2017-05-11 15:19:18.624141Z] Running run-tests step. 08:19:18 INFO - Running pre-action listener: _resource_record_pre_action 08:19:18 INFO - Running main action method: run_tests 08:19:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:19:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:19:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:19:18 INFO - 2017-05-11 08:19:18.639 screenresolution[1636:8480] starting screenresolution argv=screenresolution get 08:19:18 INFO - 2017-05-11 08:19:18.641 screenresolution[1636:8480] Display 0: 1600x1200x32@60 08:19:18 INFO - 2017-05-11 08:19:18.647 screenresolution[1637:8483] starting screenresolution argv=screenresolution list 08:19:18 INFO - Available Modes on Display 0 08:19:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:19:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:19:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:19:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:19:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:19:18 INFO - Intel Iris: 08:19:18 INFO - Chipset Model: Intel Iris 08:19:18 INFO - Type: GPU 08:19:18 INFO - Bus: Built-In 08:19:18 INFO - VRAM (Dynamic, Max): 1536 MB 08:19:18 INFO - Vendor: Intel (0x8086) 08:19:18 INFO - Device ID: 0x0a2e 08:19:18 INFO - Revision ID: 0x0009 08:19:18 INFO - Displays: 08:19:18 INFO - Display: 08:19:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:19:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:19:18 INFO - Display Serial Number: 1600x1200 60 08:19:18 INFO - Main Display: Yes 08:19:18 INFO - Mirror: Off 08:19:18 INFO - Online: Yes 08:19:18 INFO - Rotation: Supported 08:19:18 INFO - Return code: 0 08:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:19:18 INFO - Python 2.7.3 08:19:18 INFO - Return code: 0 08:19:18 INFO - grabbing minidump binary from tooltool 08:19:18 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')]} 08:19:18 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 0x103869d30>, '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 0x10381cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10385dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:19:18 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 08:19:18 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 08:19:18 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 08:19:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:19:19 INFO - Return code: 0 08:19:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:19:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:19:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:19:19 INFO - ENV: RUST_BACKTRACE is now 1 08:19:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:19:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:19:19 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/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 08:19:19 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/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0073 --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 08:19:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TXuC28l5Up/Render', 08:19:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:19:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:19:19 INFO - 'HOME': '/Users/cltbld', 08:19:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:19:19 INFO - 'LOGNAME': 'cltbld', 08:19:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:19:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:19:19 INFO - 'MOZ_NO_REMOTE': '1', 08:19:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:19:19 INFO - 'NO_EM_RESTART': '1', 08:19:19 INFO - 'PAGER': '/bin/cat', 08:19:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:19:19 INFO - 'PWD': '/builds/slave/test', 08:19:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:19:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:19:19 INFO - 'RUST_BACKTRACE': '1', 08:19:19 INFO - 'SHELL': '/bin/bash', 08:19:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DUuSqT7anv/Listeners', 08:19:19 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 08:19:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:19:19 INFO - 'USER': 'cltbld', 08:19:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:19:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:19:19 INFO - 'XPC_FLAGS': '0x0', 08:19:19 INFO - 'XPC_SERVICE_NAME': '0', 08:19:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:19:19 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/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--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 08:19:19 INFO - mozversion application_buildid: 20170511063100 08:19:19 INFO - mozversion application_changeset: 4c580a771776f77c667fd457e2915568c2fcd0a7 08:19:19 INFO - mozversion application_display_name: Nightly 08:19:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:19:19 INFO - mozversion application_name: Firefox 08:19:19 INFO - mozversion application_remotingname: firefox 08:19:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:19:19 INFO - mozversion application_vendor: Mozilla 08:19:19 INFO - mozversion application_version: 55.0a1 08:19:19 INFO - mozversion platform_buildid: 20170511063100 08:19:19 INFO - mozversion platform_changeset: 4c580a771776f77c667fd457e2915568c2fcd0a7 08:19:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:19:19 INFO - mozversion platform_version: 55.0a1 08:19:19 INFO - using testdate: 1494515959 08:19:19 INFO - actual date: 1494515959 08:19:19 INFO - starting webserver on 'localhost:49256' 08:19:19 INFO - SUITE-START | Running 2 tests 08:19:19 INFO - TEST-START | dromaeo_css 08:19:19 INFO - operating with platform_type : mac_ 08:19:19 INFO - Initialising browser for dromaeo_css test... 08:19:19 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpDg4ZCv/profile http://localhost:49256/getInfo.html) 08:19:21 INFO - PID 1647 | 2017-05-11 08:19:21.695 plugin-container[1649:8669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c43, name = 'com.apple.tsm.portname' 08:19:21 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:21 INFO - PID 1647 | 2017-05-11 08:19:21.698 plugin-container[1649:8669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 08:19:21 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:21 INFO - PID 1647 | 2017-05-11 08:19:21.698 plugin-container[1649:8669] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:19:21 INFO - PID 1647 | __metrics Screen width/height:1600/1200 08:19:21 INFO - PID 1647 | colorDepth:24 08:19:21 INFO - PID 1647 | Browser inner width/height: 1024/691 08:19:21 INFO - PID 1647 | __metrics 08:19:22 INFO - TEST-INFO | 1647: exit 0 08:19:22 INFO - Browser initialized. 08:19:22 INFO - Running cycle 1/1 for dromaeo_css test... 08:19:22 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpDg4ZCv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:19:25 INFO - PID 1652 | 2017-05-11 08:19:25.078 plugin-container[1653:8837] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:19:25 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:25 INFO - PID 1652 | 2017-05-11 08:19:25.078 plugin-container[1653:8837] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:19:25 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:25 INFO - PID 1652 | 2017-05-11 08:19:25.078 plugin-container[1653:8837] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:19:25 INFO - PID 1652 | 2017-05-11 08:19:25.606 plugin-container[1654:8881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 08:19:25 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:25 INFO - PID 1652 | 2017-05-11 08:19:25.607 plugin-container[1654:8881] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x951b, name = 'com.apple.CFPasteboardClient' 08:19:25 INFO - PID 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:19:25 INFO - PID 1652 | 2017-05-11 08:19:25.607 plugin-container[1654:8881] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:19:34 INFO - PID 1652 | RSS: Main: 228564992 08:19:34 INFO - PID 1652 | 08:22:38 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49256/tests/dromaeo/cssquery-ext.html) 08:22:40 INFO - PID 1652 | RSS: Main: 256024576 08:22:40 INFO - PID 1652 | 08:25:42 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-ext.html (next: http://localhost:49256/tests/dromaeo/cssquery-jquery.html) 08:25:42 INFO - PID 1652 | RSS: Main: 260714496 08:25:42 INFO - PID 1652 | 08:28:44 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49256/tests/dromaeo/cssquery-mootools.html) 08:28:45 INFO - PID 1652 | RSS: Main: 268484608 08:28:45 INFO - PID 1652 | 08:31:47 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49256/tests/dromaeo/cssquery-prototype.html) 08:31:47 INFO - PID 1652 | RSS: Main: 263372800 08:31:47 INFO - PID 1652 | 08:34:50 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49256/tests/dromaeo/cssquery-yui.html) 08:34:51 INFO - PID 1652 | RSS: Main: 266874880 08:34:51 INFO - PID 1652 | 08:37:52 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49256/tests/dromaeo/cssquery-yui.html (next: http://localhost:49256/tests/dromaeo/cssquery-dojo.html) 08:37:53 INFO - PID 1652 | RSS: Main: 265732096 08:37:53 INFO - PID 1652 | 08:37:53 INFO - PID 1652 | __start_tp_report 08:37:53 INFO - PID 1652 | _x_x_mozilla_page_load 08:37:53 INFO - PID 1652 | _x_x_mozilla_page_load_details 08:37:53 INFO - PID 1652 | |i|pagename|runs| 08:37:53 INFO - PID 1652 | |0;dojo.html;3156.558081868538;3504.56192975878;3635.927281454369;3668.5781135169464;3686.3548878946194;5291.841244762653;6221.377862213776;6291.685415729206;6323.241211054674;6355.459785918202;6286.214223222098;6299.464545513637;6318.620882747027;6326.905096423558;6349.047642853563;5495.230667706524;5536.612437129403;5546.500814926656;5582.385937546866;5591.161325801142;5582.944170558283;5664.858378540549;5666.263385759846;5667.376588575274;5677.772889084432;5447.046766815792;5532.585056120807;5586.776528938837;5625.662460252398;5669.348024977139;5525.502704756591;5591.189277554732;5601.579881508903;5612.747426365823;5643.351014633287;5527.2538207341895;5529.253550770634;5571.387147413781;5600.6079574429805;5602;5353.6787792732175;5617.848341090067;6322.209723784527;6322.272938612073;6332.1135041094285;6523.575967562093;6595.241547222083;6676.666166691694;6769.153855768029;6779.35596118368;5312.5218730314455;5648.293963254593;6841.794746157623;6880.793576192672;6910.378065974087;6256.906146407808;6395.744170233185;6401.679916004181;6406.871862562769;6416.069669897839;6766.357196066366;6907.240203577602;6990.825229369307;7102.928970710227;7141.14306283239;5251.737413129405;6607.57050791697;6614.239362473281;6677.332266773284;6693.096431981718;6043.3050199227655;6469.741210351627;6862.451003919675;6865.313468653195;6892.655367231619;6442.838929026718;6563.949768037091;6585.539012269095;6708.597484150967;6750.122484077038;6632.568883022588;6710;6727.562708423937;6744.190697116279;6753.797386078426;5311.468853114735;5315;5353.7590808413715;5366.409694933554;5388.67667939917;4721.079389519079;4780.976095119502;4803.135435621552;4813.518648135228;4826.034793041335;6330.778422755181;6364.459020983176;6385.169927909342;6416.550841441148;6435.22777266731;6888.207856096513;6899.241083480813;6913.10129683138;6914.827129321707;6928.515003949675;6374.139491168726;6376.266729326094;6376.7130479128555;6395.968020159962;6415.775447859302;5565.638233514889;5850.9414905851245;5919.319278283053;5926.496247818897;5948.256467941507;7030.753923612649;7046.365827075588;7116.857662846817;7131.429485641032;7174.354308112295;6614.603123812587;6742.9662851685425;6948.861022779415;6961.408280296231;6980.371766541036;5735.369109398046;7084.185318688211;7139.214686384597;7153.3919616833155;7170.498065135391;6498.64257465825;6808.65956702153;6809.93190068093;6828.351306625863;6832.385085342343;6662.267150613332;6694.196696396269;6791.93208067913;6870;6915.757948471779;6694.799156025327;6715.127033485615;6718.798436046926;6719.764808231688;6729.461643068641;6694.83262918431;6726.3609957054005;6731.495137864582;6733.461323094239;6746.4265537429865;6229.2524897012645;6348.77779277723;6405.93594064072;6423.389777971085;6505.642189679424;6645.169353830771;6648.002799580102;6698.765543205965;6711.395974362331;6716.83207919806;6482.54622176462;6489.8702025960165;6492.415682588589;6511.120998665119;6512.251091124582 08:37:53 INFO - PID 1652 | |1;ext.html;804.899387576553;1190.7975644140495;1243.7512497500497;1253.9435725392357;1255.3785875991387;35188.29623407534;35882.64117358826;35908.82045589775;35910.82044589776;35961.20193990303;30524;30541.694583054163;36686;37098.81450592743;37103.25793484136;7219.061522002158;7981.600919953994;8043;8221.876671849926;8229.135940726228;8245.876311855327;8329.541875196861;8379.036410812774;8387.538685372301;8396.874046889301;8344.040435349953;8346.916530834706;8428.831423371528;8440.62017209224;8443.15568443157;8399.244068033875;8401.53791541468;8425.11536288693;8433.662653493853;8465.984081910148;8193.13972032934;8235;8285.461445006054;8290.08809031006;8335.458195217292;28777.856110719313;35875.82062089673;35918;35924.28151436957;35990.64009359899;3596.5684117905757;3629.7096232301615;3636.381815091441;3643.234920666663;3646.762960407565;29789.957351492594;29833.55249671235;29840.55239171414;29866;29869.40261194764;3108.1141874565865;3134.529820526939;3155.195425166568;3155.5739975103525;3161.4783560712453;242238.99999999822;275257;275762;276181;276666;37999.810000949816;46174;46376.53623463722;46639.06721865544;46812;47964;48465.33440660797;48468.5153148464;48558;48804;13876.722465550632;13889.097208681404;14006.789898151535;14007;14024.439022439192;11204;11262.831057534144;11391.943040284745;11403.258788178908;11430.942845285887;12963.611091667264;13041.934790325988;13055.21668699881;13100.803487947689;13177;5390.8921821563345;5525.972370138204;5797;5822.679752613562;5891.675957822275;1003.3678782367062;1008.2337423558002;1018.221060888421;1045.7960697663882;1048.5438834107188;23393;24457.633135502627;24606.138785142433;24628.261152165465;24717.752822471906;1097.6158344579333;1114.2256131988352;1135.4039129456935;1143.2226086261421;1159.1654009113424;11314.321140731583;12402.503899844085;15836.495532924353;16053.438129665406;16466.6706665866;339849;342656;342945;343405;344260;53795;54150.45849541533;54154.729226353615;54263.72868135634;54533.90932181334;1419.5386499387535;1456.4247122386598;1490.1729540105346;1502.6018105202131;1517.1276516003343;29231;29247;29698.40603187881;30118;30180.09459716212;32739.508907366886;36632;36676.26647467089;37730;37899.81050094732;293642;296873;297429;297747;297981;32956.17609559684;34034;35455.64544354523;35655;35968;42483.15033699247;42700;45012;45048.77475612601;45189;23399.532009360057;25950.610740838525;30088.54867177038;31169.8441507791;31208.21979450532;23095.307140785804;30254.697453026067;31004.534931975588;31148.532772007984;31390;37299;37410;37414.06464838401;37415.62584374121;37472.437913430775;29843.403131936804;30874.845625771726;30919.690803091682;31332.686673132976;31502.897398591856;20207.393778186677;24641;25042.248732538053;25119.74880251174;25460 08:37:53 INFO - PID 1652 | |2;jquery.html;423.7164880981239;424.7536428871251;429.96990210685254;442.73214705103413;451.7831440908366;21116.89441552794;21467.67798483022;22066.88966555167;22156.778432215695;22559.887200563975;20501.692474612857;22389.32832015038;23366.065357385683;23377.883110584422;23692.407689807762;4650.86047418578;5966.985612445895;5974.163617093611;6145.139680444741;6195.380461953813;6000.879982400349;6172.783952561661;6176.876462470748;6182.165407669974;6187.721552530147;5934;5983.2820061592665;6089.421504957044;6134.7546098156245;6158.538109641795;6045.455908968192;6119.571629985903;6128.233970753656;6132.78535251264;6140.478059364959;5194.480551944813;5358.115910874701;5390.272313237741;5418.864528386822;5428.61999660024;27141.728582714313;27297.044603438786;27793.305167370774;28073.438531229465;28115.85942070297;2123.5540536487356;2226.6214743493647;2227.0312414099917;2234.6871437998693;2237.1722462688745;17538.912305438393;17562.824371756247;17583.64832703339;18007.72988405175;18113.728294075598;1989.2639723302325;2042.8876411797346;2107.262458139654;2127.819135373495;2168.5662867426577;140312.29843850818;144183;145247.82128268084;145263;161511;12109.757804843854;12510.81233781494;12616.621501354974;12708.618741437771;12739.108262421541;16096.678066438606;16100.919495402683;16131.677366452604;16146.75779863303;16148;8453;8652.826943461221;8718.43330183536;8776.912230877737;8870.379073464796;8085.595720213966;8088.9595552021865;8126.715564955316;8241.340692744567;8261.17388261169;6606.372394622503;7832.608369581498;7908.881366779503;7950.24472675095;7972.322352600094;1870.4014715290975;2202.7246594175726;2209.3151123151874;2215.4713247858926;2382.761723827638;859.8194379180317;942.7972985807947;948.6821914658667;950.2303134461108;987.5950860147449;20619.8969005157;21154.048067836986;21180.682289765668;21191.68212476814;21247.68128478074;997.5261750668375;1006.5068116622948;1015.7155996321042;1023.7184774187009;1031.041131747467;10285.22860785444;10881.945590271998;10890.564377424973;10932.672019839418;10994.28537145083;490945;533915;534310;545222;546103.269483658;100240;118960.40519797518;124114;124735;126369.36815315865;1065.1744897704186;1154.365099195456;1172.6599286206904;1196.1447564991074;1197.239752756988;16512.83487165113;17592.648147037242;17914.91042544779;18119.003454809586;18343.35798247055;39335.60664393319;50454.2431863515;51015;51300.973980520925;51381.751429011936;69989.65005174941;70027.64986175037;70180.59638807093;71161;72680.54638907086;7026.437884969292;8509.064002959793;8735.1701588349;8736.301095912184;8813.91186088131;13084.476620935056;13251.734965300448;13307.46770129184;13500.392482338122;13624.591262262147;11592.608886933622;11627.76744465089;11734;11841.82237266459;11917.22538035025;9471.19494842946;10642.787144256916;10881.727956801144;11086.778264434504;11087.113030957342;10788.622398216025;11834.994025507584;11920.582779602672;12039.217450865666;12159.817602736142;8465.492070475791;8609.096044915193;8663.826723465369;8752.299816014576;8805.559722014003;9615.51922403891;11412.687540932899;11461.312321260752;11667.824982625098;11737 08:37:53 INFO - PID 1652 | |3;mootools.html;1164.924279921805;1426.2654732812596;1439.4458133618557;1444.248990524927;1447.4282658349946;11287;11321.32072075675;11848.466818993145;11864.22882512637;11902.46438910249;11673.941630291836;11752.294862308267;11805;11810.232334898246;11830.940845295761;2529.089527770006;2553.8850751716122;2564.8842753402328;2566.1275166443406;2572.601246806748;2487.4776296977566;2517.169334119736;2534.6958364996226;2535.277445927911;2539.1113110411393;2478.652988581591;2493.127405408111;2526.5704830178915;2536.3912660961328;2540.2252313044514;2478.9876050619723;2491.8754062296994;2497.650328953948;2497.67530221071;2499.487605040962;2259.017327462559;2366.171839856054;2407.3620490570015;2512.7110382306087;2514.208024472304;11681.357525336103;11710.941445292805;11721.238119522204;11814;11825.526978920832;6579.309172536862;6850.4862135340045;6854.0404343391965;6857.108575885153;6858.862822743567;3641.5630124385243;3936.6850651947775;4216.662666986665;4242.893927651802;4272.829086836523;1097.4732128578328;1110.783397237539;1228.6866848953548;1234.6049264235448;1290.5160564788205;15866.20668966547;16274.023558586523;16397.508074757778;16444.177791110396;16575.922565033354;4607.917139472222;4715.481297057321;5516;5703.287089113861;5705.515031222392;11611.419429028514;11681.88318116825;11725.944664980023;11729.765404691858;11762.294262344287;6468.450181734511;6543.640099794557;6560.179977502812;6560.540762146604;6562.967185164043;5982.820515384546;6102.69486525672;6136.2636483622255;6238.844028899314;6255.655938923403;4254.787260636956;4374.956250437455;4418.889527761831;4432.800523976429;4455.331700244989;1120.815065514181;1208.8549374074992;1245.872485400714;1268.2327192048679;1272.2811611439506;792.7621713485931;794.253401802304;814.5275740070745;820.8809722590191;854.9914500855036;6129.601575897553;6168.167297414881;6377.3303803100935;6392.232932048184;6460.030995350735;567.6168586204295;580.38189328366;586.2496005113462;594.4976494861921;610.3713175429315;3771.6228377162615;3903.316919539069;4279.786010699515;4290.2706539888295;4338.414314067561;12957.546485872948;15068.773968390264;15247.695046099016;15253.466128685443;15255.542333730005;14221.715565688628;14829.036112652642;14904.925475372555;15019.624509387353;15119;7307.780766576905;8221.54781487024;8437.029741579674;8466.788330291669;8505.872411913826;5736.282964629421;6493.837654058686;6602;6692.866142677021;6719.294474080151;12851.678708032374;13045.412956416794;13125.934370328087;13372.465101395836;13575.79636305476;13239.40422681001;13344.866551334362;13592.66018349549;13628.114172578751;13741.924566241001;9190.172884440433;9263;9341.906580934103;9442.008589098321;9460.526973651153;10571.471426428494;10684.57261709554;10686.786264274826;10723.03492685662;10738.946305268424;8702.390832641655;9088.545572721205;9148.954255228682;9205.30960177976;9340.579673914826;8753.47479151253;8835.80716603276;8892.244159246278;9027.638894444412;9086.727398178065;8126.805762443989;8155.918440815516;8200.097989221062;8300.750977470685;8303.377246706401;5355.892882142413;6078.300995385411;6207.81376558704;6208.720607572761;6289.056641503811;6118.082287656887;6161.6919154041225;6259.812205633838;6270.623762574078;6388.169537960035 08:37:53 INFO - PID 1652 | |4;prototype.html;338.6968663046575;382.6364953294371;388.9066624010235;401.61043787526097;410.5976143379487;6270.811875643738;6327.557071005026;6393.968030159848;6445.1943507061615;6463.127477790491;4911.852644420673;5755.6258843175165;6238.937610623892;6260.746131392695;6312.431881130698;4548.18072770891;4558.293464512997;4595.241785105471;4600.5629465200755;4601.447826260854;3475.304939012195;4425.8229670813125;4495.685302028859;4579.221532339494;4588.220002599566;3781.8298176581984;4265.680073994447;4533.659975501834;4548.9090218195615;4554.908901821962;4243.533211346749;4289;4577;4586.2661974083985;4612.1467528507;3462.926492787244;3540.9114772130647;3702.3520883845217;3799.2211596622624;3922.8234729437245;4944.8269310574315;5082.390113186404;5552.861178470571;5830.212921255618;6107.87784244317;5419.59353048519;5444.292242008553;5450.618456708032;5460.788380705821;5491.807786727445;4688.460827004904;5428;6119.357467465897;6701.59790412572;6717.261101278856;3604.8197590120653;3961.3265744823448;4382.189294980439;4400.317950717644;4425.955740442587;5176.8964620707375;6561.671916404209;6584.84765166094;6983.441324693961;7054.611996340199;4099.503681156416;4462.906587885988;4470.687051906335;4484.9103017940115;4489.147062058199;4582.289745089516;4583.793729282191;4586.72479651222;4588.334691469785;4594.264917613166;5220.546343218681;5333.253344531769;5368.785248590013;5395.298611180521;5425.755840987165;5627.155926610959;6736.865262694719;6747.831304217434;6751.392374686302;6777.356151165632;1278.6355888571713;1515.0985163827504;1550.6433520290395;1556.7664850272324;1572.7404978178702;1001.1690297053428;1004.9196064314838;1006.9597216111273;1012.5392946209604;1013.4729940430934;5947;6372.681365931778;6372.74509019634;6378.521610879203;6387.361263873576;5472.534834539029;6992.230854605989;7045.330693584101;7058.6823592938445;7072.328128827753;6351.618902865843;6357.872842543124;6357.872842543124;6374.171357723559;6393.456556192683;3231.2245061185617;3608.3581970203495;3628.618995005539;3628.9455658165148;3675.246574452284;6117.357677443804;6586.769463068769;7263.34629883313;7273.3817625502425;7310.853782924311;6381.3937675920715;6744.482491439485;7125.35871771543;7231.89152162718;7247.710091596383;5999.940000600025;6655.534112612071;6798.932010679929;7230.349268565854;7271.691095602948;6085.69571521431;6711.899321510084;7015.789526314219;7059.682314295972;7100.857982840211;5777.8555536111935;5964.254468191476;6574.948008318646;6736;6856.862862742817;6523;6853.401963968956;7100.254473280231;7138.535995160298;7271.381932535794;3798.9240215196096;3949.5260568731937;4412.757298348622;4452.666050046195;4482.686211965131;5901.822945311647;6321.367863213642;6733.865322693617;6873.312668733087;7047.612381319077;6205.720742566505;6408.93591064102;6768.221654509597;6775.898361524482;6799.558028728117;5492.972535137299;6215.742216581656;6692.531522793358;6746.4940129489505;6783.864322713617;5622.5783066271015;6177.320494745484;6607.57050791697;6627.966860165669;6628.403443690091;6518.979116473908;6608.900866486911;6745.359190877055;6746.8650626988165;6755.560888542229;5581.6371935824045;6058.636481811105;6542.462521307584;6574.178227721535;6611.636360000246 08:37:53 INFO - PID 1652 | |5;yui.html;823.9670413183472;840.1850205300856;853.287504933381;876.0626130040858;883.116883116883;14406.27968601571;15043.774343384857;16360.263788129532;16586.834131658696;16990.895591786542;14297.78553321701;17261.568460788454;17685.91157044216;17917.283308667636;17969.91015044923;980.9264305177122;987.210231814549;997.6009596161557;1001.2140469731262;1003.6988903329009;1017.5929628148725;1019.0675531888313;1019.138827690604;1019.3832731197593;1019.6635110413546;1046.5081411736471;1050.0759331788054;1057.0539367266292;1059.6715018344337;1060.9257351985364;1040.7242080848582;1051.8790339110985;1054.1672079057535;1055.4142450939764;1056.3767377247461;635.3011687144151;773.833625770462;776.8679324514846;778.9727359542445;779.3142035009213;16792;16936.23786929591;17171;17406.042667653273;17414.30342786287;16043.518694439186;16566;16675;16692;16706.41527546542;13788.586342409744;14089.71820563583;14174.716505669932;14198.361073751603;14231.430742770277;3319.133653241808;3335.483000134983;3408.693217610427;3409.062507810347;3410.437277849152;97256.99999999929;98391.50804246005;115753;123829;125190.37404812918;12001.219920705125;12048.397580120958;12050.216735912138;12117.333546655018;12136.514539418324;20137.59724805525;20249;20428.387148385573;20459.38621841347;20490.795092049186;7542.094948606203;7876.251756083163;7914.722984695508;7917.129115797373;7921.009873765779;3383.3909896218424;3956.3669812829808;3961.74248673841;3973;3975.1652153047607;4115.053537686349;4192.832286708497;4193.853215137517;4199.265128602544;4240.85157019509;919.6827094652334;946.1910066892846;948.8671585977969;949.4350861237552;949.8622699708503;647.397919934456;651.4234902111665;651.5797310734549;654.7479220500142;688.6281408039703;11417.31496110236;12001.519939202508;12078.879211207952;12107.757844843054;12139.332336721389;650.817771024114;651.4625434016955;653.9149910511699;665.7869481765788;679.7960611816435;4523.886902827456;5257.973710131501;5279.709615971158;5322;5475.561955043587;113253;115094;116173;118389;118592;80507.58477245692;95235.09529809504;102368;103146.96853031524;104109;60639.99999999912;88081.55959220162;88895.55552222327;89254.10745892588;90227.09772902187;9152.862707059532;10491.895081049397;10769.9461502692;10784.029437350677;11008.504617292081;39435.60564394319;41990.1601967965;42115.94710132271;44312.67061988146;44946.77526612346;64680.38299042561;71220.57558848898;72370;74045;74063;16695.91652041732;16827.495175144766;17058;17106.230219640394;17234.052127133127;17616.55958601045;18394.1722622485;18581.25674972986;18758.624827503645;18855.868647881172;16393.09837958924;16472.446770750554;17102.657946840744;17178.570535736708;17190.65618687594;15911;16279.186040698154;17084.181110117188;17258.309667613154;17615.82384176142;16331.428400005943;16548;16581.834181658513;16814.327426902786;16989.575260618585;11283;11415.315081095161;11512.963833255048;11656.242344247597;11754.471048802654;12079.698007549881;12159.635210943687;12197.817032744339;12331.630051098482;12546.435410406737 08:37:53 INFO - PID 1652 | __end_tp_report 08:37:53 INFO - PID 1652 | __start_cc_report 08:37:53 INFO - PID 1652 | _x_x_mozilla_cycle_collect,414 08:37:53 INFO - PID 1652 | __end_cc_report 08:37:53 INFO - PID 1652 | __startTimestamp1494517073178__endTimestamp 08:37:53 INFO - PID 1652 | 08:37:53 INFO - PID 1652 | ------- Summary: start ------- 08:37:53 INFO - PID 1652 | Number of tests: 6 08:37:53 INFO - PID 1652 | 08:37:53 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:6228.74 Median:6423.39 stddev:759.97 (11.8%) stddev-sans-first:723.08 08:37:53 INFO - PID 1652 | Values: 3156.6 3504.6 3635.9 3668.6 3686.4 5291.8 6221.4 6291.7 6323.2 6355.5 6286.2 6299.5 6318.6 6326.9 6349.0 5495.2 5536.6 5546.5 5582.4 5591.2 5582.9 5664.9 5666.3 5667.4 5677.8 5447.0 5532.6 5586.8 5625.7 5669.3 5525.5 5591.2 5601.6 5612.7 5643.4 5527.3 5529.3 5571.4 5600.6 5602.0 5353.7 5617.8 6322.2 6322.3 6332.1 6523.6 6595.2 6676.7 6769.2 6779.4 5312.5 5648.3 6841.8 6880.8 6910.4 6256.9 6395.7 6401.7 6406.9 6416.1 6766.4 6907.2 6990.8 7102.9 7141.1 5251.7 6607.6 6614.2 6677.3 6693.1 6043.3 6469.7 6862.5 6865.3 6892.7 6442.8 6563.9 6585.5 6708.6 6750.1 6632.6 6710.0 6727.6 6744.2 6753.8 5311.5 5315.0 5353.8 5366.4 5388.7 4721.1 4781.0 4803.1 4813.5 4826.0 6330.8 6364.5 6385.2 6416.6 6435.2 6888.2 6899.2 6913.1 6914.8 6928.5 6374.1 6376.3 6376.7 6396.0 6415.8 5565.6 5850.9 5919.3 5926.5 5948.3 7030.8 7046.4 7116.9 7131.4 7174.4 6614.6 6743.0 6948.9 6961.4 6980.4 5735.4 7084.2 7139.2 7153.4 7170.5 6498.6 6808.7 6809.9 6828.4 6832.4 6662.3 6694.2 6791.9 6870.0 6915.8 6694.8 6715.1 6718.8 6719.8 6729.5 6694.8 6726.4 6731.5 6733.5 6746.4 6229.3 6348.8 6405.9 6423.4 6505.6 6645.2 6648.0 6698.8 6711.4 6716.8 6482.5 6489.9 6492.4 6511.1 6512.3 08:37:53 INFO - PID 1652 | 08:37:53 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:45037.11 Median:3158.53 stddev:79917.08 (2530.2%) stddev-sans-first:80072.26 08:37:53 INFO - PID 1652 | Values: 804.9 1190.8 1243.8 1253.9 1255.4 35188.3 35882.6 35908.8 35910.8 35961.2 30524.0 30541.7 36686.0 37098.8 37103.3 7219.1 7981.6 8043.0 8221.9 8229.1 8245.9 8329.5 8379.0 8387.5 8396.9 8344.0 8346.9 8428.8 8440.6 8443.2 8399.2 8401.5 8425.1 8433.7 8466.0 8193.1 8235.0 8285.5 8290.1 8335.5 28777.9 35875.8 35918.0 35924.3 35990.6 3596.6 3629.7 3636.4 3643.2 3646.8 29790.0 29833.6 29840.6 29866.0 29869.4 3108.1 3134.5 3155.2 3155.6 3161.5 242239.0 275257.0 275762.0 276181.0 276666.0 37999.8 46174.0 46376.5 46639.1 46812.0 47964.0 48465.3 48468.5 48558.0 48804.0 13876.7 13889.1 14006.8 14007.0 14024.4 11204.0 11262.8 11391.9 11403.3 11430.9 12963.6 13041.9 13055.2 13100.8 13177.0 5390.9 5526.0 5797.0 5822.7 5891.7 1003.4 1008.2 1018.2 1045.8 1048.5 23393.0 24457.6 24606.1 24628.3 24717.8 1097.6 1114.2 1135.4 1143.2 1159.2 11314.3 12402.5 15836.5 16053.4 16466.7 339849.0 342656.0 342945.0 343405.0 344260.0 53795.0 54150.5 54154.7 54263.7 54533.9 1419.5 1456.4 1490.2 1502.6 1517.1 29231.0 29247.0 29698.4 30118.0 30180.1 32739.5 36632.0 36676.3 37730.0 37899.8 293642.0 296873.0 297429.0 297747.0 297981.0 32956.2 34034.0 35455.6 35655.0 35968.0 42483.2 42700.0 45012.0 45048.8 45189.0 23399.5 25950.6 30088.5 31169.8 31208.2 23095.3 30254.7 31004.5 31148.5 31390.0 37299.0 37410.0 37414.1 37415.6 37472.4 29843.4 30874.8 30919.7 31332.7 31502.9 20207.4 24641.0 25042.2 25119.7 25460.0 08:37:53 INFO - PID 1652 | 08:37:53 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:33991.12 Median:12313.25 stddev:89662.18 (728.2%) stddev-sans-first:89878.28 08:37:53 INFO - PID 1652 | Values: 423.7 424.8 430.0 442.7 451.8 21116.9 21467.7 22066.9 22156.8 22559.9 20501.7 22389.3 23366.1 23377.9 23692.4 4650.9 5967.0 5974.2 6145.1 6195.4 6000.9 6172.8 6176.9 6182.2 6187.7 5934.0 5983.3 6089.4 6134.8 6158.5 6045.5 6119.6 6128.2 6132.8 6140.5 5194.5 5358.1 5390.3 5418.9 5428.6 27141.7 27297.0 27793.3 28073.4 28115.9 2123.6 2226.6 2227.0 2234.7 2237.2 17538.9 17562.8 17583.6 18007.7 18113.7 1989.3 2042.9 2107.3 2127.8 2168.6 140312.3 144183.0 145247.8 145263.0 161511.0 12109.8 12510.8 12616.6 12708.6 12739.1 16096.7 16100.9 16131.7 16146.8 16148.0 8453.0 8652.8 8718.4 8776.9 8870.4 8085.6 8089.0 8126.7 8241.3 8261.2 6606.4 7832.6 7908.9 7950.2 7972.3 1870.4 2202.7 2209.3 2215.5 2382.8 859.8 942.8 948.7 950.2 987.6 20619.9 21154.0 21180.7 21191.7 21247.7 997.5 1006.5 1015.7 1023.7 1031.0 10285.2 10881.9 10890.6 10932.7 10994.3 490945.0 533915.0 534310.0 545222.0 546103.3 100240.0 118960.4 124114.0 124735.0 126369.4 1065.2 1154.4 1172.7 1196.1 1197.2 16512.8 17592.6 17914.9 18119.0 18343.4 39335.6 50454.2 51015.0 51301.0 51381.8 69989.7 70027.6 70180.6 71161.0 72680.5 7026.4 8509.1 8735.2 8736.3 8813.9 13084.5 13251.7 13307.5 13500.4 13624.6 11592.6 11627.8 11734.0 11841.8 11917.2 9471.2 10642.8 10881.7 11086.8 11087.1 10788.6 11835.0 11920.6 12039.2 12159.8 8465.5 8609.1 8663.8 8752.3 8805.6 9615.5 11412.7 11461.3 11667.8 11737.0 08:37:53 INFO - PID 1652 | 08:37:53 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7053.57 Median:3706.59 stddev:4482.13 (120.9%) stddev-sans-first:4472.86 08:37:53 INFO - PID 1652 | Values: 1164.9 1426.3 1439.4 1444.2 1447.4 11287.0 11321.3 11848.5 11864.2 11902.5 11673.9 11752.3 11805.0 11810.2 11830.9 2529.1 2553.9 2564.9 2566.1 2572.6 2487.5 2517.2 2534.7 2535.3 2539.1 2478.7 2493.1 2526.6 2536.4 2540.2 2479.0 2491.9 2497.7 2497.7 2499.5 2259.0 2366.2 2407.4 2512.7 2514.2 11681.4 11710.9 11721.2 11814.0 11825.5 6579.3 6850.5 6854.0 6857.1 6858.9 3641.6 3936.7 4216.7 4242.9 4272.8 1097.5 1110.8 1228.7 1234.6 1290.5 15866.2 16274.0 16397.5 16444.2 16575.9 4607.9 4715.5 5516.0 5703.3 5705.5 11611.4 11681.9 11725.9 11729.8 11762.3 6468.5 6543.6 6560.2 6560.5 6563.0 5982.8 6102.7 6136.3 6238.8 6255.7 4254.8 4375.0 4418.9 4432.8 4455.3 1120.8 1208.9 1245.9 1268.2 1272.3 792.8 794.3 814.5 820.9 855.0 6129.6 6168.2 6377.3 6392.2 6460.0 567.6 580.4 586.2 594.5 610.4 3771.6 3903.3 4279.8 4290.3 4338.4 12957.5 15068.8 15247.7 15253.5 15255.5 14221.7 14829.0 14904.9 15019.6 15119.0 7307.8 8221.5 8437.0 8466.8 8505.9 5736.3 6493.8 6602.0 6692.9 6719.3 12851.7 13045.4 13125.9 13372.5 13575.8 13239.4 13344.9 13592.7 13628.1 13741.9 9190.2 9263.0 9341.9 9442.0 9460.5 10571.5 10684.6 10686.8 10723.0 10738.9 8702.4 9088.5 9149.0 9205.3 9340.6 8753.5 8835.8 8892.2 9027.6 9086.7 8126.8 8155.9 8200.1 8300.8 8303.4 5355.9 6078.3 6207.8 6208.7 6289.1 6118.1 6161.7 6259.8 6270.6 6388.2 08:37:53 INFO - PID 1652 | 08:37:53 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5325.54 Median:5924.41 stddev:1714.84 (28.9%) stddev-sans-first:1678.30 08:37:53 INFO - PID 1652 | Values: 338.7 382.6 388.9 401.6 410.6 6270.8 6327.6 6394.0 6445.2 6463.1 4911.9 5755.6 6238.9 6260.7 6312.4 4548.2 4558.3 4595.2 4600.6 4601.4 3475.3 4425.8 4495.7 4579.2 4588.2 3781.8 4265.7 4533.7 4548.9 4554.9 4243.5 4289.0 4577.0 4586.3 4612.1 3462.9 3540.9 3702.4 3799.2 3922.8 4944.8 5082.4 5552.9 5830.2 6107.9 5419.6 5444.3 5450.6 5460.8 5491.8 4688.5 5428.0 6119.4 6701.6 6717.3 3604.8 3961.3 4382.2 4400.3 4426.0 5176.9 6561.7 6584.8 6983.4 7054.6 4099.5 4462.9 4470.7 4484.9 4489.1 4582.3 4583.8 4586.7 4588.3 4594.3 5220.5 5333.3 5368.8 5395.3 5425.8 5627.2 6736.9 6747.8 6751.4 6777.4 1278.6 1515.1 1550.6 1556.8 1572.7 1001.2 1004.9 1007.0 1012.5 1013.5 5947.0 6372.7 6372.7 6378.5 6387.4 5472.5 6992.2 7045.3 7058.7 7072.3 6351.6 6357.9 6357.9 6374.2 6393.5 3231.2 3608.4 3628.6 3628.9 3675.2 6117.4 6586.8 7263.3 7273.4 7310.9 6381.4 6744.5 7125.4 7231.9 7247.7 5999.9 6655.5 6798.9 7230.3 7271.7 6085.7 6711.9 7015.8 7059.7 7100.9 5777.9 5964.3 6574.9 6736.0 6856.9 6523.0 6853.4 7100.3 7138.5 7271.4 3798.9 3949.5 4412.8 4452.7 4482.7 5901.8 6321.4 6733.9 6873.3 7047.6 6205.7 6408.9 6768.2 6775.9 6799.6 5493.0 6215.7 6692.5 6746.5 6783.9 5622.6 6177.3 6607.6 6628.0 6628.4 6519.0 6608.9 6745.4 6746.9 6755.6 5581.6 6058.6 6542.5 6574.2 6611.6 08:37:53 INFO - PID 1652 | 08:37:53 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:22227.19 Median:17333.81 stddev:31698.52 (182.9%) stddev-sans-first:31746.70 08:37:53 INFO - PID 1652 | Values: 824.0 840.2 853.3 876.1 883.1 14406.3 15043.8 16360.3 16586.8 16990.9 14297.8 17261.6 17685.9 17917.3 17969.9 980.9 987.2 997.6 1001.2 1003.7 1017.6 1019.1 1019.1 1019.4 1019.7 1046.5 1050.1 1057.1 1059.7 1060.9 1040.7 1051.9 1054.2 1055.4 1056.4 635.3 773.8 776.9 779.0 779.3 16792.0 16936.2 17171.0 17406.0 17414.3 16043.5 16566.0 16675.0 16692.0 16706.4 13788.6 14089.7 14174.7 14198.4 14231.4 3319.1 3335.5 3408.7 3409.1 3410.4 97257.0 98391.5 115753.0 123829.0 125190.4 12001.2 12048.4 12050.2 12117.3 12136.5 20137.6 20249.0 20428.4 20459.4 20490.8 7542.1 7876.3 7914.7 7917.1 7921.0 3383.4 3956.4 3961.7 3973.0 3975.2 4115.1 4192.8 4193.9 4199.3 4240.9 919.7 946.2 948.9 949.4 949.9 647.4 651.4 651.6 654.7 688.6 11417.3 12001.5 12078.9 12107.8 12139.3 650.8 651.5 653.9 665.8 679.8 4523.9 5258.0 5279.7 5322.0 5475.6 113253.0 115094.0 116173.0 118389.0 118592.0 80507.6 95235.1 102368.0 103147.0 104109.0 60640.0 88081.6 88895.6 89254.1 90227.1 9152.9 10491.9 10769.9 10784.0 11008.5 39435.6 41990.2 42115.9 44312.7 44946.8 64680.4 71220.6 72370.0 74045.0 74063.0 16695.9 16827.5 17058.0 17106.2 17234.1 17616.6 18394.2 18581.3 18758.6 18855.9 16393.1 16472.4 17102.7 17178.6 17190.7 15911.0 16279.2 17084.2 17258.3 17615.8 16331.4 16548.0 16581.8 16814.3 16989.6 11283.0 11415.3 11513.0 11656.2 11754.5 12079.7 12159.6 12197.8 12331.6 12546.4 08:37:53 INFO - PID 1652 | -------- Summary: end -------- 08:37:53 INFO - PID 1652 | 08:37:53 INFO - TEST-INFO | 1652: exit 0 08:37:53 INFO - TEST-OK | dromaeo_css | took 1114604ms 08:37:53 INFO - TEST-START | kraken 08:37:53 INFO - operating with platform_type : mac_ 08:37:53 INFO - Initialising browser for kraken test... 08:37:53 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmp2CxX4_/profile http://localhost:49256/getInfo.html) 08:37:56 INFO - PID 1664 | 2017-05-11 08:37:56.212 plugin-container[1665:11086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 08:37:56 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:37:56 INFO - PID 1664 | 2017-05-11 08:37:56.215 plugin-container[1665:11086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461f, name = 'com.apple.CFPasteboardClient' 08:37:56 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:37:56 INFO - PID 1664 | 2017-05-11 08:37:56.215 plugin-container[1665:11086] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:37:56 INFO - PID 1664 | __metrics Screen width/height:1600/1200 08:37:56 INFO - PID 1664 | colorDepth:24 08:37:56 INFO - PID 1664 | Browser inner width/height: 1024/691 08:37:56 INFO - PID 1664 | __metrics 08:37:57 INFO - TEST-INFO | 1664: exit 0 08:37:57 INFO - Browser initialized. 08:37:57 INFO - Running cycle 1/1 for kraken test... 08:37:57 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmp2CxX4_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:37:59 INFO - PID 1667 | 2017-05-11 08:37:59.516 plugin-container[1668:11244] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 08:37:59 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:37:59 INFO - PID 1667 | 2017-05-11 08:37:59.516 plugin-container[1668:11244] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 08:37:59 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:37:59 INFO - PID 1667 | 2017-05-11 08:37:59.516 plugin-container[1668:11244] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:38:00 INFO - PID 1667 | 2017-05-11 08:38:00.013 plugin-container[1669:11289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 08:38:00 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:00 INFO - PID 1667 | 2017-05-11 08:38:00.013 plugin-container[1669:11289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x962b, name = 'com.apple.CFPasteboardClient' 08:38:00 INFO - PID 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:38:00 INFO - PID 1667 | 2017-05-11 08:38:00.013 plugin-container[1669:11289] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:38:09 INFO - PID 1667 | RSS: Main: 228671488 08:38:09 INFO - PID 1667 | 08:38:11 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49256/tests/kraken/driver.html?testName=audio-beat-detection) 08:38:12 INFO - PID 1667 | RSS: Main: 242016256 08:38:12 INFO - PID 1667 | 08:38:15 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49256/tests/kraken/driver.html?testName=audio-dft) 08:38:16 INFO - PID 1667 | RSS: Main: 250023936 08:38:16 INFO - PID 1667 | 08:38:19 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49256/tests/kraken/driver.html?testName=audio-fft) 08:38:20 INFO - PID 1667 | RSS: Main: 252256256 08:38:20 INFO - PID 1667 | 08:38:22 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49256/tests/kraken/driver.html?testName=audio-oscillator) 08:38:22 INFO - PID 1667 | RSS: Main: 254025728 08:38:22 INFO - PID 1667 | 08:38:24 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49256/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:38:25 INFO - PID 1667 | RSS: Main: 255225856 08:38:25 INFO - PID 1667 | 08:38:28 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49256/tests/kraken/driver.html?testName=imaging-darkroom) 08:38:29 INFO - PID 1667 | RSS: Main: 261607424 08:38:29 INFO - PID 1667 | 08:38:32 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49256/tests/kraken/driver.html?testName=imaging-desaturate) 08:38:33 INFO - PID 1667 | RSS: Main: 262033408 08:38:33 INFO - PID 1667 | 08:38:36 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49256/tests/kraken/driver.html?testName=json-parse-financial) 08:38:36 INFO - PID 1667 | RSS: Main: 261623808 08:38:36 INFO - PID 1667 | 08:38:38 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49256/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:38:38 INFO - PID 1667 | RSS: Main: 261275648 08:38:38 INFO - PID 1667 | 08:38:40 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:38:41 INFO - PID 1667 | RSS: Main: 261312512 08:38:41 INFO - PID 1667 | 08:38:43 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:38:43 INFO - PID 1667 | RSS: Main: 260976640 08:38:43 INFO - PID 1667 | 08:38:46 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:38:47 INFO - PID 1667 | RSS: Main: 261132288 08:38:47 INFO - PID 1667 | 08:38:50 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:38:51 INFO - PID 1667 | RSS: Main: 261292032 08:38:51 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | Cycle 1(1): loaded http://localhost:49256/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49256/tests/kraken/driver.html?testName=ai-astar) 08:38:53 INFO - PID 1667 | RSS: Main: 262922240 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | __start_tp_report 08:38:53 INFO - PID 1667 | _x_x_mozilla_page_load 08:38:53 INFO - PID 1667 | _x_x_mozilla_page_load_details 08:38:53 INFO - PID 1667 | |i|pagename|runs| 08:38:53 INFO - PID 1667 | |0;ai-astar;101;114;116;114;114;114;115;115;115;116 08:38:53 INFO - PID 1667 | |1;audio-beat-detection;160;112;115;150;118;114;115;111;144;125 08:38:53 INFO - PID 1667 | |2;audio-dft;196;197;204;205;234;266;239;203;198;197 08:38:53 INFO - PID 1667 | |3;audio-fft;83;89;66;79;87;64;87;83;93;77 08:38:53 INFO - PID 1667 | |4;audio-oscillator;83;69;83;71;74;55;93;103;55;97 08:38:53 INFO - PID 1667 | |5;imaging-gaussian-blur;91;90;90;89;92;91;90;108;136;135 08:38:53 INFO - PID 1667 | |6;imaging-darkroom;97;118;155;118;107;153;110;153;151;98 08:38:53 INFO - PID 1667 | |7;imaging-desaturate;91;110;78;150;150;90;92;89;89;92 08:38:53 INFO - PID 1667 | |8;json-parse-financial;48;47;51;51;52;49;51;50;51;53 08:38:53 INFO - PID 1667 | |9;json-stringify-tinderbox;62;57;58;70;60;58;57;61;58;57 08:38:53 INFO - PID 1667 | |10;stanford-crypto-aes;80;83;84;82;84;80;78;86;81;85 08:38:53 INFO - PID 1667 | |11;stanford-crypto-ccm;140;137;141;138;138;136;139;140;188;136 08:38:53 INFO - PID 1667 | |12;stanford-crypto-pbkdf2;186;188;185;188;188;188;182;179;201;226 08:38:53 INFO - PID 1667 | |13;stanford-crypto-sha256-iterative;70;67;81;90;89;98;94;92;93;79 08:38:53 INFO - PID 1667 | __end_tp_report 08:38:53 INFO - PID 1667 | __start_cc_report 08:38:53 INFO - PID 1667 | _x_x_mozilla_cycle_collect,991 08:38:53 INFO - PID 1667 | __end_cc_report 08:38:53 INFO - PID 1667 | __startTimestamp1494517133343__endTimestamp 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | ------- Summary: start ------- 08:38:53 INFO - PID 1667 | Number of tests: 14 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#0] ai-astar Cycles:10 Average:113.40 Median:115.00 stddev:4.43 (3.8%) stddev-sans-first:0.83 08:38:53 INFO - PID 1667 | Values: 101.0 114.0 116.0 114.0 114.0 114.0 115.0 115.0 115.0 116.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:121.50 stddev:18.03 (14.8%) stddev-sans-first:14.46 08:38:53 INFO - PID 1667 | Values: 160.0 112.0 115.0 150.0 118.0 114.0 115.0 111.0 144.0 125.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#2] audio-dft Cycles:10 Average:213.90 Median:204.50 stddev:24.01 (11.7%) stddev-sans-first:24.58 08:38:53 INFO - PID 1667 | Values: 196.0 197.0 204.0 205.0 234.0 266.0 239.0 203.0 198.0 197.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#3] audio-fft Cycles:10 Average:80.80 Median:85.00 stddev:9.55 (11.2%) stddev-sans-first:10.10 08:38:53 INFO - PID 1667 | Values: 83.0 89.0 66.0 79.0 87.0 64.0 87.0 83.0 93.0 77.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#4] audio-oscillator Cycles:10 Average:78.30 Median:78.50 stddev:16.55 (21.1%) stddev-sans-first:17.46 08:38:53 INFO - PID 1667 | Values: 83.0 69.0 83.0 71.0 74.0 55.0 93.0 103.0 55.0 97.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:90.00 stddev:18.91 (21.0%) stddev-sans-first:19.69 08:38:53 INFO - PID 1667 | Values: 91.0 90.0 90.0 89.0 92.0 91.0 90.0 108.0 136.0 135.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#6] imaging-darkroom Cycles:10 Average:126.00 Median:153.00 stddev:24.25 (15.9%) stddev-sans-first:23.34 08:38:53 INFO - PID 1667 | Values: 97.0 118.0 155.0 118.0 107.0 153.0 110.0 153.0 151.0 98.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:89.50 stddev:25.90 (28.9%) stddev-sans-first:27.10 08:38:53 INFO - PID 1667 | Values: 91.0 110.0 78.0 150.0 150.0 90.0 92.0 89.0 89.0 92.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:51.00 stddev:1.83 (3.6%) stddev-sans-first:1.74 08:38:53 INFO - PID 1667 | Values: 48.0 47.0 51.0 51.0 52.0 49.0 51.0 50.0 51.0 53.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:59.00 stddev:3.99 (6.8%) stddev-sans-first:4.16 08:38:53 INFO - PID 1667 | Values: 62.0 57.0 58.0 70.0 60.0 58.0 57.0 61.0 58.0 57.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.50 stddev:2.54 (3.0%) stddev-sans-first:2.55 08:38:53 INFO - PID 1667 | Values: 80.0 83.0 84.0 82.0 84.0 80.0 78.0 86.0 81.0 85.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:139.50 stddev:15.80 (11.3%) stddev-sans-first:16.71 08:38:53 INFO - PID 1667 | Values: 140.0 137.0 141.0 138.0 138.0 136.0 139.0 140.0 188.0 136.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.10 Median:188.00 stddev:13.53 (7.2%) stddev-sans-first:14.22 08:38:53 INFO - PID 1667 | Values: 186.0 188.0 185.0 188.0 188.0 188.0 182.0 179.0 201.0 226.0 08:38:53 INFO - PID 1667 | 08:38:53 INFO - PID 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:85.30 Median:91.00 stddev:10.56 (11.6%) stddev-sans-first:9.64 08:38:53 INFO - PID 1667 | Values: 70.0 67.0 81.0 90.0 89.0 98.0 94.0 92.0 93.0 79.0 08:38:53 INFO - PID 1667 | -------- Summary: end -------- 08:38:53 INFO - PID 1667 | 08:38:54 INFO - TEST-INFO | 1667: exit 0 08:38:54 INFO - TEST-OK | kraken | took 60378ms 08:38:54 INFO - SUITE-END | took 1174s 08:38:54 INFO - Completed test suite (00:19:35) 08:38:54 INFO - javascript benchmark 08:38:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7837.35407647737, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3156.558081868538, 3504.56192975878, 3635.927281454369, 3668.5781135169464, 3686.3548878946194, 5291.841244762653, 6221.377862213776, 6291.685415729206, 6323.241211054674, 6355.459785918202, 6286.214223222098, 6299.464545513637, 6318.620882747027, 6326.905096423558, 6349.047642853563, 5495.230667706524, 5536.612437129403, 5546.500814926656, 5582.385937546866, 5591.161325801142, 5582.944170558283, 5664.858378540549, 5666.263385759846, 5667.376588575274, 5677.772889084432, 5447.046766815792, 5532.585056120807, 5586.776528938837, 5625.662460252398, 5669.348024977139, 5525.502704756591, 5591.189277554732, 5601.579881508903, 5612.747426365823, 5643.351014633287, 5527.2538207341895, 5529.253550770634, 5571.387147413781, 5600.6079574429805, 5602.0, 5353.6787792732175, 5617.848341090067, 6322.209723784527, 6322.272938612073, 6332.1135041094285, 6523.575967562093, 6595.241547222083, 6676.666166691694, 6769.153855768029, 6779.35596118368, 5312.5218730314455, 5648.293963254593, 6841.794746157623, 6880.793576192672, 6910.378065974087, 6256.906146407808, 6395.744170233185, 6401.679916004181, 6406.871862562769, 6416.069669897839, 6766.357196066366, 6907.240203577602, 6990.825229369307, 7102.928970710227, 7141.14306283239, 5251.737413129405, 6607.57050791697, 6614.239362473281, 6677.332266773284, 6693.096431981718, 6043.3050199227655, 6469.741210351627, 6862.451003919675, 6865.313468653195, 6892.655367231619, 6442.838929026718, 6563.949768037091, 6585.539012269095, 6708.597484150967, 6750.122484077038, 6632.568883022588, 6710.0, 6727.562708423937, 6744.190697116279, 6753.797386078426, 5311.468853114735, 5315.0, 5353.7590808413715, 5366.409694933554, 5388.67667939917, 4721.079389519079, 4780.976095119502, 4803.135435621552, 4813.518648135228, 4826.034793041335, 6330.778422755181, 6364.459020983176, 6385.169927909342, 6416.550841441148, 6435.22777266731, 6888.207856096513, 6899.241083480813, 6913.10129683138, 6914.827129321707, 6928.515003949675, 6374.139491168726, 6376.266729326094, 6376.7130479128555, 6395.968020159962, 6415.775447859302, 5565.638233514889, 5850.9414905851245, 5919.319278283053, 5926.496247818897, 5948.256467941507, 7030.753923612649, 7046.365827075588, 7116.857662846817, 7131.429485641032, 7174.354308112295, 6614.603123812587, 6742.9662851685425, 6948.861022779415, 6961.408280296231, 6980.371766541036, 5735.369109398046, 7084.185318688211, 7139.214686384597, 7153.3919616833155, 7170.498065135391, 6498.64257465825, 6808.65956702153, 6809.93190068093, 6828.351306625863, 6832.385085342343, 6662.267150613332, 6694.196696396269, 6791.93208067913, 6870.0, 6915.757948471779, 6694.799156025327, 6715.127033485615, 6718.798436046926, 6719.764808231688, 6729.461643068641, 6694.83262918431, 6726.3609957054005, 6731.495137864582, 6733.461323094239, 6746.4265537429865, 6229.2524897012645, 6348.77779277723, 6405.93594064072, 6423.389777971085, 6505.642189679424, 6645.169353830771, 6648.002799580102, 6698.765543205965, 6711.395974362331, 6716.83207919806, 6482.54622176462, 6489.8702025960165, 6492.415682588589, 6511.120998665119, 6512.251091124582], "value": 6178.574737666815, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [804.899387576553, 1190.7975644140495, 1243.7512497500497, 1253.9435725392357, 1255.3785875991387, 35188.29623407534, 35882.64117358826, 35908.82045589775, 35910.82044589776, 35961.20193990303, 30524.0, 30541.694583054163, 36686.0, 37098.81450592743, 37103.25793484136, 7219.061522002158, 7981.600919953994, 8043.0, 8221.876671849926, 8229.135940726228, 8245.876311855327, 8329.541875196861, 8379.036410812774, 8387.538685372301, 8396.874046889301, 8344.040435349953, 8346.916530834706, 8428.831423371528, 8440.62017209224, 8443.15568443157, 8399.244068033875, 8401.53791541468, 8425.11536288693, 8433.662653493853, 8465.984081910148, 8193.13972032934, 8235.0, 8285.461445006054, 8290.08809031006, 8335.458195217292, 28777.856110719313, 35875.82062089673, 35918.0, 35924.28151436957, 35990.64009359899, 3596.5684117905757, 3629.7096232301615, 3636.381815091441, 3643.234920666663, 3646.762960407565, 29789.957351492594, 29833.55249671235, 29840.55239171414, 29866.0, 29869.40261194764, 3108.1141874565865, 3134.529820526939, 3155.195425166568, 3155.5739975103525, 3161.4783560712453, 242238.99999999822, 275257.0, 275762.0, 276181.0, 276666.0, 37999.810000949816, 46174.0, 46376.53623463722, 46639.06721865544, 46812.0, 47964.0, 48465.33440660797, 48468.5153148464, 48558.0, 48804.0, 13876.722465550632, 13889.097208681404, 14006.789898151535, 14007.0, 14024.439022439192, 11204.0, 11262.831057534144, 11391.943040284745, 11403.258788178908, 11430.942845285887, 12963.611091667264, 13041.934790325988, 13055.21668699881, 13100.803487947689, 13177.0, 5390.8921821563345, 5525.972370138204, 5797.0, 5822.679752613562, 5891.675957822275, 1003.3678782367062, 1008.2337423558002, 1018.221060888421, 1045.7960697663882, 1048.5438834107188, 23393.0, 24457.633135502627, 24606.138785142433, 24628.261152165465, 24717.752822471906, 1097.6158344579333, 1114.2256131988352, 1135.4039129456935, 1143.2226086261421, 1159.1654009113424, 11314.321140731583, 12402.503899844085, 15836.495532924353, 16053.438129665406, 16466.6706665866, 339849.0, 342656.0, 342945.0, 343405.0, 344260.0, 53795.0, 54150.45849541533, 54154.729226353615, 54263.72868135634, 54533.90932181334, 1419.5386499387535, 1456.4247122386598, 1490.1729540105346, 1502.6018105202131, 1517.1276516003343, 29231.0, 29247.0, 29698.40603187881, 30118.0, 30180.09459716212, 32739.508907366886, 36632.0, 36676.26647467089, 37730.0, 37899.81050094732, 293642.0, 296873.0, 297429.0, 297747.0, 297981.0, 32956.17609559684, 34034.0, 35455.64544354523, 35655.0, 35968.0, 42483.15033699247, 42700.0, 45012.0, 45048.77475612601, 45189.0, 23399.532009360057, 25950.610740838525, 30088.54867177038, 31169.8441507791, 31208.21979450532, 23095.307140785804, 30254.697453026067, 31004.534931975588, 31148.532772007984, 31390.0, 37299.0, 37410.0, 37414.06464838401, 37415.62584374121, 37472.437913430775, 29843.403131936804, 30874.845625771726, 30919.690803091682, 31332.686673132976, 31502.897398591856, 20207.393778186677, 24641.0, 25042.248732538053, 25119.74880251174, 25460.0], "value": 17502.907922162205, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [423.7164880981239, 424.7536428871251, 429.96990210685254, 442.73214705103413, 451.7831440908366, 21116.89441552794, 21467.67798483022, 22066.88966555167, 22156.778432215695, 22559.887200563975, 20501.692474612857, 22389.32832015038, 23366.065357385683, 23377.883110584422, 23692.407689807762, 4650.86047418578, 5966.985612445895, 5974.163617093611, 6145.139680444741, 6195.380461953813, 6000.879982400349, 6172.783952561661, 6176.876462470748, 6182.165407669974, 6187.721552530147, 5934.0, 5983.2820061592665, 6089.421504957044, 6134.7546098156245, 6158.538109641795, 6045.455908968192, 6119.571629985903, 6128.233970753656, 6132.78535251264, 6140.478059364959, 5194.480551944813, 5358.115910874701, 5390.272313237741, 5418.864528386822, 5428.61999660024, 27141.728582714313, 27297.044603438786, 27793.305167370774, 28073.438531229465, 28115.85942070297, 2123.5540536487356, 2226.6214743493647, 2227.0312414099917, 2234.6871437998693, 2237.1722462688745, 17538.912305438393, 17562.824371756247, 17583.64832703339, 18007.72988405175, 18113.728294075598, 1989.2639723302325, 2042.8876411797346, 2107.262458139654, 2127.819135373495, 2168.5662867426577, 140312.29843850818, 144183.0, 145247.82128268084, 145263.0, 161511.0, 12109.757804843854, 12510.81233781494, 12616.621501354974, 12708.618741437771, 12739.108262421541, 16096.678066438606, 16100.919495402683, 16131.677366452604, 16146.75779863303, 16148.0, 8453.0, 8652.826943461221, 8718.43330183536, 8776.912230877737, 8870.379073464796, 8085.595720213966, 8088.9595552021865, 8126.715564955316, 8241.340692744567, 8261.17388261169, 6606.372394622503, 7832.608369581498, 7908.881366779503, 7950.24472675095, 7972.322352600094, 1870.4014715290975, 2202.7246594175726, 2209.3151123151874, 2215.4713247858926, 2382.761723827638, 859.8194379180317, 942.7972985807947, 948.6821914658667, 950.2303134461108, 987.5950860147449, 20619.8969005157, 21154.048067836986, 21180.682289765668, 21191.68212476814, 21247.68128478074, 997.5261750668375, 1006.5068116622948, 1015.7155996321042, 1023.7184774187009, 1031.041131747467, 10285.22860785444, 10881.945590271998, 10890.564377424973, 10932.672019839418, 10994.28537145083, 490945.0, 533915.0, 534310.0, 545222.0, 546103.269483658, 100240.0, 118960.40519797518, 124114.0, 124735.0, 126369.36815315865, 1065.1744897704186, 1154.365099195456, 1172.6599286206904, 1196.1447564991074, 1197.239752756988, 16512.83487165113, 17592.648147037242, 17914.91042544779, 18119.003454809586, 18343.35798247055, 39335.60664393319, 50454.2431863515, 51015.0, 51300.973980520925, 51381.751429011936, 69989.65005174941, 70027.64986175037, 70180.59638807093, 71161.0, 72680.54638907086, 7026.437884969292, 8509.064002959793, 8735.1701588349, 8736.301095912184, 8813.91186088131, 13084.476620935056, 13251.734965300448, 13307.46770129184, 13500.392482338122, 13624.591262262147, 11592.608886933622, 11627.76744465089, 11734.0, 11841.82237266459, 11917.22538035025, 9471.19494842946, 10642.787144256916, 10881.727956801144, 11086.778264434504, 11087.113030957342, 10788.622398216025, 11834.994025507584, 11920.582779602672, 12039.217450865666, 12159.817602736142, 8465.492070475791, 8609.096044915193, 8663.826723465369, 8752.299816014576, 8805.559722014003, 9615.51922403891, 11412.687540932899, 11461.312321260752, 11667.824982625098, 11737.0], "value": 10174.740059789048, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1164.924279921805, 1426.2654732812596, 1439.4458133618557, 1444.248990524927, 1447.4282658349946, 11287.0, 11321.32072075675, 11848.466818993145, 11864.22882512637, 11902.46438910249, 11673.941630291836, 11752.294862308267, 11805.0, 11810.232334898246, 11830.940845295761, 2529.089527770006, 2553.8850751716122, 2564.8842753402328, 2566.1275166443406, 2572.601246806748, 2487.4776296977566, 2517.169334119736, 2534.6958364996226, 2535.277445927911, 2539.1113110411393, 2478.652988581591, 2493.127405408111, 2526.5704830178915, 2536.3912660961328, 2540.2252313044514, 2478.9876050619723, 2491.8754062296994, 2497.650328953948, 2497.67530221071, 2499.487605040962, 2259.017327462559, 2366.171839856054, 2407.3620490570015, 2512.7110382306087, 2514.208024472304, 11681.357525336103, 11710.941445292805, 11721.238119522204, 11814.0, 11825.526978920832, 6579.309172536862, 6850.4862135340045, 6854.0404343391965, 6857.108575885153, 6858.862822743567, 3641.5630124385243, 3936.6850651947775, 4216.662666986665, 4242.893927651802, 4272.829086836523, 1097.4732128578328, 1110.783397237539, 1228.6866848953548, 1234.6049264235448, 1290.5160564788205, 15866.20668966547, 16274.023558586523, 16397.508074757778, 16444.177791110396, 16575.922565033354, 4607.917139472222, 4715.481297057321, 5516.0, 5703.287089113861, 5705.515031222392, 11611.419429028514, 11681.88318116825, 11725.944664980023, 11729.765404691858, 11762.294262344287, 6468.450181734511, 6543.640099794557, 6560.179977502812, 6560.540762146604, 6562.967185164043, 5982.820515384546, 6102.69486525672, 6136.2636483622255, 6238.844028899314, 6255.655938923403, 4254.787260636956, 4374.956250437455, 4418.889527761831, 4432.800523976429, 4455.331700244989, 1120.815065514181, 1208.8549374074992, 1245.872485400714, 1268.2327192048679, 1272.2811611439506, 792.7621713485931, 794.253401802304, 814.5275740070745, 820.8809722590191, 854.9914500855036, 6129.601575897553, 6168.167297414881, 6377.3303803100935, 6392.232932048184, 6460.030995350735, 567.6168586204295, 580.38189328366, 586.2496005113462, 594.4976494861921, 610.3713175429315, 3771.6228377162615, 3903.316919539069, 4279.786010699515, 4290.2706539888295, 4338.414314067561, 12957.546485872948, 15068.773968390264, 15247.695046099016, 15253.466128685443, 15255.542333730005, 14221.715565688628, 14829.036112652642, 14904.925475372555, 15019.624509387353, 15119.0, 7307.780766576905, 8221.54781487024, 8437.029741579674, 8466.788330291669, 8505.872411913826, 5736.282964629421, 6493.837654058686, 6602.0, 6692.866142677021, 6719.294474080151, 12851.678708032374, 13045.412956416794, 13125.934370328087, 13372.465101395836, 13575.79636305476, 13239.40422681001, 13344.866551334362, 13592.66018349549, 13628.114172578751, 13741.924566241001, 9190.172884440433, 9263.0, 9341.906580934103, 9442.008589098321, 9460.526973651153, 10571.471426428494, 10684.57261709554, 10686.786264274826, 10723.03492685662, 10738.946305268424, 8702.390832641655, 9088.545572721205, 9148.954255228682, 9205.30960177976, 9340.579673914826, 8753.47479151253, 8835.80716603276, 8892.244159246278, 9027.638894444412, 9086.727398178065, 8126.805762443989, 8155.918440815516, 8200.097989221062, 8300.750977470685, 8303.377246706401, 5355.892882142413, 6078.300995385411, 6207.81376558704, 6208.720607572761, 6289.056641503811, 6118.082287656887, 6161.6919154041225, 6259.812205633838, 6270.623762574078, 6388.169537960035], "value": 5274.395288244653, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.6968663046575, 382.6364953294371, 388.9066624010235, 401.61043787526097, 410.5976143379487, 6270.811875643738, 6327.557071005026, 6393.968030159848, 6445.1943507061615, 6463.127477790491, 4911.852644420673, 5755.6258843175165, 6238.937610623892, 6260.746131392695, 6312.431881130698, 4548.18072770891, 4558.293464512997, 4595.241785105471, 4600.5629465200755, 4601.447826260854, 3475.304939012195, 4425.8229670813125, 4495.685302028859, 4579.221532339494, 4588.220002599566, 3781.8298176581984, 4265.680073994447, 4533.659975501834, 4548.9090218195615, 4554.908901821962, 4243.533211346749, 4289.0, 4577.0, 4586.2661974083985, 4612.1467528507, 3462.926492787244, 3540.9114772130647, 3702.3520883845217, 3799.2211596622624, 3922.8234729437245, 4944.8269310574315, 5082.390113186404, 5552.861178470571, 5830.212921255618, 6107.87784244317, 5419.59353048519, 5444.292242008553, 5450.618456708032, 5460.788380705821, 5491.807786727445, 4688.460827004904, 5428.0, 6119.357467465897, 6701.59790412572, 6717.261101278856, 3604.8197590120653, 3961.3265744823448, 4382.189294980439, 4400.317950717644, 4425.955740442587, 5176.8964620707375, 6561.671916404209, 6584.84765166094, 6983.441324693961, 7054.611996340199, 4099.503681156416, 4462.906587885988, 4470.687051906335, 4484.9103017940115, 4489.147062058199, 4582.289745089516, 4583.793729282191, 4586.72479651222, 4588.334691469785, 4594.264917613166, 5220.546343218681, 5333.253344531769, 5368.785248590013, 5395.298611180521, 5425.755840987165, 5627.155926610959, 6736.865262694719, 6747.831304217434, 6751.392374686302, 6777.356151165632, 1278.6355888571713, 1515.0985163827504, 1550.6433520290395, 1556.7664850272324, 1572.7404978178702, 1001.1690297053428, 1004.9196064314838, 1006.9597216111273, 1012.5392946209604, 1013.4729940430934, 5947.0, 6372.681365931778, 6372.74509019634, 6378.521610879203, 6387.361263873576, 5472.534834539029, 6992.230854605989, 7045.330693584101, 7058.6823592938445, 7072.328128827753, 6351.618902865843, 6357.872842543124, 6357.872842543124, 6374.171357723559, 6393.456556192683, 3231.2245061185617, 3608.3581970203495, 3628.618995005539, 3628.9455658165148, 3675.246574452284, 6117.357677443804, 6586.769463068769, 7263.34629883313, 7273.3817625502425, 7310.853782924311, 6381.3937675920715, 6744.482491439485, 7125.35871771543, 7231.89152162718, 7247.710091596383, 5999.940000600025, 6655.534112612071, 6798.932010679929, 7230.349268565854, 7271.691095602948, 6085.69571521431, 6711.899321510084, 7015.789526314219, 7059.682314295972, 7100.857982840211, 5777.8555536111935, 5964.254468191476, 6574.948008318646, 6736.0, 6856.862862742817, 6523.0, 6853.401963968956, 7100.254473280231, 7138.535995160298, 7271.381932535794, 3798.9240215196096, 3949.5260568731937, 4412.757298348622, 4452.666050046195, 4482.686211965131, 5901.822945311647, 6321.367863213642, 6733.865322693617, 6873.312668733087, 7047.612381319077, 6205.720742566505, 6408.93591064102, 6768.221654509597, 6775.898361524482, 6799.558028728117, 5492.972535137299, 6215.742216581656, 6692.531522793358, 6746.4940129489505, 6783.864322713617, 5622.5783066271015, 6177.320494745484, 6607.57050791697, 6627.966860165669, 6628.403443690091, 6518.979116473908, 6608.900866486911, 6745.359190877055, 6746.8650626988165, 6755.560888542229, 5581.6371935824045, 6058.636481811105, 6542.462521307584, 6574.178227721535, 6611.636360000246], "value": 4787.199212692445, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [823.9670413183472, 840.1850205300856, 853.287504933381, 876.0626130040858, 883.116883116883, 14406.27968601571, 15043.774343384857, 16360.263788129532, 16586.834131658696, 16990.895591786542, 14297.78553321701, 17261.568460788454, 17685.91157044216, 17917.283308667636, 17969.91015044923, 980.9264305177122, 987.210231814549, 997.6009596161557, 1001.2140469731262, 1003.6988903329009, 1017.5929628148725, 1019.0675531888313, 1019.138827690604, 1019.3832731197593, 1019.6635110413546, 1046.5081411736471, 1050.0759331788054, 1057.0539367266292, 1059.6715018344337, 1060.9257351985364, 1040.7242080848582, 1051.8790339110985, 1054.1672079057535, 1055.4142450939764, 1056.3767377247461, 635.3011687144151, 773.833625770462, 776.8679324514846, 778.9727359542445, 779.3142035009213, 16792.0, 16936.23786929591, 17171.0, 17406.042667653273, 17414.30342786287, 16043.518694439186, 16566.0, 16675.0, 16692.0, 16706.41527546542, 13788.586342409744, 14089.71820563583, 14174.716505669932, 14198.361073751603, 14231.430742770277, 3319.133653241808, 3335.483000134983, 3408.693217610427, 3409.062507810347, 3410.437277849152, 97256.99999999929, 98391.50804246005, 115753.0, 123829.0, 125190.37404812918, 12001.219920705125, 12048.397580120958, 12050.216735912138, 12117.333546655018, 12136.514539418324, 20137.59724805525, 20249.0, 20428.387148385573, 20459.38621841347, 20490.795092049186, 7542.094948606203, 7876.251756083163, 7914.722984695508, 7917.129115797373, 7921.009873765779, 3383.3909896218424, 3956.3669812829808, 3961.74248673841, 3973.0, 3975.1652153047607, 4115.053537686349, 4192.832286708497, 4193.853215137517, 4199.265128602544, 4240.85157019509, 919.6827094652334, 946.1910066892846, 948.8671585977969, 949.4350861237552, 949.8622699708503, 647.397919934456, 651.4234902111665, 651.5797310734549, 654.7479220500142, 688.6281408039703, 11417.31496110236, 12001.519939202508, 12078.879211207952, 12107.757844843054, 12139.332336721389, 650.817771024114, 651.4625434016955, 653.9149910511699, 665.7869481765788, 679.7960611816435, 4523.886902827456, 5257.973710131501, 5279.709615971158, 5322.0, 5475.561955043587, 113253.0, 115094.0, 116173.0, 118389.0, 118592.0, 80507.58477245692, 95235.09529809504, 102368.0, 103146.96853031524, 104109.0, 60639.99999999912, 88081.55959220162, 88895.55552222327, 89254.10745892588, 90227.09772902187, 9152.862707059532, 10491.895081049397, 10769.9461502692, 10784.029437350677, 11008.504617292081, 39435.60564394319, 41990.1601967965, 42115.94710132271, 44312.67061988146, 44946.77526612346, 64680.38299042561, 71220.57558848898, 72370.0, 74045.0, 74063.0, 16695.91652041732, 16827.495175144766, 17058.0, 17106.230219640394, 17234.052127133127, 17616.55958601045, 18394.1722622485, 18581.25674972986, 18758.624827503645, 18855.868647881172, 16393.09837958924, 16472.446770750554, 17102.657946840744, 17178.570535736708, 17190.65618687594, 15911.0, 16279.186040698154, 17084.181110117188, 17258.309667613154, 17615.82384176142, 16331.428400005943, 16548.0, 16581.834181658513, 16814.327426902786, 16989.575260618585, 11283.0, 11415.315081095161, 11512.963833255048, 11656.242344247597, 11754.471048802654, 12079.698007549881, 12159.635210943687, 12197.817032744339, 12331.630051098482, 12546.435410406737], "value": 8340.851117533004, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 116.0, 114.0, 114.0, 114.0, 115.0, 115.0, 115.0, 116.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 112.0, 115.0, 150.0, 118.0, 114.0, 115.0, 111.0, 144.0, 125.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 197.0, 204.0, 205.0, 234.0, 266.0, 239.0, 203.0, 198.0, 197.0], "value": 213.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 89.0, 66.0, 79.0, 87.0, 64.0, 87.0, 83.0, 93.0, 77.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 69.0, 83.0, 71.0, 74.0, 55.0, 93.0, 103.0, 55.0, 97.0], "value": 78.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 90.0, 89.0, 92.0, 91.0, 90.0, 108.0, 136.0, 135.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 118.0, 155.0, 118.0, 107.0, 153.0, 110.0, 153.0, 151.0, 98.0], "value": 126.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 110.0, 78.0, 150.0, 150.0, 90.0, 92.0, 89.0, 89.0, 92.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 47.0, 51.0, 51.0, 52.0, 49.0, 51.0, 50.0, 51.0, 53.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 57.0, 58.0, 70.0, 60.0, 58.0, 57.0, 61.0, 58.0, 57.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 84.0, 82.0, 84.0, 80.0, 78.0, 86.0, 81.0, 85.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 141.0, 138.0, 138.0, 136.0, 139.0, 140.0, 188.0, 136.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 188.0, 185.0, 188.0, 188.0, 188.0, 182.0, 179.0, 201.0, 226.0], "value": 191.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 81.0, 90.0, 89.0, 98.0, 94.0, 92.0, 93.0, 79.0], "value": 85.3, "unit": "score"}]}]} 08:38:54 INFO - Return code: 0 08:38:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:38:54 INFO - # TBPL SUCCESS # 08:38:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:38:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:38:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:38:54 INFO - 2017-05-11 08:38:54.666 screenresolution[1674:11675] starting screenresolution argv=screenresolution get 08:38:54 INFO - 2017-05-11 08:38:54.669 screenresolution[1674:11675] Display 0: 1600x1200x32@60 08:38:54 INFO - 2017-05-11 08:38:54.675 screenresolution[1675:11678] starting screenresolution argv=screenresolution list 08:38:54 INFO - Available Modes on Display 0 08:38:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:38:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:38:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:38:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:38:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:38:54 INFO - Intel Iris: 08:38:54 INFO - Chipset Model: Intel Iris 08:38:54 INFO - Type: GPU 08:38:54 INFO - Bus: Built-In 08:38:54 INFO - VRAM (Dynamic, Max): 1536 MB 08:38:54 INFO - Vendor: Intel (0x8086) 08:38:54 INFO - Device ID: 0x0a2e 08:38:54 INFO - Revision ID: 0x0009 08:38:54 INFO - Displays: 08:38:54 INFO - Display: 08:38:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:38:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:38:54 INFO - Display Serial Number: 1600x1200 60 08:38:54 INFO - Main Display: Yes 08:38:54 INFO - Mirror: Off 08:38:54 INFO - Online: Yes 08:38:54 INFO - Rotation: Supported 08:38:54 INFO - Return code: 0 08:38:54 INFO - Running post-action listener: _resource_record_post_action 08:38:54 INFO - [mozharness: 2017-05-11 15:38:54.912528Z] Finished run-tests step (success) 08:38:54 INFO - Running post-run listener: _resource_record_post_run 08:38:54 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 34997248; Write bytes: 530472960; Read time: 329; Write time: 1989 08:38:54 INFO - TinderboxPrint: CPU usage
54.2% 08:38:54 INFO - TinderboxPrint: I/O read bytes / time
34,997,248 / 329 08:38:54 INFO - TinderboxPrint: I/O write bytes / time
530,472,960 / 1,989 08:38:54 INFO - TinderboxPrint: CPU idle
2,193.7 (45.8%) 08:38:54 INFO - TinderboxPrint: CPU system
959.9 (20.1%) 08:38:54 INFO - TinderboxPrint: CPU user
1,632.0 (34.1%) 08:38:54 INFO - TinderboxPrint: Swap in / out
244,981,760 / 0 08:38:54 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202189312; Write bytes: 196626432; Read time: 15690; Write time: 427 08:38:54 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 34014208; Write bytes: 313042944; Read time: 314; Write time: 1502 08:38:55 INFO - Running post-run listener: _upload_blobber_files 08:38:55 INFO - Blob upload gear active. 08:38:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:38:55 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 08:38:55 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'] 08:38:55 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 08:38:55 INFO - (blobuploader) - INFO - Open directory for files ... 08:38:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:38:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:38:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:38:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:38:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:38:57 INFO - (blobuploader) - INFO - Done attempting. 08:38:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:38:58 INFO - (blobuploader) - INFO - Done attempting. 08:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:00 INFO - (blobuploader) - INFO - Done attempting. 08:39:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:39:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:39:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:39:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:39:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:39:02 INFO - (blobuploader) - INFO - Done attempting. 08:39:02 INFO - (blobuploader) - INFO - Iteration through files over. 08:39:02 INFO - Return code: 0 08:39:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:39:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:39:02 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd9376c9182e26ab717d4881ef413af66832e46994dd46c7ed838d857748a57f8ac76a2dc8c70e258716ecf7fc34238407b1ab3c7c31c45b00ed06fcd083693a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49abef460f877b52402c8cfd867caa1af94261e5da286e59e85807f60845dd3c593556fb521cde7d3baa4f40f5af08b5a092782d01481ae28864acb7629bc951", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d0adaa704b79b7b27df2df2a76358838697a92e8f4febfbec05c28ba60a8f50f56b4e921d11d64e8431de50f1a2f64a14435b5ac22f51b243c7ae2a407d6b84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9204752eceb26830fa4c86cf43caa92527b6b6d30dac2e50bf41712a2db012ba40690b4fcaa1ed918b20c469db1c97f827eb9e5e69b590d3d0130e3a2de7ea2b"} 08:39:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:39:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:39:02 INFO - Contents: 08:39:02 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fd9376c9182e26ab717d4881ef413af66832e46994dd46c7ed838d857748a57f8ac76a2dc8c70e258716ecf7fc34238407b1ab3c7c31c45b00ed06fcd083693a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49abef460f877b52402c8cfd867caa1af94261e5da286e59e85807f60845dd3c593556fb521cde7d3baa4f40f5af08b5a092782d01481ae28864acb7629bc951", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d0adaa704b79b7b27df2df2a76358838697a92e8f4febfbec05c28ba60a8f50f56b4e921d11d64e8431de50f1a2f64a14435b5ac22f51b243c7ae2a407d6b84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9204752eceb26830fa4c86cf43caa92527b6b6d30dac2e50bf41712a2db012ba40690b4fcaa1ed918b20c469db1c97f827eb9e5e69b590d3d0130e3a2de7ea2b"} 08:39:02 INFO - Running post-run listener: copy_logs_to_upload_dir 08:39:02 INFO - Copying logs to upload dir... 08:39:02 INFO - mkdir: /builds/slave/test/build/upload/logs 08:39:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2486.833115 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 26 secs) (at 2017-05-11 08:39:02.761037) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 08:39:02.764871) ========= 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.TXuC28l5Up/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.DUuSqT7anv/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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/fd9376c9182e26ab717d4881ef413af66832e46994dd46c7ed838d857748a57f8ac76a2dc8c70e258716ecf7fc34238407b1ab3c7c31c45b00ed06fcd083693a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49abef460f877b52402c8cfd867caa1af94261e5da286e59e85807f60845dd3c593556fb521cde7d3baa4f40f5af08b5a092782d01481ae28864acb7629bc951", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d0adaa704b79b7b27df2df2a76358838697a92e8f4febfbec05c28ba60a8f50f56b4e921d11d64e8431de50f1a2f64a14435b5ac22f51b243c7ae2a407d6b84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9204752eceb26830fa4c86cf43caa92527b6b6d30dac2e50bf41712a2db012ba40690b4fcaa1ed918b20c469db1c97f827eb9e5e69b590d3d0130e3a2de7ea2b"} build_url:https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011402 build_url: 'https://queue.taskcluster.net/v1/task/G98BLq8zSRmtZIeIu7xZ4Q/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/fd9376c9182e26ab717d4881ef413af66832e46994dd46c7ed838d857748a57f8ac76a2dc8c70e258716ecf7fc34238407b1ab3c7c31c45b00ed06fcd083693a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/49abef460f877b52402c8cfd867caa1af94261e5da286e59e85807f60845dd3c593556fb521cde7d3baa4f40f5af08b5a092782d01481ae28864acb7629bc951", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d0adaa704b79b7b27df2df2a76358838697a92e8f4febfbec05c28ba60a8f50f56b4e921d11d64e8431de50f1a2f64a14435b5ac22f51b243c7ae2a407d6b84", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9204752eceb26830fa4c86cf43caa92527b6b6d30dac2e50bf41712a2db012ba40690b4fcaa1ed918b20c469db1c97f827eb9e5e69b590d3d0130e3a2de7ea2b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 08:39:02.799762) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:39:02.800136) ========= 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.TXuC28l5Up/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.DUuSqT7anv/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.005407 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 08:39:02.849690) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 08:39:02.850055) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 08:39:02.850424) ========= ========= Total master_lag: 0.18 =========