builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0371 starttime: 1487105844.02 results: success (0) buildid: 20170214125711 builduid: 7982ca790af0483c85044c6f85a173f0 revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:24.017777) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:24.018193) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:24.018466) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:24.048027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:24.048381) ========= 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.NTPDszJ6Is/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.oWARTdi4YF/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-02-14 12:57:24-- 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% 18.9M=0.001s 2017-02-14 12:57:24 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134035 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:24.205944) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:24.206250) ========= 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.NTPDszJ6Is/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.oWARTdi4YF/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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.026774 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:24.256343) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-14 12:57:24.256702) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NTPDszJ6Is/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.oWARTdi4YF/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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-02-14 12:57:24,643 truncating revision to first 12 chars 2017-02-14 12:57:24,643 Setting DEBUG logging. 2017-02-14 12:57:24,644 attempt 1/10 2017-02-14 12:57:24,644 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fc9f6f7e8e86?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-14 12:57:25,762 unpacking tar archive at: mozilla-central-fc9f6f7e8e86/testing/mozharness/ program finished with exit code 0 elapsedTime=1.395104 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-14 12:57:25.674777) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:25.675117) ========= script_repo_revision: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:25.675558) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:25.675825) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-14 12:57:25.695280) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-02-14 12:57:25.695562) ========= /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.NTPDszJ6Is/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.oWARTdi4YF/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:57:26 INFO - MultiFileLogger online at 20170214 12:57:26 in /builds/slave/test 12:57:26 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 12:57:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:57:26 INFO - {'append_to_log': False, 12:57:26 INFO - 'base_work_dir': '/builds/slave/test', 12:57:26 INFO - 'blob_upload_branch': 'Firefox', 12:57:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:57:26 INFO - 'branch': 'Firefox', 12:57:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:57:26 INFO - 'config_files': ('talos/mac_config.py',), 12:57:26 INFO - 'default_actions': ('clobber', 12:57:26 INFO - 'read-buildbot-config', 12:57:26 INFO - 'download-and-extract', 12:57:26 INFO - 'populate-webroot', 12:57:26 INFO - 'create-virtualenv', 12:57:26 INFO - 'install', 12:57:26 INFO - 'run-tests'), 12:57:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:57:26 INFO - 'download_minidump_stackwalk': True, 12:57:26 INFO - 'download_symbols': 'ondemand', 12:57:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:57:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:57:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:57:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:57:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:57:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:57:26 INFO - 'installer_path': 'installer.exe', 12:57:26 INFO - 'log_level': 'info', 12:57:26 INFO - 'log_name': 'talos', 12:57:26 INFO - 'log_to_console': True, 12:57:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:57:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:57:26 INFO - 'opt_config_files': (), 12:57:26 INFO - 'pip_index': False, 12:57:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:57:26 INFO - 'cmd': ('bash', 12:57:26 INFO - '-c', 12:57:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:57:26 INFO - 'enabled': True, 12:57:26 INFO - 'halt_on_failure': False, 12:57:26 INFO - 'name': 'check_screen_resolution'},), 12:57:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:57:26 INFO - 'cmd': ('bash', 12:57:26 INFO - '-c', 12:57:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:57:26 INFO - 'enabled': True, 12:57:26 INFO - 'halt_on_failure': False, 12:57:26 INFO - 'name': 'check_screen_resolution'},), 12:57:26 INFO - 'run_cmd_checks_enabled': True, 12:57:26 INFO - 'sps_profile': False, 12:57:26 INFO - 'sps_profile_interval': 0, 12:57:26 INFO - 'suite': 'dromaeojs-e10s', 12:57:26 INFO - 'system_bits': '32', 12:57:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:57:26 INFO - 'title': 't-yosemite-r7-0371', 12:57:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:57:26 INFO - 'use_talos_json': True, 12:57:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:57:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:57:26 INFO - 'work_dir': 'build'} 12:57:26 INFO - [mozharness: 2017-02-14 20:57:26.191197Z] Running clobber step. 12:57:26 INFO - Running pre-action listener: _resource_record_pre_action 12:57:26 INFO - Running main action method: clobber 12:57:26 INFO - rmtree: /builds/slave/test/build 12:57:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:57:28 INFO - Running post-action listener: _resource_record_post_action 12:57:28 INFO - [mozharness: 2017-02-14 20:57:28.616219Z] Finished clobber step (success) 12:57:28 INFO - [mozharness: 2017-02-14 20:57:28.616433Z] Running read-buildbot-config step. 12:57:28 INFO - Running pre-action listener: _resource_record_pre_action 12:57:28 INFO - Running main action method: read_buildbot_config 12:57:28 INFO - Using buildbot properties: 12:57:28 INFO - { 12:57:28 INFO - "project": "", 12:57:28 INFO - "product": "firefox", 12:57:28 INFO - "script_repo_revision": "production", 12:57:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:57:28 INFO - "repository": "", 12:57:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:57:28 INFO - "buildid": "20170214125711", 12:57:28 INFO - "stage_platform": "macosx64", 12:57:28 INFO - "basedir": "/builds/slave/test", 12:57:28 INFO - "buildnumber": 141, 12:57:28 INFO - "platform": "yosemite_r7", 12:57:28 INFO - "mozci_request": { 12:57:28 INFO - "priority": -1, 12:57:28 INFO - "type": "trigger_all_talos_jobs", 12:57:28 INFO - "times": 1 12:57:28 INFO - }, 12:57:28 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 12:57:28 INFO - "slavebuilddir": "test", 12:57:28 INFO - "branch": "mozilla-central", 12:57:28 INFO - "revision": "fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd", 12:57:28 INFO - "slavename": "t-yosemite-r7-0371", 12:57:28 INFO - "builduid": "7982ca790af0483c85044c6f85a173f0", 12:57:28 INFO - "repo_path": "mozilla-central" 12:57:28 INFO - } 12:57:28 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg. 12:57:28 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json. 12:57:28 INFO - Running post-action listener: _resource_record_post_action 12:57:28 INFO - [mozharness: 2017-02-14 20:57:28.623305Z] Finished read-buildbot-config step (success) 12:57:28 INFO - [mozharness: 2017-02-14 20:57:28.623548Z] Running download-and-extract step. 12:57:28 INFO - Running pre-action listener: _resource_record_pre_action 12:57:28 INFO - Running main action method: download_and_extract 12:57:28 INFO - mkdir: /builds/slave/test/build/tests 12:57:28 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')]} 12:57:28 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json 12:57:28 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 12:57:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 12:57:29 INFO - Downloaded 1199 bytes. 12:57:29 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 12:57:29 INFO - Using the following test package requirements: 12:57:29 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 12:57:29 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:57:29 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 12:57:29 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:57:29 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 12:57:29 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:57:29 INFO - u'jsshell-mac.zip'], 12:57:29 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:57:29 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 12:57:29 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 12:57:29 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:57:29 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 12:57:29 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:57:29 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 12:57:29 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:57:29 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 12:57:29 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:57:29 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 12:57:29 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:57:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip 12:57:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:57:29 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip into memory 12:57:30 INFO - Expected file size: 51504348 12:57:30 INFO - Obtained file size: 51504348 12:57:46 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:57:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip 12:57:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:57:46 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.common.tests.zip into memory 12:57:47 INFO - Expected file size: 51504348 12:57:47 INFO - Obtained file size: 51504348 12:58:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.talos.tests.zip 12:58:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:58:02 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 12:58:03 INFO - Expected file size: 14403647 12:58:03 INFO - Obtained file size: 14403647 12:58:03 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')]} 12:58:03 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 12:58:03 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 12:58:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 12:58:08 INFO - Downloaded 70417704 bytes. 12:58:08 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 12:58:08 INFO - mkdir: /builds/slave/test/properties 12:58:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:58:08 INFO - Writing to file /builds/slave/test/properties/build_url 12:58:08 INFO - Contents: 12:58:08 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg 12:58:09 INFO - Running post-action listener: _resource_record_post_action 12:58:09 INFO - Running post-action listener: set_extra_try_arguments 12:58:09 INFO - [mozharness: 2017-02-14 20:58:09.359166Z] Finished download-and-extract step (success) 12:58:09 INFO - [mozharness: 2017-02-14 20:58:09.359312Z] Running populate-webroot step. 12:58:09 INFO - Running pre-action listener: _resource_record_pre_action 12:58:09 INFO - Running main action method: populate_webroot 12:58:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:58:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:58:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:58:09 INFO - '--tppagecycles', 12:58:09 INFO - '7'], 12:58:09 INFO - 'tests': ['tsvgm']}}, 12:58:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:58:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:58:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:58:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:58:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:58:09 INFO - 'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:58:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:58:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:58:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:58:09 INFO - 'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['damp', 'tps']}, 12:58:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:58:09 INFO - 'tests': ['damp', 'tps']}, 12:58:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['dromaeo_dom']}, 12:58:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:58:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['basic_compositor_video']}, 12:58:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:58:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['a11yr', 12:58:09 INFO - 'ts_paint', 12:58:09 INFO - 'tpaint', 12:58:09 INFO - 'sessionrestore', 12:58:09 INFO - 'sessionrestore_no_auto_restore', 12:58:09 INFO - 'tabpaint']}, 12:58:09 INFO - 'other-e10s': {'tests': ['a11yr', 12:58:09 INFO - 'ts_paint', 12:58:09 INFO - 'tpaint', 12:58:09 INFO - 'sessionrestore', 12:58:09 INFO - 'sessionrestore_no_auto_restore', 12:58:09 INFO - 'tabpaint']}, 12:58:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:58:09 INFO - 'ts_paint', 12:58:09 INFO - 'tpaint', 12:58:09 INFO - 'sessionrestore', 12:58:09 INFO - 'sessionrestore_no_auto_restore', 12:58:09 INFO - 'tabpaint']}, 12:58:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:58:09 INFO - 'ts_paint', 12:58:09 INFO - 'tpaint', 12:58:09 INFO - 'sessionrestore', 12:58:09 INFO - 'sessionrestore_no_auto_restore', 12:58:09 INFO - 'tabpaint']}, 12:58:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['a11yr', 12:58:09 INFO - 'ts_paint', 12:58:09 INFO - 'tpaint', 12:58:09 INFO - 'sessionrestore', 12:58:09 INFO - 'sessionrestore_no_auto_restore', 12:58:09 INFO - 'tabpaint']}, 12:58:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['a11yr', 12:58:09 INFO - 'ts_paint', 12:58:09 INFO - 'tpaint', 12:58:09 INFO - 'sessionrestore', 12:58:09 INFO - 'sessionrestore_no_auto_restore', 12:58:09 INFO - 'tabpaint']}, 12:58:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['tsvgx', 12:58:09 INFO - 'tsvgr_opacity', 12:58:09 INFO - 'tart', 12:58:09 INFO - 'tscrollx', 12:58:09 INFO - 'cart', 12:58:09 INFO - 'tsvg_static']}, 12:58:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:58:09 INFO - 'tsvgr_opacity', 12:58:09 INFO - 'tart', 12:58:09 INFO - 'tscrollx', 12:58:09 INFO - 'cart', 12:58:09 INFO - 'tsvg_static']}, 12:58:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:58:09 INFO - 'talos_options': ['--disable-e10s'], 12:58:09 INFO - 'tests': ['tp5o']}, 12:58:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:58:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:58:09 INFO - 'talos_options': ['--disable-e10s', 12:58:09 INFO - '--xperf_path', 12:58:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:58:09 INFO - 'tests': ['tp5n']}, 12:58:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:58:09 INFO - 'talos_options': ['--xperf_path', 12:58:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:58:09 INFO - 'tests': ['tp5n']}}, 12:58:09 INFO - 'talos.zip': {'path': '', 12:58:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:58:09 INFO - Running post-action listener: _resource_record_post_action 12:58:09 INFO - [mozharness: 2017-02-14 20:58:09.377647Z] Finished populate-webroot step (success) 12:58:09 INFO - [mozharness: 2017-02-14 20:58:09.377778Z] Running create-virtualenv step. 12:58:09 INFO - Running pre-action listener: _resource_record_pre_action 12:58:09 INFO - Running main action method: create_virtualenv 12:58:09 INFO - Creating virtualenv /builds/slave/test/build/venv 12:58:09 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 12:58:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:58:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:58:09 INFO - Using real prefix '/tools/python27' 12:58:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:58:10 INFO - Installing distribute.............................................................................................................................................................................................done. 12:58:13 INFO - Installing pip.................done. 12:58:13 INFO - Return code: 0 12:58:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:58:13 INFO - Reading from file tmpfile_stdout 12:58:13 INFO - Output received: 12:58:13 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) 12:58:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:58:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:13 INFO - 'HOME': '/Users/cltbld', 12:58:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:13 INFO - 'LOGNAME': 'cltbld', 12:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:13 INFO - 'MOZ_NO_REMOTE': '1', 12:58:13 INFO - 'NO_EM_RESTART': '1', 12:58:13 INFO - 'PAGER': '/bin/cat', 12:58:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:13 INFO - 'PWD': '/builds/slave/test', 12:58:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:13 INFO - 'SHELL': '/bin/bash', 12:58:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:13 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:13 INFO - 'USER': 'cltbld', 12:58:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:13 INFO - 'XPC_FLAGS': '0x0', 12:58:13 INFO - 'XPC_SERVICE_NAME': '0', 12:58:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:13 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 12:58:13 INFO - Cleaning up... 12:58:13 INFO - Return code: 0 12:58:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:58:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:58:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:13 INFO - 'HOME': '/Users/cltbld', 12:58:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:13 INFO - 'LOGNAME': 'cltbld', 12:58:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:13 INFO - 'MOZ_NO_REMOTE': '1', 12:58:13 INFO - 'NO_EM_RESTART': '1', 12:58:13 INFO - 'PAGER': '/bin/cat', 12:58:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:13 INFO - 'PWD': '/builds/slave/test', 12:58:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:13 INFO - 'SHELL': '/bin/bash', 12:58:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:13 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:13 INFO - 'USER': 'cltbld', 12:58:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:13 INFO - 'XPC_FLAGS': '0x0', 12:58:13 INFO - 'XPC_SERVICE_NAME': '0', 12:58:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:13 INFO - Downloading/unpacking psutil>=3.1.1 12:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:58:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:58:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:58:17 INFO - Installing collected packages: psutil 12:58:17 INFO - Running setup.py install for psutil 12:58:17 INFO - building 'psutil._psutil_osx' extension 12:58:17 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 12:58:18 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 12:58:18 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 12:58:18 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 12:58:18 INFO - building 'psutil._psutil_posix' extension 12:58:18 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 12:58:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:58:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:58:18 INFO - ^ 12:58:18 INFO - 1 warning generated. 12:58:18 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 12:58:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:58:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:58:18 INFO - Successfully installed psutil 12:58:18 INFO - Cleaning up... 12:58:18 INFO - Return code: 0 12:58:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12: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')]} 12:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:19 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')]} 12:58:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:19 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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:19 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 12:58:19 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 12:58:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:19 INFO - 'HOME': '/Users/cltbld', 12:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:19 INFO - 'LOGNAME': 'cltbld', 12:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:19 INFO - 'MOZ_NO_REMOTE': '1', 12:58:19 INFO - 'NO_EM_RESTART': '1', 12:58:19 INFO - 'PAGER': '/bin/cat', 12:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:19 INFO - 'PWD': '/builds/slave/test', 12:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:19 INFO - 'SHELL': '/bin/bash', 12:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:19 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:19 INFO - 'USER': 'cltbld', 12:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:19 INFO - 'XPC_FLAGS': '0x0', 12:58:19 INFO - 'XPC_SERVICE_NAME': '0', 12:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:58:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:58:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:58:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:58:23 INFO - Installing collected packages: mozsystemmonitor 12:58:23 INFO - Running setup.py install for mozsystemmonitor 12:58:23 INFO - Successfully installed mozsystemmonitor 12:58:23 INFO - Cleaning up... 12:58:23 INFO - Return code: 0 12:58:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:58:23 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')]} 12:58:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:23 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')]} 12:58:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:23 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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:23 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 12:58:23 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 12:58:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:23 INFO - 'HOME': '/Users/cltbld', 12:58:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:23 INFO - 'LOGNAME': 'cltbld', 12:58:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:23 INFO - 'MOZ_NO_REMOTE': '1', 12:58:23 INFO - 'NO_EM_RESTART': '1', 12:58:23 INFO - 'PAGER': '/bin/cat', 12:58:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:23 INFO - 'PWD': '/builds/slave/test', 12:58:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:23 INFO - 'SHELL': '/bin/bash', 12:58:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:23 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:23 INFO - 'USER': 'cltbld', 12:58:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:23 INFO - 'XPC_FLAGS': '0x0', 12:58:23 INFO - 'XPC_SERVICE_NAME': '0', 12:58:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:23 INFO - Downloading/unpacking jsonschema==2.5.1 12:58:23 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 12:58:23 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 12:58:23 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 12:58:23 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 12:58:23 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 12:58:23 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 12:58:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:58:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:58:26 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 12:58:26 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 12:58:26 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 12:58:26 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 12:58:26 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 12:58:26 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 12:58:26 INFO - Downloading functools32-3.2.3-2.tar.gz 12:58:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:58:26 INFO - warning: no files found matching '*.txt' 12:58:26 INFO - no previously-included directories found matching 'build' 12:58:26 INFO - no previously-included directories found matching 'dist' 12:58:26 INFO - no previously-included directories found matching '.git*' 12:58:26 INFO - Installing collected packages: jsonschema, functools32 12:58:26 INFO - Running setup.py install for functools32 12:58:27 INFO - warning: no files found matching '*.txt' 12:58:27 INFO - no previously-included directories found matching 'build' 12:58:27 INFO - no previously-included directories found matching 'dist' 12:58:27 INFO - no previously-included directories found matching '.git*' 12:58:27 INFO - Successfully installed jsonschema functools32 12:58:27 INFO - Cleaning up... 12:58:27 INFO - Return code: 0 12:58:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:58:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:58:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:27 INFO - 'HOME': '/Users/cltbld', 12:58:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:27 INFO - 'LOGNAME': 'cltbld', 12:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:27 INFO - 'MOZ_NO_REMOTE': '1', 12:58:27 INFO - 'NO_EM_RESTART': '1', 12:58:27 INFO - 'PAGER': '/bin/cat', 12:58:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:27 INFO - 'PWD': '/builds/slave/test', 12:58:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:27 INFO - 'SHELL': '/bin/bash', 12:58:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:27 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:27 INFO - 'USER': 'cltbld', 12:58:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:27 INFO - 'XPC_FLAGS': '0x0', 12:58:27 INFO - 'XPC_SERVICE_NAME': '0', 12:58:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:58:27 INFO - Cleaning up... 12:58:27 INFO - Return code: 0 12:58:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:58:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:58:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:58:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:27 INFO - 'HOME': '/Users/cltbld', 12:58:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:27 INFO - 'LOGNAME': 'cltbld', 12:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:27 INFO - 'MOZ_NO_REMOTE': '1', 12:58:27 INFO - 'NO_EM_RESTART': '1', 12:58:27 INFO - 'PAGER': '/bin/cat', 12:58:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:27 INFO - 'PWD': '/builds/slave/test', 12:58:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:27 INFO - 'SHELL': '/bin/bash', 12:58:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:27 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:27 INFO - 'USER': 'cltbld', 12:58:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:27 INFO - 'XPC_FLAGS': '0x0', 12:58:27 INFO - 'XPC_SERVICE_NAME': '0', 12:58:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:27 INFO - Downloading/unpacking blobuploader==1.2.4 12:58:27 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 12:58:27 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 12:58:27 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 12:58:27 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 12:58:27 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 12:58:27 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 12:58:30 INFO - Downloading blobuploader-1.2.4.tar.gz 12:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:58:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:58:30 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 12:58:30 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 12:58:30 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 12:58:30 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 12:58:30 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 12:58:30 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 12:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:58:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:31 INFO - Downloading docopt-0.6.1.tar.gz 12:58:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:58:31 INFO - Installing collected packages: blobuploader, requests, docopt 12:58:31 INFO - Running setup.py install for blobuploader 12:58:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:58:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:58:31 INFO - Running setup.py install for requests 12:58:32 INFO - Running setup.py install for docopt 12:58:32 INFO - Successfully installed blobuploader requests docopt 12:58:32 INFO - Cleaning up... 12:58:32 INFO - Return code: 0 12:58:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:58: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')]} 12:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58: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')]} 12:58:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:32 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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:32 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 12:58:32 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 12:58:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:32 INFO - 'HOME': '/Users/cltbld', 12:58:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:32 INFO - 'LOGNAME': 'cltbld', 12:58:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:32 INFO - 'MOZ_NO_REMOTE': '1', 12:58:32 INFO - 'NO_EM_RESTART': '1', 12:58:32 INFO - 'PAGER': '/bin/cat', 12:58:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:32 INFO - 'PWD': '/builds/slave/test', 12:58:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:32 INFO - 'SHELL': '/bin/bash', 12:58:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:32 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:32 INFO - 'USER': 'cltbld', 12:58:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:32 INFO - 'XPC_FLAGS': '0x0', 12:58:32 INFO - 'XPC_SERVICE_NAME': '0', 12:58:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:58:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-quXJf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:58:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-cog9jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:58:32 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-_2ZN9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Usw9w2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-fW5sY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-CmI6uM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-PTVE6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-xr6Leb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-TazV6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-hnq4Th-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-eMKxyW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:58:33 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-4xZqN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:58:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-GIu3Cu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:58:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-bRPt7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:58:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Ox6UN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:58:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-W3WqTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:58:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:58:34 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-HRT17l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:58:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:58:34 INFO - Running setup.py install for manifestparser 12:58:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:58:34 INFO - Running setup.py install for mozcrash 12:58:34 INFO - Running setup.py install for mozdebug 12:58:34 INFO - Running setup.py install for mozdevice 12:58:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:58:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:58:35 INFO - Running setup.py install for mozfile 12:58:35 INFO - Running setup.py install for mozhttpd 12:58:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:58:35 INFO - Running setup.py install for mozinfo 12:58:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:58:35 INFO - Running setup.py install for mozInstall 12:58:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:58:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:58:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:58:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:58:35 INFO - Running setup.py install for mozleak 12:58:35 INFO - Running setup.py install for mozlog 12:58:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:58:36 INFO - Running setup.py install for moznetwork 12:58:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:58:36 INFO - Running setup.py install for mozprocess 12:58:36 INFO - Running setup.py install for mozprofile 12:58:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:58:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:58:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:58:36 INFO - Running setup.py install for mozrunner 12:58:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:58:36 INFO - Running setup.py install for mozscreenshot 12:58:36 INFO - Running setup.py install for moztest 12:58:36 INFO - Running setup.py install for mozversion 12:58:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:58:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:58:37 INFO - Cleaning up... 12:58:37 INFO - Return code: 0 12:58:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:58: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')]} 12:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58: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')]} 12:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:37 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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:37 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 12:58:37 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 12:58:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:37 INFO - 'HOME': '/Users/cltbld', 12:58:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:37 INFO - 'LOGNAME': 'cltbld', 12:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:37 INFO - 'MOZ_NO_REMOTE': '1', 12:58:37 INFO - 'NO_EM_RESTART': '1', 12:58:37 INFO - 'PAGER': '/bin/cat', 12:58:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:37 INFO - 'PWD': '/builds/slave/test', 12:58:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:37 INFO - 'SHELL': '/bin/bash', 12:58:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:37 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:37 INFO - 'USER': 'cltbld', 12:58:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:37 INFO - 'XPC_FLAGS': '0x0', 12:58:37 INFO - 'XPC_SERVICE_NAME': '0', 12:58:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:58:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-b1M2J1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:58:37 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)) 12:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:58:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-p6Mhtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:58:37 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)) 12:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:58:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-fbA8r5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:58:37 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)) 12:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:58:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-6bvB1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:58:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:58:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-tkabW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:58:37 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)) 12:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:58:37 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-7gsR2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:58:38 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-1FJWYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:58:38 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-oYd76G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:58:38 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-O7evFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:58:38 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-VOryaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:58:38 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-MotdaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:58:38 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-93GYC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:58:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-TVaRLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:58:38 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-GRYt72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:58:38 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)) 12:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:58:38 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Yanioh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:58:39 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)) 12:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:58:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-zgD2Yb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:58:39 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)) 12:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:58:39 INFO - Running setup.py (path:/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/pip-Juc1Oi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:58:39 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)) 12:58:39 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)) 12:58:39 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)) 12:58:39 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)) 12:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:58:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:58:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:58: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 12:58: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 12:58: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 12:58: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 12:58: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 12:58: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 12:58:41 INFO - Downloading blessings-1.6.tar.gz 12:58:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:58:41 INFO - Installing collected packages: blessings 12:58:41 INFO - Running setup.py install for blessings 12:58:42 INFO - Successfully installed blessings 12:58:42 INFO - Cleaning up... 12:58:42 INFO - Return code: 0 12:58:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:58:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:58:42 INFO - Reading from file tmpfile_stdout 12:58:42 INFO - Current package versions: 12:58:42 INFO - blessings == 1.6 12:58:42 INFO - blobuploader == 1.2.4 12:58:42 INFO - docopt == 0.6.1 12:58:42 INFO - functools32 == 3.2.3-2 12:58:42 INFO - jsonschema == 2.5.1 12:58:42 INFO - manifestparser == 1.1 12:58:42 INFO - mozInstall == 1.12 12:58:42 INFO - mozcrash == 1.0 12:58:42 INFO - mozdebug == 0.1 12:58:42 INFO - mozdevice == 0.49 12:58:42 INFO - mozfile == 1.2 12:58:42 INFO - mozhttpd == 0.7 12:58:42 INFO - mozinfo == 0.9 12:58:42 INFO - mozleak == 0.1 12:58:42 INFO - mozlog == 3.4 12:58:42 INFO - moznetwork == 0.27 12:58:42 INFO - mozprocess == 0.24 12:58:42 INFO - mozprofile == 0.28 12:58:42 INFO - mozrunner == 6.13 12:58:42 INFO - mozscreenshot == 0.1 12:58:42 INFO - mozsystemmonitor == 0.3 12:58:42 INFO - moztest == 0.8 12:58:42 INFO - mozversion == 1.4 12:58:42 INFO - psutil == 3.1.1 12:58:42 INFO - requests == 1.2.3 12:58:42 INFO - wsgiref == 0.1.2 12:58:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:58:42 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')]} 12:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:42 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')]} 12:58:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:42 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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:42 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 12:58:42 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 12:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:42 INFO - 'HOME': '/Users/cltbld', 12:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:42 INFO - 'LOGNAME': 'cltbld', 12:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:42 INFO - 'MOZ_NO_REMOTE': '1', 12:58:42 INFO - 'NO_EM_RESTART': '1', 12:58:42 INFO - 'PAGER': '/bin/cat', 12:58:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:42 INFO - 'PWD': '/builds/slave/test', 12:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:42 INFO - 'SHELL': '/bin/bash', 12:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:42 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:42 INFO - 'USER': 'cltbld', 12:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:42 INFO - 'XPC_FLAGS': '0x0', 12:58:42 INFO - 'XPC_SERVICE_NAME': '0', 12:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 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)) 12:58:42 INFO - Cleaning up... 12:58:42 INFO - Return code: 0 12:58:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:58:42 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')]} 12:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:42 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')]} 12:58:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:42 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 0x1016c9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1016cc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc2a1575600>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, '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.NTPDszJ6Is/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.oWARTdi4YF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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 12:58:42 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 12:58:42 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 12:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:42 INFO - 'HOME': '/Users/cltbld', 12:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:42 INFO - 'LOGNAME': 'cltbld', 12:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:42 INFO - 'MOZ_NO_REMOTE': '1', 12:58:42 INFO - 'NO_EM_RESTART': '1', 12:58:42 INFO - 'PAGER': '/bin/cat', 12:58:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:42 INFO - 'PWD': '/builds/slave/test', 12:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:42 INFO - 'SHELL': '/bin/bash', 12:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:58:42 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:42 INFO - 'USER': 'cltbld', 12:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:42 INFO - 'XPC_FLAGS': '0x0', 12:58:42 INFO - 'XPC_SERVICE_NAME': '0', 12:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:58:42 INFO - Cleaning up... 12:58:42 INFO - Return code: 0 12:58:42 INFO - Running post-action listener: _resource_record_post_action 12:58:42 INFO - Running post-action listener: _start_resource_monitoring 12:58:42 INFO - Starting resource monitoring. 12:58:42 INFO - [mozharness: 2017-02-14 20:58:42.986345Z] Finished create-virtualenv step (success) 12:58:42 INFO - [mozharness: 2017-02-14 20:58:42.987371Z] Running install step. 12:58:42 INFO - Running pre-action listener: _resource_record_pre_action 12:58:42 INFO - Running main action method: install 12:58:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:58:43 INFO - Reading from file tmpfile_stdout 12:58:43 INFO - Detecting whether we're running mozinstall >=1.0... 12:58:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:58:43 INFO - Reading from file tmpfile_stdout 12:58:43 INFO - Output received: 12:58:43 INFO - Usage: mozinstall [options] installer 12:58:43 INFO - Options: 12:58:43 INFO - -h, --help show this help message and exit 12:58:43 INFO - -d DEST, --destination=DEST 12:58:43 INFO - Directory to install application into. [default: 12:58:43 INFO - "/builds/slave/test"] 12:58:43 INFO - --app=APP Application being installed. [default: firefox] 12:58:43 INFO - mkdir: /builds/slave/test/build/application 12:58:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:58:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:59:06 INFO - Reading from file tmpfile_stdout 12:59:06 INFO - Output received: 12:59:06 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:59:06 INFO - Running post-action listener: _resource_record_post_action 12:59:06 INFO - [mozharness: 2017-02-14 20:59:06.325115Z] Finished install step (success) 12:59:06 INFO - [mozharness: 2017-02-14 20:59:06.325291Z] Running run-tests step. 12:59:06 INFO - Running pre-action listener: _resource_record_pre_action 12:59:06 INFO - Running main action method: run_tests 12:59:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:59:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:59:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:59:06 INFO - 2017-02-14 12:59:06.339 screenresolution[1622:7468] starting screenresolution argv=screenresolution get 12:59:06 INFO - 2017-02-14 12:59:06.342 screenresolution[1622:7468] Display 0: 1600x1200x32@60 12:59:06 INFO - 2017-02-14 12:59:06.348 screenresolution[1623:7470] starting screenresolution argv=screenresolution list 12:59:06 INFO - Available Modes on Display 0 12:59:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:59:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:59:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:59:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:59:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:59:06 INFO - Intel Iris: 12:59:06 INFO - Chipset Model: Intel Iris 12:59:06 INFO - Type: GPU 12:59:06 INFO - Bus: Built-In 12:59:06 INFO - VRAM (Dynamic, Max): 1536 MB 12:59:06 INFO - Vendor: Intel (0x8086) 12:59:06 INFO - Device ID: 0x0a2e 12:59:06 INFO - Revision ID: 0x0009 12:59:06 INFO - Displays: 12:59:06 INFO - Display: 12:59:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:59:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:59:06 INFO - Display Serial Number: 1600x1200 60 12:59:06 INFO - Main Display: Yes 12:59:06 INFO - Mirror: Off 12:59:06 INFO - Online: Yes 12:59:06 INFO - Rotation: Supported 12:59:06 INFO - Return code: 0 12:59:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:59:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:59:06 INFO - Python 2.7.3 12:59:06 INFO - Return code: 0 12:59:06 INFO - grabbing minidump binary from tooltool 12:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:59:06 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 0x1014a7d30>, '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 0x1013cedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10149cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:59:06 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 12:59:06 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 12:59:06 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 12:59:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:59:06 INFO - Return code: 0 12:59:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:59:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:59:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:59:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:59:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:59:06 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0371', '--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 12:59:06 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0371 --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 12:59:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NTPDszJ6Is/Render', 12:59:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:06 INFO - 'HOME': '/Users/cltbld', 12:59:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:06 INFO - 'LOGNAME': 'cltbld', 12:59:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:59:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:06 INFO - 'MOZ_NO_REMOTE': '1', 12:59:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:59:06 INFO - 'NO_EM_RESTART': '1', 12:59:06 INFO - 'PAGER': '/bin/cat', 12:59:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:06 INFO - 'PWD': '/builds/slave/test', 12:59:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:59:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:06 INFO - 'SHELL': '/bin/bash', 12:59:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oWARTdi4YF/Listeners', 12:59:06 INFO - 'TMPDIR': '/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/', 12:59:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:06 INFO - 'USER': 'cltbld', 12:59:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:06 INFO - 'XPC_FLAGS': '0x0', 12:59:06 INFO - 'XPC_SERVICE_NAME': '0', 12:59:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:06 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://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0371', '--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 12:59:06 INFO - mozversion application_buildid: 20170214071445 12:59:06 INFO - mozversion application_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 12:59:06 INFO - mozversion application_display_name: Nightly 12:59:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:59:06 INFO - mozversion application_name: Firefox 12:59:06 INFO - mozversion application_remotingname: firefox 12:59:06 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:59:06 INFO - mozversion application_vendor: Mozilla 12:59:06 INFO - mozversion application_version: 54.0a1 12:59:06 INFO - mozversion platform_buildid: 20170214071445 12:59:06 INFO - mozversion platform_changeset: fc9f6f7e8e86f00af60ff1ecc72eaf854a6b1ddd 12:59:06 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:59:06 INFO - mozversion platform_version: 54.0a1 12:59:06 INFO - using testdate: 1487105946 12:59:06 INFO - actual date: 1487105946 12:59:06 INFO - starting webserver on 'localhost:49229' 12:59:06 INFO - SUITE-START | Running 2 tests 12:59:06 INFO - TEST-START | dromaeo_css 12:59:06 INFO - operating with platform_type : mac_ 12:59:06 INFO - Initialising browser for dromaeo_css test... 12:59:06 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpus8rsr/profile http://localhost:49229/getInfo.html) 12:59:09 INFO - PROCESS | 1633 | 2017-02-14 12:59:09.943 plugin-container[1635:7636] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4953, name = 'com.apple.tsm.portname' 12:59:09 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:59:09 INFO - PROCESS | 1633 | 2017-02-14 12:59:09.947 plugin-container[1635:7636] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 12:59:09 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:59:09 INFO - PROCESS | 1633 | 2017-02-14 12:59:09.947 plugin-container[1635:7636] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:59:10 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 12:59:10 INFO - PROCESS | 1633 | colorDepth:24 12:59:10 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 12:59:10 INFO - PROCESS | 1633 | __metrics 12:59:10 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 12:59:10 INFO - TEST-INFO | 1633: exit 0 12:59:10 INFO - Browser initialized. 12:59:10 INFO - Running cycle 1/1 for dromaeo_css test... 12:59:10 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpus8rsr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:59:13 INFO - PROCESS | 1636 | 2017-02-14 12:59:13.541 plugin-container[1637:7764] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 12:59:13 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:59:13 INFO - PROCESS | 1636 | 2017-02-14 12:59:13.542 plugin-container[1637:7764] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 12:59:13 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:59:13 INFO - PROCESS | 1636 | 2017-02-14 12:59:13.542 plugin-container[1637:7764] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:59:23 INFO - PROCESS | 1636 | RSS: Main: 234164224 12:59:23 INFO - PROCESS | 1636 | 13:02:26 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 13:02:27 INFO - PROCESS | 1636 | RSS: Main: 255299584 13:02:27 INFO - PROCESS | 1636 | 13:04:11 INFO - PROCESS | 1636 | ************************* 13:04:11 INFO - PROCESS | 1636 | A coding exception was thrown and uncaught in a Task. 13:04:11 INFO - PROCESS | 1636 | 13:04:11 ERROR - PROCESS | 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:04:11 INFO - PROCESS | 1636 | Full stack: 13:04:11 INFO - PROCESS | 1636 | ************************* 13:05:30 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 13:05:30 INFO - PROCESS | 1636 | RSS: Main: 260509696 13:05:30 INFO - PROCESS | 1636 | 13:08:33 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 13:08:33 INFO - PROCESS | 1636 | RSS: Main: 272343040 13:08:33 INFO - PROCESS | 1636 | 13:11:35 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 13:11:36 INFO - PROCESS | 1636 | RSS: Main: 264278016 13:11:36 INFO - PROCESS | 1636 | 13:14:39 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 13:14:39 INFO - PROCESS | 1636 | RSS: Main: 271548416 13:14:39 INFO - PROCESS | 1636 | 13:17:42 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 13:17:43 INFO - PROCESS | 1636 | RSS: Main: 272310272 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - PROCESS | 1636 | __start_tp_report 13:17:43 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 13:17:43 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 13:17:43 INFO - PROCESS | 1636 | |i|pagename|runs| 13:17:43 INFO - PROCESS | 1636 | |0;dojo.html;3014.9246268843276;3370.713489353406;3374.156460884779;3383.999999999999;3395.660433956606;4308.719933204347;5452.127659574468;5479.0959491683825;5626.521745651619;5679.943200567998;5537.916931246024;5569.526590239834;5625.859353516164;5631.915521267184;5700.686462244575;4619.930701039487;5021.372328458942;5075.111855425304;5264.578833693315;5275.920861187085;5065.088284108859;5249.658772179796;5274.314339135907;5293.391260005091;5307.708076055816;5030.899382012376;5050.141475949097;5126.897462050775;5234.81678141267;5278.445763194886;5095.286659867622;5141.820036298712;5186.7925282988635;5203.713795741232;5329.227262046981;5121.948780512222;5134.306868572769;5143.8456846294675;5146.691198528063;5153;4866.294387313855;5503.669779813184;5552.583556233259;5622.409646987048;5696.572757043238;6110.892920173586;6114.388561143895;6114.601521188683;6153.907691384633;6172.444479996821;5537.726322945705;6018.3981601839905;6231.937680623181;6241.843953901143;6250.9373406521;5829.795957141479;5833.445822646841;5835.649861008268;5851.999999999957;5863.442972917566;6102.664353460514;6131.509479241739;6166.630002199882;6184.103305020747;6213.068039793977;6123.846903827351;6177.907331390033;6191.19514463126;6203.379662033761;6219.191505104398;5976;6204.751809927552;6217.502599792006;6268.780592679234;6280.371962803683;5098.592112631055;5889.882202355929;6123.709696704001;6142.3550527194;6269.843253918689;5424.240606315119;5963.761449542057;6222.253329600387;6261.311255761862;6272.8118156455375;4960.404751429852;4970.552650261494;4978.377702787152;4992.176290912032;5025.396952365666;3718.2749363874327;4334;4369.628581570539;4395.252807022814;4436;5803.883922321615;5854.824355269348;5874.73563689635;5894.705264736746;5917.112433135064;6221.2845522765465;6242.687865606701;6246;6262.029385445263;6281.685915704196;5541.722913854291;5642.04085305499;5646.6894320811925;5789.536837053026;5843.5909486336395;5403.459654034565;5411.594130440233;5438.945610543923;5489.752961116679;5501.284832971688;6251.812445626639;6265.686715664198;6268.780592679234;6270.843228919313;6305.653189074553;5985.281766188085;6242.094896240111;6244.937550624436;6253.937460625336;6257.342978987141;6113.932424433873;6127.647695153045;6160.322364539987;6165.876682466415;6204.255489341127;6018.21763170785;6021.819345419644;6036.245469316335;6044.304904935989;6057.97014507526;6042;6048.727807248596;6113.083037544404;6129.35641757609;6131.754729810757;4931.260310953386;5847.590668653153;5856.209411729362;6039.849003774765;6070.3929607038945;4253.4257875187695;4979.825706100269;5003.77483013258;5223.268742375994;6064.181335519648;5229.581633469389;5726.9713651431475;5765;5775.81595772874;5931.377205393372;6112.351504965139;6150.600210986271;6155.353687862889;6165.321814600301;6165.5375846810775;5933.940660593512;5999.520038397005;6017.15759793638;6029.60807547508;6048.727807248596 13:17:43 INFO - PROCESS | 1636 | |1;ext.html;486.33615115367525;489.76736050376053;506.939167299924;512.9330991537599;543.6221825831049;37429.68996085138;39118.60881391189;39238.01904952374;39279.62521311755;39301.82094537161;40651;41245.38131928023;41373.793131034305;41413.37879931804;41544.58455415445;9303.069693030711;9554.761130971712;9568.760780980496;9578.137967582916;10897.509612067475;11024.779504409908;11196.99999999996;11237.213395062348;11241.943790281037;11251.54993800247;11230.10159187263;11236.550537978472;11243.325400475956;11247.60633377832;11282.323060616349;9495.762605934839;11139.387333696643;11280.2667826591;11311.038561722264;11316.151288653384;10999.670009899715;11019.338839669645;11076.224664273504;11079.169062320278;11110.388928609003;36641.26717465636;36935.445968310494;36962.630373696185;37000;37050.62949370499;3695.464157697119;3745.834081701889;3770.981145094257;3788.337041017811;3931.842726290945;27050.782714777684;27157.049503267488;27824.74788634516;28026;28173.718262817314;2985.820850748962;3048.6189226346705;3060.6021217241837;3178.761592880543;3209.4864821628425;402261;403512.9824350889;406306;407565;408674;44257.33613995793;45528.54471455243;54616.45383546193;55082.62293442696;55248.72375638096;55435.16847247294;56035.43964560384;56656;59954;59987.70006150028;13415.597532074053;13512.391942362618;13552.051356404956;13563.254021028939;13612.115212511353;10133.797324053477;10203.897961020295;10270.383776973405;10340.224483163793;10343.27597068198;14764.778528322084;16134.75797863033;17075;17091.914540427217;17306.567335816704;7703.845923081507;7734.729284475016;7783.805404864924;7785.3771698264;7814.140444551095;777.6461709921972;827.9461834980707;831.8170002599539;836.786619412046;856.8200677857719;25469.10858119957;27458.725412746015;27828.860855695995;28697.139085827042;28852.42295154085;972.2270794718216;1012.5949620151998;1019.9490025498695;1055.3404122423485;1065.589747947046;11520.827187592193;13451.730965380637;14138.929305353407;14738.55784326472;15070.246487675791;571413;572931;577767;578019;578686;43168;43255;43657.781711091244;43685;43695.344569831475;1311.3836496846468;1351.2500562187884;1357.2263809628614;1357.8642135786342;1359.6940688345242;21659.783402166406;24075.879620601783;26046.73953260443;26447.33881652974;28894;47297.763511182224;47534.04931901273;47571.524284756706;47683.52316476929;47720;494055;497814;498311;498713;500280;23976.28071157868;24507.38731531726;24693.629595556442;25145.371365716004;25587.488250234517;39821.00447488836;40708.79645601753;40779.38830917479;41054;41204.38193427161;32426.513602296458;33605.49591756175;34157.31685366329;34306.14234644154;35138.82430587933;27350.691416469417;33711.662883371835;33759;34409.65590344165;34813.477797832544;29832;32689.019329420156;32696.182595435304;32880.67119328872;34533;23704.644430333214;24729.010839566934;27675;30560.847195764767;30642.540361895037;23118;23150.884245578665;23360.6495902565;23473.76526234716;23541.293761187193 13:17:43 INFO - PROCESS | 1636 | |2;jquery.html;347.95650543682035;388.5531638615592;394.83076923076925;402.31405453701444;425.64671322802076;15275.312610932508;15977.201139943014;18300.81044732093;19336.356409705157;20184.495387615316;10374.221933354991;12432.937835310811;12521.436535355908;17159.74260386092;20293.391198264075;4448.33275008748;5303.337082864642;5493.340799104093;5516.641418307817;5535.363433205172;4358.302671572549;4522.3216517522305;4626.4910859805395;5399.19012148177;5414.83755487336;4829.492903245144;5301.628885977983;5430.91853622196;5435.483629055253;5492.670439773627;5160.225966105114;5777.62445441045;5875.38308477608;6078.665673387962;6141.17094192285;5796.1595568642315;5982.670953097578;5997.970010149965;6058.848528786772;6080.179175811253;13520.59438216855;13593.048486605941;13659.521916732967;13673.931630341785;13695.657608559766;2514.396544829246;2531.405119796846;2535.657686212356;2555.821092523524;2567.7303883092195;8773.561321933877;9394.530273486298;9793.412395256308;10140.645077422254;10430.165586753117;1683.7895263092114;1698.1678977301158;1757.8417942385247;1775.7691500104904;1807.268056437145;75393.87449557296;121044;122386;126175.3691231538;127771.36114319462;11713.590024349107;11950.103742219368;12041.518339266506;12061.577844775566;12111.515539378503;15662.765058524132;15671.041119860018;15719;15731.68536629261;15758.921205393899;7714.344280736088;7908.485948413335;8026.077001144827;8038.919610803934;8087;5990.850228744229;7306.890396644055;7307.232740562271;7466.066741657293;7486.438517111238;4005.559388467266;4856.635752318591;4870.512948705101;4873.31773551703;4946.233333833262;2048.1704909511673;2063.690446433047;2080.91676332948;2084.114251443131;2090.4878304815416;1010.4543546484963;1014.1582486536157;1028.388109075114;1031.4533297352416;1059.989400105989;21136.788632113483;21558;21619.027143778567;21621.432463643323;21664.675029874565;928.03020843219;971.9805603887883;1020.2144348851491;1052.7210289273348;1059;8977.50623715689;11235.325880447199;11300.71748206285;11303.321800691985;11357.204995650225;539583;551904;559612;571216;574659;104768;136812.31593841966;137903;139954;150375.24812375868;938.9671361502315;982.7543114221445;1011.4790882695418;1011.6408674920444;1013.2957594471918;19928.800711992695;19962.201511939358;20291.883946383092;20338.59322813565;20485.897570512054;49156.508434915195;54515.637109071;58050.709746451;58311.416885832296;58680.70659646674;82427;83098.58450707707;84029.57985210035;84563.5771821137;84964.57517712371;8024.2376974187355;8130.146334635015;8476.533790641573;8513.148685131437;8617.138286171332;11850.511392607648;12813.74372512526;12880.871191287968;13221.669458263621;13257.867421325664;9970.052844979715;10605.363678179334;10609.151267898395;11547.826782598437;11619.186656934271;10776.568937242422;11121.110311175249;11221.270617409842;11358.886411135783;11441.027512661518;9622;11205.66383008511;11379.487923043318;11591.130665200313;11739;7152.141742990874;7404.703811847466;7537.2085930976455;7837.255460731221;8789.648414063366;9698.6120555177;9729.318947673879;10142.645007425;11410.258333208314;11479.713007174887 13:17:43 INFO - PROCESS | 1636 | |3;mootools.html;1437.3819257719183;1450.5938337265557;1451.8620731030549;1474.7419201639714;1486.2791546100143;7356.926430735691;7590.392768578514;8179.386546009051;8641.740747777569;8644.438111522746;8016.679332826695;8369;8371.28844048255;8398.1601839816;8402.479256223123;1764.4265613675543;2053.609814135317;2142.682250415991;2244.38279473145;2303.4126297794123;2269.897854596547;2270.818334533233;2285.6342985122383;2290.725112986444;2291.0606651272983;1931.2757715856553;2267.9659805102856;2271.136967952176;2284.668723035159;2290.6105961986505;2245.741739699931;2247.5280191159877;2262.671912572684;2266.1275408967585;2272.6363781794917;2244.438890277431;2301.056566807601;2301.620232661609;2303.8272129590346;2323.6514522821544;6632.436242919358;8358.247757701836;8379.078301386904;8432.40973131881;8447.831043379098;5067.0879241736475;5184.585233181337;5203.869903252411;5214.478552144793;5231.058409486291;3067.969320306813;3211.2614098757417;3488.7383446241633;3490.127468132967;3496.5629296337956;1161.7569200954981;1175.3300618647374;1228.9385530723428;1235.6663700800734;1244.4151248912997;10166.08505234525;10456.52945617449;10571.682849514527;10619.628313009085;10689.412082335468;4432.667549933768;4639.141758774637;4712.69367491112;4713.882152946204;4725.763711814396;9553.522323883779;9579.425234485952;9615.375000624937;9633.036696330451;9633.90366096344;5261.894762104813;5263.315768950012;5267.868303292372;5273.683578985273;5274.261603375454;4051.8946127879085;4576.359309696645;4619.745913974696;4696.9901471183175;4802.615790736732;3764.153065560282;3813.122981714221;3841.058940559525;3909.7849618271266;3932.390479475686;1162.4304090995352;1227.8948945948719;1247.4822948476462;1266.1896386312767;1271.6566527037833;721.7870728135213;831.846108469935;887.454215657379;893.6380765789736;918.7473444802732;5377.5160235578205;5561.888762224733;5611.494965453129;5762.394948530344;5972.686009078083;926.305271046715;946.9621215151454;976.1653786012998;977.5649835822991;1012.6607586458472;3580.9104772380897;3598.2263813279747;3709.3694072007825;3719.20037192005;3820.2359528094494;9793.804123917482;10004.299699021145;10106.494675266207;10122.645707400206;10133.898661013443;9364;9917.553710083064;10047.145992590711;10070.798584028278;10079.193664506822;7636.656350464132;7929.969104016441;7939.2060793920145;7967.203279671987;7984.800379990546;5412.75642596092;5548.306461692288;5600.691961942132;5622.7469763861545;5748.425157484218;8818.074102219174;9171.266298695955;9180.770480738034;9207.079292070739;9244.583993720164;8859.911400885909;9152.725418237464;9272.860907086533;9400.388974716621;9418.670346537838;6258.249010118815;7088.716451342095;7150.856982860209;7233.891491627524;7304.598247096461;7906.486078404885;8014.078380986028;8017.919820801717;8027.1972802719265;8047.275745182962;7041.612711300722;7062.329078737512;7121.145462544528;7190.388816950618;7239.782806515813;6873.912159515274;6970.23327433992;7007.7196912122945;7043.859122817617;7075.292470752884;6098.451139397475;6177.876442471035;6213.782517611862;6221.4089661482085;6231.937680623317;4860.1008813369635;4944.159492886146;4955.900881982268;5004.274380214922;5013.874653133701;4433.445819272591;4482.708623939434;4869.780859861386;4916.237983112624;5073.543381095719 13:17:43 INFO - PROCESS | 1636 | |4;prototype.html;313.568559525765;353.0855085328997;361.26662874365036;363.3695538241151;367.96418083096097;4409.933850992234;4992.975035124819;5114.335136432268;5415.64798288111;5622.465865742753;3992.141689536749;4384.649228061756;5147.22791581263;5291.074062039137;5341.412444631087;3822.4648549203134;3878.1855810279803;3978.820953057105;4012.4583181270445;4032.6370626643597;3369.595648522181;3535.108716836493;3901.9804900975455;4016.5180178378637;4042.2117687051036;4003.1793482336197;4012.8563359442564;4021.7532564904827;4022.45696830927;4053.716239863211;3234.32079263355;3381.9008822132905;3470.0283920502175;4049.4330793688905;4061.8520646498905;3446.190145315849;3447.775894566845;3463.9826800866085;3469.5663042119736;3481.5299934509026;4536.591706746409;4745.908441058541;4956.851294461136;5150.459201783834;5195.220716892459;4573.542645735433;4573.977130114361;4592.265237562007;4615.46922103959;4624.676272660915;5251.68489890604;5424.349078110652;5576.581756368248;5607.831765047055;5612.961602103624;3316;3609.097725568608;3816.255830113129;3825.9426108608113;3846.2115266370606;4543.8186071621285;5340.439253878326;5426.430224826416;5503.642263252836;5969.492593129546;3452.9482057769155;3599.218386898502;3626.3291291111227;3839.6160383961937;3882.320593896057;3908.9218215636097;3914.784686842194;3919;3922.941155882664;3949.763014219156;3630.945535816912;4521.553103007006;4556.066006468666;4590.013147173374;4604.631629469634;5432.565394768409;5556.333240011225;5603.8599035023635;5679.517241034476;5681.261436013375;1092.8470463660756;1193.7194759231575;1221.6457227403953;1271.4723389793132;1274.3182397417338;691.374306252842;725.7517070638494;819.0335404222955;867.4405008769309;874.3791907745572;4371.13154857953;4606.539346065434;5019.648624596316;5019.799208031711;5022.372203474566;5521.420250873601;5533.3083364579425;5541.612087153861;5544.750486228129;5563.165525171256;4505.414296141546;4716.858494245178;4806.44725856524;4910.959519790677;5056.609432406044;2893.3923875985856;2909.432660631199;2918.635904917024;2925.648922129358;2934.9266268343463;5776.595638305278;5795.623284486495;5799.3620701722775;5830.096335068071;5843.824685259449;4695.929561056587;5459.525927999418;5664.631798933056;5678.375378708339;5756.5682573807135;5123.538881500683;5536.4186760390385;5655.981923253771;5973.432523910308;6008.489278411209;4905.950940490549;5747.224124743107;5757.65454072757;5764.884702306014;5807.738651760766;5035.111833062601;5257.4216836147225;5494.587905907153;5696.629719068247;5714.457126572969;5035.345405097314;5186.948130518646;5313.990341835039;5335.546478549211;5771.98990176709;3400.9659903400648;3727.3908264214615;3784.7161462890945;3891.4551962724677;3909.667678247267;5105.030044291572;5333.493318134771;5529.170624406211;5710.8001219957105;5810.244668193108;4503.887402814956;5262.42113367537;5289.497497737709;5573.767215015362;5616.943830561642;5582.3859375467855;5599.440055994531;5618.85952851182;5623.2408624835125;5623.325200975745;4656.926428557136;4721.362616046789;5329.307190065267;5423.674579525241;5447.319085114361;5215.4002289735645;5601;5609.971950140223;5643.435656434323;5654.830355089354;4666.906661866811;5311.203319502106;5492.286002819608;5533.944660553343;5545.889082218414 13:17:43 INFO - PROCESS | 1636 | |5;yui.html;655.8589903170817;673.7103045690354;737.9852402951941;739.9334059934605;857.7812657772265;12737;14192.36134373953;14852.48016319429;15164.393424263044;15300.387984480622;14235.074720143184;14298.78551821721;14308.998370114095;14428.134311941265;14447.711045779104;856.7044369692446;878.8681697745326;890.3678388344251;893.3523195683143;904.5884122724144;879.4767113567417;913.7761248494128;914.5975770660921;916.7753900294438;917.6008436330218;958.2812890332251;960.0687333286701;960.4189465373453;960.9231261499099;961.038961038961;955.9904400956005;956.8325543029977;957.1481381570408;957.2246480350868;958.6932181701858;697.0728930522394;699.0667458942318;705.1855107350966;706.5125063706026;706.7173130747691;11375.886241137565;15160.393584256617;15616.921915390463;15654.843451565452;15659.921700391425;13927.721445571133;13961.511347102803;14095.474888115365;14250.857491425057;14301.78547321791;11506.94246528762;11586.942065289704;11738.237014594024;12035.097367697457;12099.758004839854;3641.1443310821937;3662.3224703429682;3699.4635777812337;3788.2234142000825;3802.7718336899875;81960;96404.51797740966;100705;102522.20002082417;103813.96186038118;10241.692749217535;10572;10589.682309530726;10673.252872298865;11175.608853690244;22341.776582234295;22366.105355785912;22366.55266894653;22396.328110156723;22462.550748985257;7020.157581090302;7043.330883566053;7064.894026589605;7196.388306993859;7343.118825740945;3929.8821035368983;3959.4060890866026;4118.176364727066;4148.522919864194;4183.769892655933;3381.5096810962273;3461.7403694723;3476.634953329864;3497.842597083137;3538.7699799513466;621.1986537366837;624.9031400132986;625.996870015647;636.9076483909807;646.767163821024;631.9905201421982;635.5741653092439;658.4962503684685;664.6178447392672;687.7517306089532;9434.764130896645;9816.656417025513;10058.698239052841;10074.798504029879;10288.022637849392;647.3623480871338;666.2671061831946;703.7255470366562;706.8619522568661;708.4084789200972;4657.115148121846;4757.705739636446;4868.367112275381;4870.871529890547;4882.926756098661;99951.50024249832;100448;101493;101594;104067.95932040735;84387;85894.14105858862;86140;87258;87700.68448973271;77154.45691086147;77367;78324;78495.60752196203;78639;7428.294312040348;7554.735584254525;8885.244754195708;8948.65770134485;9417.815855586103;44726.77636611796;50832;51204.48795511997;51387;52446.73776631092;65846.67076664777;67581.66209168923;68118.3188168112;68314.82694317025;69738.30261697317;11310.095192384424;11336.829947550628;11401.258918170292;11567.595134170264;11672;12410.379481026093;12417.937910310391;12594.24434533931;12875.553644807456;12925.935370323088;11255;11644.184907056764;11685.357305348287;11841.289522628325;11902.761944761229;11695.824562631396;11927.045836333245;11960.76078478408;12049.397530123284;12218.938905305416;11224.438778061227;11482.425878706199;11496.367699776592;11595.536178552764;11607.825882611598;9567.186789123003;9651;9808.65669701557;10267.84598231042;10461.790764184825;7875.842483150191;8604.397692161489;8922.68770593026;9612.855807163038;9630.662926797528 13:17:43 INFO - PROCESS | 1636 | __end_tp_report 13:17:43 INFO - PROCESS | 1636 | __start_cc_report 13:17:43 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,421 13:17:43 INFO - PROCESS | 1636 | __end_cc_report 13:17:43 INFO - PROCESS | 1636 | __startTimestamp1487107063013__endTimestamp 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - PROCESS | 1636 | ------- Summary: start ------- 13:17:43 INFO - PROCESS | 1636 | Number of tests: 6 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5632.94 Median:5852.00 stddev:669.15 (11.4%) stddev-sans-first:638.90 13:17:43 INFO - PROCESS | 1636 | Values: 3014.9 3370.7 3374.2 3384.0 3395.7 4308.7 5452.1 5479.1 5626.5 5679.9 5537.9 5569.5 5625.9 5631.9 5700.7 4619.9 5021.4 5075.1 5264.6 5275.9 5065.1 5249.7 5274.3 5293.4 5307.7 5030.9 5050.1 5126.9 5234.8 5278.4 5095.3 5141.8 5186.8 5203.7 5329.2 5121.9 5134.3 5143.8 5146.7 5153.0 4866.3 5503.7 5552.6 5622.4 5696.6 6110.9 6114.4 6114.6 6153.9 6172.4 5537.7 6018.4 6231.9 6241.8 6250.9 5829.8 5833.4 5835.6 5852.0 5863.4 6102.7 6131.5 6166.6 6184.1 6213.1 6123.8 6177.9 6191.2 6203.4 6219.2 5976.0 6204.8 6217.5 6268.8 6280.4 5098.6 5889.9 6123.7 6142.4 6269.8 5424.2 5963.8 6222.3 6261.3 6272.8 4960.4 4970.6 4978.4 4992.2 5025.4 3718.3 4334.0 4369.6 4395.3 4436.0 5803.9 5854.8 5874.7 5894.7 5917.1 6221.3 6242.7 6246.0 6262.0 6281.7 5541.7 5642.0 5646.7 5789.5 5843.6 5403.5 5411.6 5438.9 5489.8 5501.3 6251.8 6265.7 6268.8 6270.8 6305.7 5985.3 6242.1 6244.9 6253.9 6257.3 6113.9 6127.6 6160.3 6165.9 6204.3 6018.2 6021.8 6036.2 6044.3 6058.0 6042.0 6048.7 6113.1 6129.4 6131.8 4931.3 5847.6 5856.2 6039.8 6070.4 4253.4 4979.8 5003.8 5223.3 6064.2 5229.6 5727.0 5765.0 5775.8 5931.4 6112.4 6150.6 6155.4 6165.3 6165.5 5933.9 5999.5 6017.2 6029.6 6048.7 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:61632.80 Median:33658.58 stddev:132902.33 (394.9%) stddev-sans-first:133195.86 13:17:43 INFO - PROCESS | 1636 | Values: 486.3 489.8 506.9 512.9 543.6 37429.7 39118.6 39238.0 39279.6 39301.8 40651.0 41245.4 41373.8 41413.4 41544.6 9303.1 9554.8 9568.8 9578.1 10897.5 11024.8 11197.0 11237.2 11241.9 11251.5 11230.1 11236.6 11243.3 11247.6 11282.3 9495.8 11139.4 11280.3 11311.0 11316.2 10999.7 11019.3 11076.2 11079.2 11110.4 36641.3 36935.4 36962.6 37000.0 37050.6 3695.5 3745.8 3771.0 3788.3 3931.8 27050.8 27157.0 27824.7 28026.0 28173.7 2985.8 3048.6 3060.6 3178.8 3209.5 402261.0 403513.0 406306.0 407565.0 408674.0 44257.3 45528.5 54616.5 55082.6 55248.7 55435.2 56035.4 56656.0 59954.0 59987.7 13415.6 13512.4 13552.1 13563.3 13612.1 10133.8 10203.9 10270.4 10340.2 10343.3 14764.8 16134.8 17075.0 17091.9 17306.6 7703.8 7734.7 7783.8 7785.4 7814.1 777.6 827.9 831.8 836.8 856.8 25469.1 27458.7 27828.9 28697.1 28852.4 972.2 1012.6 1019.9 1055.3 1065.6 11520.8 13451.7 14138.9 14738.6 15070.2 571413.0 572931.0 577767.0 578019.0 578686.0 43168.0 43255.0 43657.8 43685.0 43695.3 1311.4 1351.3 1357.2 1357.9 1359.7 21659.8 24075.9 26046.7 26447.3 28894.0 47297.8 47534.0 47571.5 47683.5 47720.0 494055.0 497814.0 498311.0 498713.0 500280.0 23976.3 24507.4 24693.6 25145.4 25587.5 39821.0 40708.8 40779.4 41054.0 41204.4 32426.5 33605.5 34157.3 34306.1 35138.8 27350.7 33711.7 33759.0 34409.7 34813.5 29832.0 32689.0 32696.2 32880.7 34533.0 23704.6 24729.0 27675.0 30560.8 30642.5 23118.0 23150.9 23360.6 23473.8 23541.3 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:33687.20 Median:21643.05 stddev:94152.42 (435.0%) stddev-sans-first:94383.26 13:17:43 INFO - PROCESS | 1636 | Values: 348.0 388.6 394.8 402.3 425.6 15275.3 15977.2 18300.8 19336.4 20184.5 10374.2 12432.9 12521.4 17159.7 20293.4 4448.3 5303.3 5493.3 5516.6 5535.4 4358.3 4522.3 4626.5 5399.2 5414.8 4829.5 5301.6 5430.9 5435.5 5492.7 5160.2 5777.6 5875.4 6078.7 6141.2 5796.2 5982.7 5998.0 6058.8 6080.2 13520.6 13593.0 13659.5 13673.9 13695.7 2514.4 2531.4 2535.7 2555.8 2567.7 8773.6 9394.5 9793.4 10140.6 10430.2 1683.8 1698.2 1757.8 1775.8 1807.3 75393.9 121044.0 122386.0 126175.4 127771.4 11713.6 11950.1 12041.5 12061.6 12111.5 15662.8 15671.0 15719.0 15731.7 15758.9 7714.3 7908.5 8026.1 8038.9 8087.0 5990.9 7306.9 7307.2 7466.1 7486.4 4005.6 4856.6 4870.5 4873.3 4946.2 2048.2 2063.7 2080.9 2084.1 2090.5 1010.5 1014.2 1028.4 1031.5 1060.0 21136.8 21558.0 21619.0 21621.4 21664.7 928.0 972.0 1020.2 1052.7 1059.0 8977.5 11235.3 11300.7 11303.3 11357.2 539583.0 551904.0 559612.0 571216.0 574659.0 104768.0 136812.3 137903.0 139954.0 150375.2 939.0 982.8 1011.5 1011.6 1013.3 19928.8 19962.2 20291.9 20338.6 20485.9 49156.5 54515.6 58050.7 58311.4 58680.7 82427.0 83098.6 84029.6 84563.6 84964.6 8024.2 8130.1 8476.5 8513.1 8617.1 11850.5 12813.7 12880.9 13221.7 13257.9 9970.1 10605.4 10609.2 11547.8 11619.2 10776.6 11121.1 11221.3 11358.9 11441.0 9622.0 11205.7 11379.5 11591.1 11739.0 7152.1 7404.7 7537.2 7837.3 8789.6 9698.6 9729.3 10142.6 11410.3 11479.7 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5399.77 Median:5209.17 stddev:2954.01 (56.7%) stddev-sans-first:2947.29 13:17:43 INFO - PROCESS | 1636 | Values: 1437.4 1450.6 1451.9 1474.7 1486.3 7356.9 7590.4 8179.4 8641.7 8644.4 8016.7 8369.0 8371.3 8398.2 8402.5 1764.4 2053.6 2142.7 2244.4 2303.4 2269.9 2270.8 2285.6 2290.7 2291.1 1931.3 2268.0 2271.1 2284.7 2290.6 2245.7 2247.5 2262.7 2266.1 2272.6 2244.4 2301.1 2301.6 2303.8 2323.7 6632.4 8358.2 8379.1 8432.4 8447.8 5067.1 5184.6 5203.9 5214.5 5231.1 3068.0 3211.3 3488.7 3490.1 3496.6 1161.8 1175.3 1228.9 1235.7 1244.4 10166.1 10456.5 10571.7 10619.6 10689.4 4432.7 4639.1 4712.7 4713.9 4725.8 9553.5 9579.4 9615.4 9633.0 9633.9 5261.9 5263.3 5267.9 5273.7 5274.3 4051.9 4576.4 4619.7 4697.0 4802.6 3764.2 3813.1 3841.1 3909.8 3932.4 1162.4 1227.9 1247.5 1266.2 1271.7 721.8 831.8 887.5 893.6 918.7 5377.5 5561.9 5611.5 5762.4 5972.7 926.3 947.0 976.2 977.6 1012.7 3580.9 3598.2 3709.4 3719.2 3820.2 9793.8 10004.3 10106.5 10122.6 10133.9 9364.0 9917.6 10047.1 10070.8 10079.2 7636.7 7930.0 7939.2 7967.2 7984.8 5412.8 5548.3 5600.7 5622.7 5748.4 8818.1 9171.3 9180.8 9207.1 9244.6 8859.9 9152.7 9272.9 9400.4 9418.7 6258.2 7088.7 7150.9 7233.9 7304.6 7906.5 8014.1 8017.9 8027.2 8047.3 7041.6 7062.3 7121.1 7190.4 7239.8 6873.9 6970.2 7007.7 7043.9 7075.3 6098.5 6177.9 6213.8 6221.4 6231.9 4860.1 4944.2 4955.9 5004.3 5013.9 4433.4 4482.7 4869.8 4916.2 5073.5 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4449.54 Median:5019.72 stddev:1379.51 (27.5%) stddev-sans-first:1348.00 13:17:43 INFO - PROCESS | 1636 | Values: 313.6 353.1 361.3 363.4 368.0 4409.9 4993.0 5114.3 5415.6 5622.5 3992.1 4384.6 5147.2 5291.1 5341.4 3822.5 3878.2 3978.8 4012.5 4032.6 3369.6 3535.1 3902.0 4016.5 4042.2 4003.2 4012.9 4021.8 4022.5 4053.7 3234.3 3381.9 3470.0 4049.4 4061.9 3446.2 3447.8 3464.0 3469.6 3481.5 4536.6 4745.9 4956.9 5150.5 5195.2 4573.5 4574.0 4592.3 4615.5 4624.7 5251.7 5424.3 5576.6 5607.8 5613.0 3316.0 3609.1 3816.3 3825.9 3846.2 4543.8 5340.4 5426.4 5503.6 5969.5 3452.9 3599.2 3626.3 3839.6 3882.3 3908.9 3914.8 3919.0 3922.9 3949.8 3630.9 4521.6 4556.1 4590.0 4604.6 5432.6 5556.3 5603.9 5679.5 5681.3 1092.8 1193.7 1221.6 1271.5 1274.3 691.4 725.8 819.0 867.4 874.4 4371.1 4606.5 5019.6 5019.8 5022.4 5521.4 5533.3 5541.6 5544.8 5563.2 4505.4 4716.9 4806.4 4911.0 5056.6 2893.4 2909.4 2918.6 2925.6 2934.9 5776.6 5795.6 5799.4 5830.1 5843.8 4695.9 5459.5 5664.6 5678.4 5756.6 5123.5 5536.4 5656.0 5973.4 6008.5 4906.0 5747.2 5757.7 5764.9 5807.7 5035.1 5257.4 5494.6 5696.6 5714.5 5035.3 5186.9 5314.0 5335.5 5772.0 3401.0 3727.4 3784.7 3891.5 3909.7 5105.0 5333.5 5529.2 5710.8 5810.2 4503.9 5262.4 5289.5 5573.8 5616.9 5582.4 5599.4 5618.9 5623.2 5623.3 4656.9 4721.4 5329.3 5423.7 5447.3 5215.4 5601.0 5610.0 5643.4 5654.8 4666.9 5311.2 5492.3 5533.9 5545.9 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19725.80 Median:4869.62 stddev:28565.08 (586.6%) stddev-sans-first:28609.33 13:17:43 INFO - PROCESS | 1636 | Values: 655.9 673.7 738.0 739.9 857.8 12737.0 14192.4 14852.5 15164.4 15300.4 14235.1 14298.8 14309.0 14428.1 14447.7 856.7 878.9 890.4 893.4 904.6 879.5 913.8 914.6 916.8 917.6 958.3 960.1 960.4 960.9 961.0 956.0 956.8 957.1 957.2 958.7 697.1 699.1 705.2 706.5 706.7 11375.9 15160.4 15616.9 15654.8 15659.9 13927.7 13961.5 14095.5 14250.9 14301.8 11506.9 11586.9 11738.2 12035.1 12099.8 3641.1 3662.3 3699.5 3788.2 3802.8 81960.0 96404.5 100705.0 102522.2 103814.0 10241.7 10572.0 10589.7 10673.3 11175.6 22341.8 22366.1 22366.6 22396.3 22462.6 7020.2 7043.3 7064.9 7196.4 7343.1 3929.9 3959.4 4118.2 4148.5 4183.8 3381.5 3461.7 3476.6 3497.8 3538.8 621.2 624.9 626.0 636.9 646.8 632.0 635.6 658.5 664.6 687.8 9434.8 9816.7 10058.7 10074.8 10288.0 647.4 666.3 703.7 706.9 708.4 4657.1 4757.7 4868.4 4870.9 4882.9 99951.5 100448.0 101493.0 101594.0 104068.0 84387.0 85894.1 86140.0 87258.0 87700.7 77154.5 77367.0 78324.0 78495.6 78639.0 7428.3 7554.7 8885.2 8948.7 9417.8 44726.8 50832.0 51204.5 51387.0 52446.7 65846.7 67581.7 68118.3 68314.8 69738.3 11310.1 11336.8 11401.3 11567.6 11672.0 12410.4 12417.9 12594.2 12875.6 12925.9 11255.0 11644.2 11685.4 11841.3 11902.8 11695.8 11927.0 11960.8 12049.4 12218.9 11224.4 11482.4 11496.4 11595.5 11607.8 9567.2 9651.0 9808.7 10267.8 10461.8 7875.8 8604.4 8922.7 9612.9 9630.7 13:17:43 INFO - PROCESS | 1636 | -------- Summary: end -------- 13:17:43 INFO - PROCESS | 1636 | 13:17:43 INFO - TEST-INFO | 1636: exit 0 13:17:43 INFO - TEST-OK | dromaeo_css | took 1116745ms 13:17:43 INFO - TEST-START | kraken 13:17:43 INFO - operating with platform_type : mac_ 13:17:43 INFO - Initialising browser for kraken test... 13:17:43 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpKVvcgC/profile http://localhost:49229/getInfo.html) 13:17:46 INFO - PROCESS | 1647 | 2017-02-14 13:17:46.418 plugin-container[1648:10086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 13:17:46 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:17:46 INFO - PROCESS | 1647 | 2017-02-14 13:17:46.422 plugin-container[1648:10086] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 13:17:46 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:17:46 INFO - PROCESS | 1647 | 2017-02-14 13:17:46.422 plugin-container[1648:10086] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:17:46 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 13:17:46 INFO - PROCESS | 1647 | colorDepth:24 13:17:46 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 13:17:46 INFO - PROCESS | 1647 | __metrics 13:17:46 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3283: TypeError: this.tabs is undefined 13:17:47 INFO - TEST-INFO | 1647: exit 0 13:17:47 INFO - Browser initialized. 13:17:47 INFO - Running cycle 1/1 for kraken test... 13:17:47 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/T/tmpKVvcgC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:17:49 INFO - PROCESS | 1649 | 2017-02-14 13:17:49.815 plugin-container[1650:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 13:17:49 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:17:49 INFO - PROCESS | 1649 | 2017-02-14 13:17:49.816 plugin-container[1650:10201] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 13:17:49 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:17:49 INFO - PROCESS | 1649 | 2017-02-14 13:17:49.816 plugin-container[1650:10201] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:17:59 INFO - PROCESS | 1649 | RSS: Main: 234287104 13:17:59 INFO - PROCESS | 1649 | 13:18:02 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 13:18:03 INFO - PROCESS | 1649 | RSS: Main: 248033280 13:18:03 INFO - PROCESS | 1649 | 13:18:06 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 13:18:07 INFO - PROCESS | 1649 | RSS: Main: 255778816 13:18:07 INFO - PROCESS | 1649 | 13:18:10 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 13:18:11 INFO - PROCESS | 1649 | RSS: Main: 258433024 13:18:11 INFO - PROCESS | 1649 | 13:18:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 13:18:13 INFO - PROCESS | 1649 | RSS: Main: 259637248 13:18:13 INFO - PROCESS | 1649 | 13:18:15 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:18:16 INFO - PROCESS | 1649 | RSS: Main: 259985408 13:18:16 INFO - PROCESS | 1649 | 13:18:19 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 13:18:19 INFO - PROCESS | 1649 | RSS: Main: 265617408 13:18:19 INFO - PROCESS | 1649 | 13:18:22 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 13:18:23 INFO - PROCESS | 1649 | RSS: Main: 264880128 13:18:23 INFO - PROCESS | 1649 | 13:18:26 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 13:18:27 INFO - PROCESS | 1649 | RSS: Main: 264970240 13:18:27 INFO - PROCESS | 1649 | 13:18:28 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:18:29 INFO - PROCESS | 1649 | RSS: Main: 265768960 13:18:29 INFO - PROCESS | 1649 | 13:18:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:18:31 INFO - PROCESS | 1649 | RSS: Main: 265785344 13:18:31 INFO - PROCESS | 1649 | 13:18:34 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:18:34 INFO - PROCESS | 1649 | RSS: Main: 265887744 13:18:34 INFO - PROCESS | 1649 | 13:18:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:18:38 INFO - PROCESS | 1649 | RSS: Main: 266260480 13:18:38 INFO - PROCESS | 1649 | 13:18:41 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:18:42 INFO - PROCESS | 1649 | RSS: Main: 265969664 13:18:42 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 13:18:44 INFO - PROCESS | 1649 | RSS: Main: 267993088 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | __start_tp_report 13:18:44 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 13:18:44 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 13:18:44 INFO - PROCESS | 1649 | |i|pagename|runs| 13:18:44 INFO - PROCESS | 1649 | |0;ai-astar;108;118;118;117;118;117;117;150;176;149 13:18:44 INFO - PROCESS | 1649 | |1;audio-beat-detection;143;125;140;186;142;115;134;144;166;109 13:18:44 INFO - PROCESS | 1649 | |2;audio-dft;205;208;226;202;197;198;195;196;200;195 13:18:44 INFO - PROCESS | 1649 | |3;audio-fft;84;64;72;108;86;64;69;81;103;54 13:18:44 INFO - PROCESS | 1649 | |4;audio-oscillator;91;73;85;78;95;64;86;102;58;104 13:18:44 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;89;89;103;89;88;88;110;105;135 13:18:44 INFO - PROCESS | 1649 | |6;imaging-darkroom;97;96;98;99;97;97;97;97;104;98 13:18:44 INFO - PROCESS | 1649 | |7;imaging-desaturate;94;97;106;95;92;92;92;91;92;94 13:18:44 INFO - PROCESS | 1649 | |8;json-parse-financial;65;66;63;65;66;67;65;67;65;66 13:18:44 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;75;70;82;76;79;70;70;69;72;73 13:18:44 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;94;107;112;89;86;78;98;111;81;94 13:18:44 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;135;154;158;157;158;155;157;182;187;187 13:18:44 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;177;182;177;176;178;177;178;196;172;236 13:18:44 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;68;69;70;67;75;79;83;86;83;67 13:18:44 INFO - PROCESS | 1649 | __end_tp_report 13:18:44 INFO - PROCESS | 1649 | __start_cc_report 13:18:44 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1012 13:18:44 INFO - PROCESS | 1649 | __end_cc_report 13:18:44 INFO - PROCESS | 1649 | __startTimestamp1487107124490__endTimestamp 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | ------- Summary: start ------- 13:18:44 INFO - PROCESS | 1649 | Number of tests: 14 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:128.80 Median:118.00 stddev:21.82 (18.5%) stddev-sans-first:21.81 13:18:44 INFO - PROCESS | 1649 | Values: 108.0 118.0 118.0 117.0 118.0 117.0 117.0 150.0 176.0 149.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:140.40 Median:142.50 stddev:22.77 (16.0%) stddev-sans-first:24.13 13:18:44 INFO - PROCESS | 1649 | Values: 143.0 125.0 140.0 186.0 142.0 115.0 134.0 144.0 166.0 109.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:202.20 Median:201.00 stddev:9.43 (4.7%) stddev-sans-first:9.94 13:18:44 INFO - PROCESS | 1649 | Values: 205.0 208.0 226.0 202.0 197.0 198.0 195.0 196.0 200.0 195.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:78.50 Median:70.50 stddev:17.37 (24.6%) stddev-sans-first:18.31 13:18:44 INFO - PROCESS | 1649 | Values: 84.0 64.0 72.0 108.0 86.0 64.0 69.0 81.0 103.0 54.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:81.50 stddev:15.39 (18.9%) stddev-sans-first:16.08 13:18:44 INFO - PROCESS | 1649 | Values: 91.0 73.0 85.0 78.0 95.0 64.0 86.0 102.0 58.0 104.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:88.50 stddev:15.31 (17.3%) stddev-sans-first:15.84 13:18:44 INFO - PROCESS | 1649 | Values: 89.0 89.0 89.0 103.0 89.0 88.0 88.0 110.0 105.0 135.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:97.00 stddev:2.26 (2.3%) stddev-sans-first:2.37 13:18:44 INFO - PROCESS | 1649 | Values: 97.0 96.0 98.0 99.0 97.0 97.0 97.0 97.0 104.0 98.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:93.00 stddev:4.43 (4.8%) stddev-sans-first:4.69 13:18:44 INFO - PROCESS | 1649 | Values: 94.0 97.0 106.0 95.0 92.0 92.0 92.0 91.0 92.0 94.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:66.00 stddev:1.18 (1.8%) stddev-sans-first:1.24 13:18:44 INFO - PROCESS | 1649 | Values: 65.0 66.0 63.0 65.0 66.0 67.0 65.0 67.0 65.0 66.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:74.00 stddev:4.35 (5.9%) stddev-sans-first:4.59 13:18:44 INFO - PROCESS | 1649 | Values: 75.0 70.0 82.0 76.0 79.0 70.0 70.0 69.0 72.0 73.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:95.00 Median:87.50 stddev:12.03 (13.7%) stddev-sans-first:12.75 13:18:44 INFO - PROCESS | 1649 | Values: 94.0 107.0 112.0 89.0 86.0 78.0 98.0 111.0 81.0 94.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:163.00 Median:158.00 stddev:16.88 (10.7%) stddev-sans-first:14.55 13:18:44 INFO - PROCESS | 1649 | Values: 135.0 154.0 158.0 157.0 158.0 155.0 157.0 182.0 187.0 187.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.90 Median:178.00 stddev:19.06 (10.7%) stddev-sans-first:20.00 13:18:44 INFO - PROCESS | 1649 | Values: 177.0 182.0 177.0 176.0 178.0 177.0 178.0 196.0 172.0 236.0 13:18:44 INFO - PROCESS | 1649 | 13:18:44 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:77.00 stddev:7.47 (9.7%) stddev-sans-first:7.52 13:18:44 INFO - PROCESS | 1649 | Values: 68.0 69.0 70.0 67.0 75.0 79.0 83.0 86.0 83.0 67.0 13:18:44 INFO - PROCESS | 1649 | -------- Summary: end -------- 13:18:44 INFO - PROCESS | 1649 | 13:18:45 INFO - TEST-INFO | 1649: exit 0 13:18:45 INFO - TEST-OK | kraken | took 61742ms 13:18:45 INFO - SUITE-END | took 1178s 13:18:45 INFO - Completed test suite (00:19:38) 13:18:45 INFO - javascript benchmark 13:18:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7050.201577736927, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3014.9246268843276, 3370.713489353406, 3374.156460884779, 3383.999999999999, 3395.660433956606, 4308.719933204347, 5452.127659574468, 5479.0959491683825, 5626.521745651619, 5679.943200567998, 5537.916931246024, 5569.526590239834, 5625.859353516164, 5631.915521267184, 5700.686462244575, 4619.930701039487, 5021.372328458942, 5075.111855425304, 5264.578833693315, 5275.920861187085, 5065.088284108859, 5249.658772179796, 5274.314339135907, 5293.391260005091, 5307.708076055816, 5030.899382012376, 5050.141475949097, 5126.897462050775, 5234.81678141267, 5278.445763194886, 5095.286659867622, 5141.820036298712, 5186.7925282988635, 5203.713795741232, 5329.227262046981, 5121.948780512222, 5134.306868572769, 5143.8456846294675, 5146.691198528063, 5153.0, 4866.294387313855, 5503.669779813184, 5552.583556233259, 5622.409646987048, 5696.572757043238, 6110.892920173586, 6114.388561143895, 6114.601521188683, 6153.907691384633, 6172.444479996821, 5537.726322945705, 6018.3981601839905, 6231.937680623181, 6241.843953901143, 6250.9373406521, 5829.795957141479, 5833.445822646841, 5835.649861008268, 5851.999999999957, 5863.442972917566, 6102.664353460514, 6131.509479241739, 6166.630002199882, 6184.103305020747, 6213.068039793977, 6123.846903827351, 6177.907331390033, 6191.19514463126, 6203.379662033761, 6219.191505104398, 5976.0, 6204.751809927552, 6217.502599792006, 6268.780592679234, 6280.371962803683, 5098.592112631055, 5889.882202355929, 6123.709696704001, 6142.3550527194, 6269.843253918689, 5424.240606315119, 5963.761449542057, 6222.253329600387, 6261.311255761862, 6272.8118156455375, 4960.404751429852, 4970.552650261494, 4978.377702787152, 4992.176290912032, 5025.396952365666, 3718.2749363874327, 4334.0, 4369.628581570539, 4395.252807022814, 4436.0, 5803.883922321615, 5854.824355269348, 5874.73563689635, 5894.705264736746, 5917.112433135064, 6221.2845522765465, 6242.687865606701, 6246.0, 6262.029385445263, 6281.685915704196, 5541.722913854291, 5642.04085305499, 5646.6894320811925, 5789.536837053026, 5843.5909486336395, 5403.459654034565, 5411.594130440233, 5438.945610543923, 5489.752961116679, 5501.284832971688, 6251.812445626639, 6265.686715664198, 6268.780592679234, 6270.843228919313, 6305.653189074553, 5985.281766188085, 6242.094896240111, 6244.937550624436, 6253.937460625336, 6257.342978987141, 6113.932424433873, 6127.647695153045, 6160.322364539987, 6165.876682466415, 6204.255489341127, 6018.21763170785, 6021.819345419644, 6036.245469316335, 6044.304904935989, 6057.97014507526, 6042.0, 6048.727807248596, 6113.083037544404, 6129.35641757609, 6131.754729810757, 4931.260310953386, 5847.590668653153, 5856.209411729362, 6039.849003774765, 6070.3929607038945, 4253.4257875187695, 4979.825706100269, 5003.77483013258, 5223.268742375994, 6064.181335519648, 5229.581633469389, 5726.9713651431475, 5765.0, 5775.81595772874, 5931.377205393372, 6112.351504965139, 6150.600210986271, 6155.353687862889, 6165.321814600301, 6165.5375846810775, 5933.940660593512, 5999.520038397005, 6017.15759793638, 6029.60807547508, 6048.727807248596], "value": 5590.9560408451125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.33615115367525, 489.76736050376053, 506.939167299924, 512.9330991537599, 543.6221825831049, 37429.68996085138, 39118.60881391189, 39238.01904952374, 39279.62521311755, 39301.82094537161, 40651.0, 41245.38131928023, 41373.793131034305, 41413.37879931804, 41544.58455415445, 9303.069693030711, 9554.761130971712, 9568.760780980496, 9578.137967582916, 10897.509612067475, 11024.779504409908, 11196.99999999996, 11237.213395062348, 11241.943790281037, 11251.54993800247, 11230.10159187263, 11236.550537978472, 11243.325400475956, 11247.60633377832, 11282.323060616349, 9495.762605934839, 11139.387333696643, 11280.2667826591, 11311.038561722264, 11316.151288653384, 10999.670009899715, 11019.338839669645, 11076.224664273504, 11079.169062320278, 11110.388928609003, 36641.26717465636, 36935.445968310494, 36962.630373696185, 37000.0, 37050.62949370499, 3695.464157697119, 3745.834081701889, 3770.981145094257, 3788.337041017811, 3931.842726290945, 27050.782714777684, 27157.049503267488, 27824.74788634516, 28026.0, 28173.718262817314, 2985.820850748962, 3048.6189226346705, 3060.6021217241837, 3178.761592880543, 3209.4864821628425, 402261.0, 403512.9824350889, 406306.0, 407565.0, 408674.0, 44257.33613995793, 45528.54471455243, 54616.45383546193, 55082.62293442696, 55248.72375638096, 55435.16847247294, 56035.43964560384, 56656.0, 59954.0, 59987.70006150028, 13415.597532074053, 13512.391942362618, 13552.051356404956, 13563.254021028939, 13612.115212511353, 10133.797324053477, 10203.897961020295, 10270.383776973405, 10340.224483163793, 10343.27597068198, 14764.778528322084, 16134.75797863033, 17075.0, 17091.914540427217, 17306.567335816704, 7703.845923081507, 7734.729284475016, 7783.805404864924, 7785.3771698264, 7814.140444551095, 777.6461709921972, 827.9461834980707, 831.8170002599539, 836.786619412046, 856.8200677857719, 25469.10858119957, 27458.725412746015, 27828.860855695995, 28697.139085827042, 28852.42295154085, 972.2270794718216, 1012.5949620151998, 1019.9490025498695, 1055.3404122423485, 1065.589747947046, 11520.827187592193, 13451.730965380637, 14138.929305353407, 14738.55784326472, 15070.246487675791, 571413.0, 572931.0, 577767.0, 578019.0, 578686.0, 43168.0, 43255.0, 43657.781711091244, 43685.0, 43695.344569831475, 1311.3836496846468, 1351.2500562187884, 1357.2263809628614, 1357.8642135786342, 1359.6940688345242, 21659.783402166406, 24075.879620601783, 26046.73953260443, 26447.33881652974, 28894.0, 47297.763511182224, 47534.04931901273, 47571.524284756706, 47683.52316476929, 47720.0, 494055.0, 497814.0, 498311.0, 498713.0, 500280.0, 23976.28071157868, 24507.38731531726, 24693.629595556442, 25145.371365716004, 25587.488250234517, 39821.00447488836, 40708.79645601753, 40779.38830917479, 41054.0, 41204.38193427161, 32426.513602296458, 33605.49591756175, 34157.31685366329, 34306.14234644154, 35138.82430587933, 27350.691416469417, 33711.662883371835, 33759.0, 34409.65590344165, 34813.477797832544, 29832.0, 32689.019329420156, 32696.182595435304, 32880.67119328872, 34533.0, 23704.644430333214, 24729.010839566934, 27675.0, 30560.847195764767, 30642.540361895037, 23118.0, 23150.884245578665, 23360.6495902565, 23473.76526234716, 23541.293761187193], "value": 18566.139472252773, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.95650543682035, 388.5531638615592, 394.83076923076925, 402.31405453701444, 425.64671322802076, 15275.312610932508, 15977.201139943014, 18300.81044732093, 19336.356409705157, 20184.495387615316, 10374.221933354991, 12432.937835310811, 12521.436535355908, 17159.74260386092, 20293.391198264075, 4448.33275008748, 5303.337082864642, 5493.340799104093, 5516.641418307817, 5535.363433205172, 4358.302671572549, 4522.3216517522305, 4626.4910859805395, 5399.19012148177, 5414.83755487336, 4829.492903245144, 5301.628885977983, 5430.91853622196, 5435.483629055253, 5492.670439773627, 5160.225966105114, 5777.62445441045, 5875.38308477608, 6078.665673387962, 6141.17094192285, 5796.1595568642315, 5982.670953097578, 5997.970010149965, 6058.848528786772, 6080.179175811253, 13520.59438216855, 13593.048486605941, 13659.521916732967, 13673.931630341785, 13695.657608559766, 2514.396544829246, 2531.405119796846, 2535.657686212356, 2555.821092523524, 2567.7303883092195, 8773.561321933877, 9394.530273486298, 9793.412395256308, 10140.645077422254, 10430.165586753117, 1683.7895263092114, 1698.1678977301158, 1757.8417942385247, 1775.7691500104904, 1807.268056437145, 75393.87449557296, 121044.0, 122386.0, 126175.3691231538, 127771.36114319462, 11713.590024349107, 11950.103742219368, 12041.518339266506, 12061.577844775566, 12111.515539378503, 15662.765058524132, 15671.041119860018, 15719.0, 15731.68536629261, 15758.921205393899, 7714.344280736088, 7908.485948413335, 8026.077001144827, 8038.919610803934, 8087.0, 5990.850228744229, 7306.890396644055, 7307.232740562271, 7466.066741657293, 7486.438517111238, 4005.559388467266, 4856.635752318591, 4870.512948705101, 4873.31773551703, 4946.233333833262, 2048.1704909511673, 2063.690446433047, 2080.91676332948, 2084.114251443131, 2090.4878304815416, 1010.4543546484963, 1014.1582486536157, 1028.388109075114, 1031.4533297352416, 1059.989400105989, 21136.788632113483, 21558.0, 21619.027143778567, 21621.432463643323, 21664.675029874565, 928.03020843219, 971.9805603887883, 1020.2144348851491, 1052.7210289273348, 1059.0, 8977.50623715689, 11235.325880447199, 11300.71748206285, 11303.321800691985, 11357.204995650225, 539583.0, 551904.0, 559612.0, 571216.0, 574659.0, 104768.0, 136812.31593841966, 137903.0, 139954.0, 150375.24812375868, 938.9671361502315, 982.7543114221445, 1011.4790882695418, 1011.6408674920444, 1013.2957594471918, 19928.800711992695, 19962.201511939358, 20291.883946383092, 20338.59322813565, 20485.897570512054, 49156.508434915195, 54515.637109071, 58050.709746451, 58311.416885832296, 58680.70659646674, 82427.0, 83098.58450707707, 84029.57985210035, 84563.5771821137, 84964.57517712371, 8024.2376974187355, 8130.146334635015, 8476.533790641573, 8513.148685131437, 8617.138286171332, 11850.511392607648, 12813.74372512526, 12880.871191287968, 13221.669458263621, 13257.867421325664, 9970.052844979715, 10605.363678179334, 10609.151267898395, 11547.826782598437, 11619.186656934271, 10776.568937242422, 11121.110311175249, 11221.270617409842, 11358.886411135783, 11441.027512661518, 9622.0, 11205.66383008511, 11379.487923043318, 11591.130665200313, 11739.0, 7152.141742990874, 7404.703811847466, 7537.2085930976455, 7837.255460731221, 8789.648414063366, 9698.6120555177, 9729.318947673879, 10142.645007425, 11410.258333208314, 11479.713007174887], "value": 9275.808862664497, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.3819257719183, 1450.5938337265557, 1451.8620731030549, 1474.7419201639714, 1486.2791546100143, 7356.926430735691, 7590.392768578514, 8179.386546009051, 8641.740747777569, 8644.438111522746, 8016.679332826695, 8369.0, 8371.28844048255, 8398.1601839816, 8402.479256223123, 1764.4265613675543, 2053.609814135317, 2142.682250415991, 2244.38279473145, 2303.4126297794123, 2269.897854596547, 2270.818334533233, 2285.6342985122383, 2290.725112986444, 2291.0606651272983, 1931.2757715856553, 2267.9659805102856, 2271.136967952176, 2284.668723035159, 2290.6105961986505, 2245.741739699931, 2247.5280191159877, 2262.671912572684, 2266.1275408967585, 2272.6363781794917, 2244.438890277431, 2301.056566807601, 2301.620232661609, 2303.8272129590346, 2323.6514522821544, 6632.436242919358, 8358.247757701836, 8379.078301386904, 8432.40973131881, 8447.831043379098, 5067.0879241736475, 5184.585233181337, 5203.869903252411, 5214.478552144793, 5231.058409486291, 3067.969320306813, 3211.2614098757417, 3488.7383446241633, 3490.127468132967, 3496.5629296337956, 1161.7569200954981, 1175.3300618647374, 1228.9385530723428, 1235.6663700800734, 1244.4151248912997, 10166.08505234525, 10456.52945617449, 10571.682849514527, 10619.628313009085, 10689.412082335468, 4432.667549933768, 4639.141758774637, 4712.69367491112, 4713.882152946204, 4725.763711814396, 9553.522323883779, 9579.425234485952, 9615.375000624937, 9633.036696330451, 9633.90366096344, 5261.894762104813, 5263.315768950012, 5267.868303292372, 5273.683578985273, 5274.261603375454, 4051.8946127879085, 4576.359309696645, 4619.745913974696, 4696.9901471183175, 4802.615790736732, 3764.153065560282, 3813.122981714221, 3841.058940559525, 3909.7849618271266, 3932.390479475686, 1162.4304090995352, 1227.8948945948719, 1247.4822948476462, 1266.1896386312767, 1271.6566527037833, 721.7870728135213, 831.846108469935, 887.454215657379, 893.6380765789736, 918.7473444802732, 5377.5160235578205, 5561.888762224733, 5611.494965453129, 5762.394948530344, 5972.686009078083, 926.305271046715, 946.9621215151454, 976.1653786012998, 977.5649835822991, 1012.6607586458472, 3580.9104772380897, 3598.2263813279747, 3709.3694072007825, 3719.20037192005, 3820.2359528094494, 9793.804123917482, 10004.299699021145, 10106.494675266207, 10122.645707400206, 10133.898661013443, 9364.0, 9917.553710083064, 10047.145992590711, 10070.798584028278, 10079.193664506822, 7636.656350464132, 7929.969104016441, 7939.2060793920145, 7967.203279671987, 7984.800379990546, 5412.75642596092, 5548.306461692288, 5600.691961942132, 5622.7469763861545, 5748.425157484218, 8818.074102219174, 9171.266298695955, 9180.770480738034, 9207.079292070739, 9244.583993720164, 8859.911400885909, 9152.725418237464, 9272.860907086533, 9400.388974716621, 9418.670346537838, 6258.249010118815, 7088.716451342095, 7150.856982860209, 7233.891491627524, 7304.598247096461, 7906.486078404885, 8014.078380986028, 8017.919820801717, 8027.1972802719265, 8047.275745182962, 7041.612711300722, 7062.329078737512, 7121.145462544528, 7190.388816950618, 7239.782806515813, 6873.912159515274, 6970.23327433992, 7007.7196912122945, 7043.859122817617, 7075.292470752884, 6098.451139397475, 6177.876442471035, 6213.782517611862, 6221.4089661482085, 6231.937680623317, 4860.1008813369635, 4944.159492886146, 4955.900881982268, 5004.274380214922, 5013.874653133701, 4433.445819272591, 4482.708623939434, 4869.780859861386, 4916.237983112624, 5073.543381095719], "value": 4381.774946258015, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.568559525765, 353.0855085328997, 361.26662874365036, 363.3695538241151, 367.96418083096097, 4409.933850992234, 4992.975035124819, 5114.335136432268, 5415.64798288111, 5622.465865742753, 3992.141689536749, 4384.649228061756, 5147.22791581263, 5291.074062039137, 5341.412444631087, 3822.4648549203134, 3878.1855810279803, 3978.820953057105, 4012.4583181270445, 4032.6370626643597, 3369.595648522181, 3535.108716836493, 3901.9804900975455, 4016.5180178378637, 4042.2117687051036, 4003.1793482336197, 4012.8563359442564, 4021.7532564904827, 4022.45696830927, 4053.716239863211, 3234.32079263355, 3381.9008822132905, 3470.0283920502175, 4049.4330793688905, 4061.8520646498905, 3446.190145315849, 3447.775894566845, 3463.9826800866085, 3469.5663042119736, 3481.5299934509026, 4536.591706746409, 4745.908441058541, 4956.851294461136, 5150.459201783834, 5195.220716892459, 4573.542645735433, 4573.977130114361, 4592.265237562007, 4615.46922103959, 4624.676272660915, 5251.68489890604, 5424.349078110652, 5576.581756368248, 5607.831765047055, 5612.961602103624, 3316.0, 3609.097725568608, 3816.255830113129, 3825.9426108608113, 3846.2115266370606, 4543.8186071621285, 5340.439253878326, 5426.430224826416, 5503.642263252836, 5969.492593129546, 3452.9482057769155, 3599.218386898502, 3626.3291291111227, 3839.6160383961937, 3882.320593896057, 3908.9218215636097, 3914.784686842194, 3919.0, 3922.941155882664, 3949.763014219156, 3630.945535816912, 4521.553103007006, 4556.066006468666, 4590.013147173374, 4604.631629469634, 5432.565394768409, 5556.333240011225, 5603.8599035023635, 5679.517241034476, 5681.261436013375, 1092.8470463660756, 1193.7194759231575, 1221.6457227403953, 1271.4723389793132, 1274.3182397417338, 691.374306252842, 725.7517070638494, 819.0335404222955, 867.4405008769309, 874.3791907745572, 4371.13154857953, 4606.539346065434, 5019.648624596316, 5019.799208031711, 5022.372203474566, 5521.420250873601, 5533.3083364579425, 5541.612087153861, 5544.750486228129, 5563.165525171256, 4505.414296141546, 4716.858494245178, 4806.44725856524, 4910.959519790677, 5056.609432406044, 2893.3923875985856, 2909.432660631199, 2918.635904917024, 2925.648922129358, 2934.9266268343463, 5776.595638305278, 5795.623284486495, 5799.3620701722775, 5830.096335068071, 5843.824685259449, 4695.929561056587, 5459.525927999418, 5664.631798933056, 5678.375378708339, 5756.5682573807135, 5123.538881500683, 5536.4186760390385, 5655.981923253771, 5973.432523910308, 6008.489278411209, 4905.950940490549, 5747.224124743107, 5757.65454072757, 5764.884702306014, 5807.738651760766, 5035.111833062601, 5257.4216836147225, 5494.587905907153, 5696.629719068247, 5714.457126572969, 5035.345405097314, 5186.948130518646, 5313.990341835039, 5335.546478549211, 5771.98990176709, 3400.9659903400648, 3727.3908264214615, 3784.7161462890945, 3891.4551962724677, 3909.667678247267, 5105.030044291572, 5333.493318134771, 5529.170624406211, 5710.8001219957105, 5810.244668193108, 4503.887402814956, 5262.42113367537, 5289.497497737709, 5573.767215015362, 5616.943830561642, 5582.3859375467855, 5599.440055994531, 5618.85952851182, 5623.2408624835125, 5623.325200975745, 4656.926428557136, 4721.362616046789, 5329.307190065267, 5423.674579525241, 5447.319085114361, 5215.4002289735645, 5601.0, 5609.971950140223, 5643.435656434323, 5654.830355089354, 4666.906661866811, 5311.203319502106, 5492.286002819608, 5533.944660553343, 5545.889082218414], "value": 4015.4902602251937, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.8589903170817, 673.7103045690354, 737.9852402951941, 739.9334059934605, 857.7812657772265, 12737.0, 14192.36134373953, 14852.48016319429, 15164.393424263044, 15300.387984480622, 14235.074720143184, 14298.78551821721, 14308.998370114095, 14428.134311941265, 14447.711045779104, 856.7044369692446, 878.8681697745326, 890.3678388344251, 893.3523195683143, 904.5884122724144, 879.4767113567417, 913.7761248494128, 914.5975770660921, 916.7753900294438, 917.6008436330218, 958.2812890332251, 960.0687333286701, 960.4189465373453, 960.9231261499099, 961.038961038961, 955.9904400956005, 956.8325543029977, 957.1481381570408, 957.2246480350868, 958.6932181701858, 697.0728930522394, 699.0667458942318, 705.1855107350966, 706.5125063706026, 706.7173130747691, 11375.886241137565, 15160.393584256617, 15616.921915390463, 15654.843451565452, 15659.921700391425, 13927.721445571133, 13961.511347102803, 14095.474888115365, 14250.857491425057, 14301.78547321791, 11506.94246528762, 11586.942065289704, 11738.237014594024, 12035.097367697457, 12099.758004839854, 3641.1443310821937, 3662.3224703429682, 3699.4635777812337, 3788.2234142000825, 3802.7718336899875, 81960.0, 96404.51797740966, 100705.0, 102522.20002082417, 103813.96186038118, 10241.692749217535, 10572.0, 10589.682309530726, 10673.252872298865, 11175.608853690244, 22341.776582234295, 22366.105355785912, 22366.55266894653, 22396.328110156723, 22462.550748985257, 7020.157581090302, 7043.330883566053, 7064.894026589605, 7196.388306993859, 7343.118825740945, 3929.8821035368983, 3959.4060890866026, 4118.176364727066, 4148.522919864194, 4183.769892655933, 3381.5096810962273, 3461.7403694723, 3476.634953329864, 3497.842597083137, 3538.7699799513466, 621.1986537366837, 624.9031400132986, 625.996870015647, 636.9076483909807, 646.767163821024, 631.9905201421982, 635.5741653092439, 658.4962503684685, 664.6178447392672, 687.7517306089532, 9434.764130896645, 9816.656417025513, 10058.698239052841, 10074.798504029879, 10288.022637849392, 647.3623480871338, 666.2671061831946, 703.7255470366562, 706.8619522568661, 708.4084789200972, 4657.115148121846, 4757.705739636446, 4868.367112275381, 4870.871529890547, 4882.926756098661, 99951.50024249832, 100448.0, 101493.0, 101594.0, 104067.95932040735, 84387.0, 85894.14105858862, 86140.0, 87258.0, 87700.68448973271, 77154.45691086147, 77367.0, 78324.0, 78495.60752196203, 78639.0, 7428.294312040348, 7554.735584254525, 8885.244754195708, 8948.65770134485, 9417.815855586103, 44726.77636611796, 50832.0, 51204.48795511997, 51387.0, 52446.73776631092, 65846.67076664777, 67581.66209168923, 68118.3188168112, 68314.82694317025, 69738.30261697317, 11310.095192384424, 11336.829947550628, 11401.258918170292, 11567.595134170264, 11672.0, 12410.379481026093, 12417.937910310391, 12594.24434533931, 12875.553644807456, 12925.935370323088, 11255.0, 11644.184907056764, 11685.357305348287, 11841.289522628325, 11902.761944761229, 11695.824562631396, 11927.045836333245, 11960.76078478408, 12049.397530123284, 12218.938905305416, 11224.438778061227, 11482.425878706199, 11496.367699776592, 11595.536178552764, 11607.825882611598, 9567.186789123003, 9651.0, 9808.65669701557, 10267.84598231042, 10461.790764184825, 7875.842483150191, 8604.397692161489, 8922.68770593026, 9612.855807163038, 9630.662926797528], "value": 7247.957215378891, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1581.2000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 118.0, 117.0, 118.0, 117.0, 117.0, 150.0, 176.0, 149.0], "value": 128.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 125.0, 140.0, 186.0, 142.0, 115.0, 134.0, 144.0, 166.0, 109.0], "value": 140.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 208.0, 226.0, 202.0, 197.0, 198.0, 195.0, 196.0, 200.0, 195.0], "value": 202.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 64.0, 72.0, 108.0, 86.0, 64.0, 69.0, 81.0, 103.0, 54.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 73.0, 85.0, 78.0, 95.0, 64.0, 86.0, 102.0, 58.0, 104.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 103.0, 89.0, 88.0, 88.0, 110.0, 105.0, 135.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 98.0, 99.0, 97.0, 97.0, 97.0, 97.0, 104.0, 98.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 106.0, 95.0, 92.0, 92.0, 92.0, 91.0, 92.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 63.0, 65.0, 66.0, 67.0, 65.0, 67.0, 65.0, 66.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 70.0, 82.0, 76.0, 79.0, 70.0, 70.0, 69.0, 72.0, 73.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 107.0, 112.0, 89.0, 86.0, 78.0, 98.0, 111.0, 81.0, 94.0], "value": 95.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 154.0, 158.0, 157.0, 158.0, 155.0, 157.0, 182.0, 187.0, 187.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 182.0, 177.0, 176.0, 178.0, 177.0, 178.0, 196.0, 172.0, 236.0], "value": 184.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 70.0, 67.0, 75.0, 79.0, 83.0, 86.0, 83.0, 67.0], "value": 74.7, "unit": "score"}]}]} 13:18:45 INFO - Return code: 0 13:18:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:18:45 ERROR - # TBPL SUCCESS # 13:18:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:18:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:18:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:18:45 INFO - 2017-02-14 13:18:45.817 screenresolution[1654:10596] starting screenresolution argv=screenresolution get 13:18:45 INFO - 2017-02-14 13:18:45.820 screenresolution[1654:10596] Display 0: 1600x1200x32@60 13:18:45 INFO - 2017-02-14 13:18:45.826 screenresolution[1655:10599] starting screenresolution argv=screenresolution list 13:18:45 INFO - Available Modes on Display 0 13:18:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:18:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:18:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:18:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:18:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:18:46 INFO - Intel Iris: 13:18:46 INFO - Chipset Model: Intel Iris 13:18:46 INFO - Type: GPU 13:18:46 INFO - Bus: Built-In 13:18:46 INFO - VRAM (Dynamic, Max): 1536 MB 13:18:46 INFO - Vendor: Intel (0x8086) 13:18:46 INFO - Device ID: 0x0a2e 13:18:46 INFO - Revision ID: 0x0009 13:18:46 INFO - Displays: 13:18:46 INFO - Display: 13:18:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:18:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:18:46 INFO - Display Serial Number: 1600x1200 60 13:18:46 INFO - Main Display: Yes 13:18:46 INFO - Mirror: Off 13:18:46 INFO - Online: Yes 13:18:46 INFO - Rotation: Supported 13:18:46 INFO - Return code: 0 13:18:46 INFO - Running post-action listener: _resource_record_post_action 13:18:46 INFO - [mozharness: 2017-02-14 21:18:46.065248Z] Finished run-tests step (success) 13:18:46 INFO - Running post-run listener: _resource_record_post_run 13:18:46 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 37111296; Write bytes: 557991936; Read time: 235; Write time: 1728 13:18:46 INFO - TinderboxPrint: CPU usage
54.6% 13:18:46 INFO - TinderboxPrint: I/O read bytes / time
37,111,296 / 235 13:18:46 INFO - TinderboxPrint: I/O write bytes / time
557,991,936 / 1,728 13:18:46 INFO - TinderboxPrint: CPU idle
2,182.5 (45.4%) 13:18:46 INFO - TinderboxPrint: CPU system
970.5 (20.2%) 13:18:46 INFO - TinderboxPrint: CPU user
1,656.7 (34.4%) 13:18:46 INFO - TinderboxPrint: Swap in / out
290,000,896 / 0 13:18:46 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 246970880; Write bytes: 242309120; Read time: 18069; Write time: 537 13:18:46 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 34997760; Write bytes: 300175360; Read time: 216; Write time: 1146 13:18:46 INFO - Running post-run listener: _upload_blobber_files 13:18:46 INFO - Blob upload gear active. 13:18:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:18:46 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 13:18:46 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'] 13:18:46 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 13:18:46 INFO - (blobuploader) - INFO - Open directory for files ... 13:18:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:18:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:18:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:18:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:18:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:18:47 INFO - (blobuploader) - INFO - Done attempting. 13:18:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:18:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:18:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:18:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:18:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:18:48 INFO - (blobuploader) - INFO - Done attempting. 13:18:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:18:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:18:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:18:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:18:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:18:49 INFO - (blobuploader) - INFO - Done attempting. 13:18:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:18:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:18:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:18:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:18:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:18:51 INFO - (blobuploader) - INFO - Done attempting. 13:18:51 INFO - (blobuploader) - INFO - Iteration through files over. 13:18:51 INFO - Return code: 0 13:18:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:18:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:18:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd234be99aebc4d995eb034a9c64c3ea13822f4396203ee58bb686a31578029854cbef7fee841ffc70910118a923cbd7d476b265bbde37d06ec291e9922a9ba8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad59d247750ea3b22bb1f9ded18e4db7a35ad38420d1aadf8ee70e1ca0cabda8532a366fb80bc2d845f1f7c36445dc948a9286df56eb13739b5109a54f8c472d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f296a902402269a0330f87f32e7ea67d394ddf4e7f96e0bad119ba7febf2af1472c784e87ac78fa0217bd0927378ae61152faac7caf668a5a3cb23009400a4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f71b6d74d671e96ceb0ea319f26924ec467f98e1bdb2db83f94c9a176c78306f48fd869d3d40d0a1ae83b2ea9e6cc1d1ba826648eb1b2c697befd6bfa29d402"} 13:18:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:18:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:18:51 INFO - Contents: 13:18:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd234be99aebc4d995eb034a9c64c3ea13822f4396203ee58bb686a31578029854cbef7fee841ffc70910118a923cbd7d476b265bbde37d06ec291e9922a9ba8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad59d247750ea3b22bb1f9ded18e4db7a35ad38420d1aadf8ee70e1ca0cabda8532a366fb80bc2d845f1f7c36445dc948a9286df56eb13739b5109a54f8c472d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f296a902402269a0330f87f32e7ea67d394ddf4e7f96e0bad119ba7febf2af1472c784e87ac78fa0217bd0927378ae61152faac7caf668a5a3cb23009400a4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f71b6d74d671e96ceb0ea319f26924ec467f98e1bdb2db83f94c9a176c78306f48fd869d3d40d0a1ae83b2ea9e6cc1d1ba826648eb1b2c697befd6bfa29d402"} 13:18:51 INFO - Running post-run listener: copy_logs_to_upload_dir 13:18:51 INFO - Copying logs to upload dir... 13:18:51 INFO - mkdir: /builds/slave/test/build/upload/logs 13:18:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.967203 ========= master_lag: 0.41 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-02-14 13:18:52.077082) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-14 13:18:52.078332) ========= 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.NTPDszJ6Is/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.oWARTdi4YF/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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/dd234be99aebc4d995eb034a9c64c3ea13822f4396203ee58bb686a31578029854cbef7fee841ffc70910118a923cbd7d476b265bbde37d06ec291e9922a9ba8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad59d247750ea3b22bb1f9ded18e4db7a35ad38420d1aadf8ee70e1ca0cabda8532a366fb80bc2d845f1f7c36445dc948a9286df56eb13739b5109a54f8c472d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f296a902402269a0330f87f32e7ea67d394ddf4e7f96e0bad119ba7febf2af1472c784e87ac78fa0217bd0927378ae61152faac7caf668a5a3cb23009400a4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f71b6d74d671e96ceb0ea319f26924ec467f98e1bdb2db83f94c9a176c78306f48fd869d3d40d0a1ae83b2ea9e6cc1d1ba826648eb1b2c697befd6bfa29d402"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011140 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-central-macosx64/1487085285/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dd234be99aebc4d995eb034a9c64c3ea13822f4396203ee58bb686a31578029854cbef7fee841ffc70910118a923cbd7d476b265bbde37d06ec291e9922a9ba8", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad59d247750ea3b22bb1f9ded18e4db7a35ad38420d1aadf8ee70e1ca0cabda8532a366fb80bc2d845f1f7c36445dc948a9286df56eb13739b5109a54f8c472d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f296a902402269a0330f87f32e7ea67d394ddf4e7f96e0bad119ba7febf2af1472c784e87ac78fa0217bd0927378ae61152faac7caf668a5a3cb23009400a4a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f71b6d74d671e96ceb0ea319f26924ec467f98e1bdb2db83f94c9a176c78306f48fd869d3d40d0a1ae83b2ea9e6cc1d1ba826648eb1b2c697befd6bfa29d402"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-14 13:18:52.117557) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2017-02-14 13:18:52.117930) ========= 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.NTPDszJ6Is/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.oWARTdi4YF/Listeners TMPDIR=/var/folders/p5/ggm4rr852vvd80d664drn_0m00000w/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.005026 ========= master_lag: 2.48 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2017-02-14 13:18:54.604505) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-14 13:18:54.604837) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-14 13:18:54.635928) ========= ========= Total master_lag: 2.99 =========