builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0096 starttime: 1489770218.32 results: success (0) buildid: 20170317083920 builduid: b5e327c5c2884141a6aa1ae87c3a1bc2 revision: b7ba1d2827750be9bc599ca5365aa350ae3995d3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:38.316642) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:38.317187) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:38.317509) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:38.344729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:38.345146) ========= 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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-17 10:03:38-- 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% 13.4M=0.001s 2017-03-17 10:03:38 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.185655 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:38.555917) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:38.556319) ========= 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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.028886 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:38.607809) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-17 10:03:38.608317) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b7ba1d2827750be9bc599ca5365aa350ae3995d3 --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 b7ba1d2827750be9bc599ca5365aa350ae3995d3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jwDjGR3gbM/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.nmlvOhP4JV/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-17 10:03:38,713 truncating revision to first 12 chars 2017-03-17 10:03:38,714 Setting DEBUG logging. 2017-03-17 10:03:38,714 attempt 1/10 2017-03-17 10:03:38,714 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b7ba1d282775?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-17 10:03:40,468 unpacking tar archive at: mozilla-central-b7ba1d282775/testing/mozharness/ program finished with exit code 0 elapsedTime=2.030974 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-03-17 10:03:40.665683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:40.666058) ========= script_repo_revision: b7ba1d2827750be9bc599ca5365aa350ae3995d3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:40.666507) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:40.666805) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-17 10:03:40.687606) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-17 10:03:40.688446) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:03:40 INFO - MultiFileLogger online at 20170317 10:03:40 in /builds/slave/test 10:03:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:03:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:03:40 INFO - {'append_to_log': False, 10:03:40 INFO - 'base_work_dir': '/builds/slave/test', 10:03:40 INFO - 'blob_upload_branch': 'Firefox', 10:03:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:03:40 INFO - 'branch': 'Firefox', 10:03:40 INFO - 'buildbot_json_path': 'buildprops.json', 10:03:40 INFO - 'config_files': ('talos/mac_config.py',), 10:03:40 INFO - 'default_actions': ('clobber', 10:03:40 INFO - 'read-buildbot-config', 10:03:40 INFO - 'download-and-extract', 10:03:40 INFO - 'populate-webroot', 10:03:40 INFO - 'create-virtualenv', 10:03:40 INFO - 'install', 10:03:40 INFO - 'run-tests'), 10:03:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:03:40 INFO - 'download_minidump_stackwalk': True, 10:03:40 INFO - 'download_symbols': 'ondemand', 10:03:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:03:40 INFO - 'tooltool.py': '/tools/tooltool.py', 10:03:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:03:40 INFO - '/tools/misc-python/virtualenv.py')}, 10:03:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:03:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:03:40 INFO - 'gecko_profile': False, 10:03:40 INFO - 'gecko_profile_interval': 0, 10:03:40 INFO - 'installer_path': 'installer.exe', 10:03:40 INFO - 'log_level': 'info', 10:03:40 INFO - 'log_name': 'talos', 10:03:40 INFO - 'log_to_console': True, 10:03:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:03:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:03:40 INFO - 'opt_config_files': (), 10:03:40 INFO - 'pip_index': False, 10:03:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:03:40 INFO - 'cmd': ('bash', 10:03:40 INFO - '-c', 10:03:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:03:40 INFO - 'enabled': True, 10:03:40 INFO - 'halt_on_failure': False, 10:03:40 INFO - 'name': 'check_screen_resolution'},), 10:03:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:03:40 INFO - 'cmd': ('bash', 10:03:40 INFO - '-c', 10:03:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:03:40 INFO - 'enabled': True, 10:03:40 INFO - 'halt_on_failure': False, 10:03:40 INFO - 'name': 'check_screen_resolution'},), 10:03:40 INFO - 'run_cmd_checks_enabled': True, 10:03:40 INFO - 'suite': 'dromaeojs', 10:03:40 INFO - 'system_bits': '32', 10:03:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:03:40 INFO - 'title': 't-yosemite-r7-0096', 10:03:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:03:40 INFO - 'use_talos_json': True, 10:03:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:03:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:03:40 INFO - 'work_dir': 'build'} 10:03:40 INFO - [mozharness: 2017-03-17 17:03:40.906625Z] Running clobber step. 10:03:40 INFO - Running pre-action listener: _resource_record_pre_action 10:03:40 INFO - Running main action method: clobber 10:03:40 INFO - rmtree: /builds/slave/test/build 10:03:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:03:43 INFO - Running post-action listener: _resource_record_post_action 10:03:43 INFO - [mozharness: 2017-03-17 17:03:43.564970Z] Finished clobber step (success) 10:03:43 INFO - [mozharness: 2017-03-17 17:03:43.565100Z] Running read-buildbot-config step. 10:03:43 INFO - Running pre-action listener: _resource_record_pre_action 10:03:43 INFO - Running main action method: read_buildbot_config 10:03:43 INFO - Using buildbot properties: 10:03:43 INFO - { 10:03:43 INFO - "project": "", 10:03:43 INFO - "product": "firefox", 10:03:43 INFO - "script_repo_revision": "production", 10:03:43 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:03:43 INFO - "repository": "", 10:03:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:03:43 INFO - "buildid": "20170317083920", 10:03:43 INFO - "pgo_build": "False", 10:03:43 INFO - "basedir": "/builds/slave/test", 10:03:43 INFO - "buildnumber": 74, 10:03:43 INFO - "slavename": "t-yosemite-r7-0096", 10:03:43 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:03:43 INFO - "platform": "yosemite_r7", 10:03:43 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:03:43 INFO - "branch": "mozilla-central", 10:03:43 INFO - "repo_path": "mozilla-central", 10:03:43 INFO - "revision": "b7ba1d2827750be9bc599ca5365aa350ae3995d3", 10:03:43 INFO - "stage_platform": "macosx64", 10:03:43 INFO - "builduid": "b5e327c5c2884141a6aa1ae87c3a1bc2", 10:03:43 INFO - "slavebuilddir": "test" 10:03:43 INFO - } 10:03:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 10:03:43 INFO - Running post-action listener: _resource_record_post_action 10:03:43 INFO - [mozharness: 2017-03-17 17:03:43.568586Z] Finished read-buildbot-config step (success) 10:03:43 INFO - [mozharness: 2017-03-17 17:03:43.568703Z] Running download-and-extract step. 10:03:43 INFO - Running pre-action listener: _resource_record_pre_action 10:03:43 INFO - Running main action method: download_and_extract 10:03:43 INFO - mkdir: /builds/slave/test/build/tests 10:03:43 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')]} 10:03:43 INFO - trying https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:03:43 INFO - Downloading https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:03:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:03:44 INFO - Downloaded 1325 bytes. 10:03:44 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:03:44 INFO - Using the following test package requirements: 10:03:44 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:03:44 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:03:44 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:03:44 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:03:44 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'jsshell-mac.zip'], 10:03:44 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:03:44 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:03:44 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:03:44 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:03:44 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:03:44 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:03:44 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:03:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:03:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:03:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:03:44 INFO - Fetch https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:03:46 INFO - Expected file size: 52472792 10:03:46 INFO - Obtained file size: 52472792 10:04:02 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:04:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:04:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:04:02 INFO - Fetch https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:04:04 INFO - Expected file size: 52472792 10:04:04 INFO - Obtained file size: 52472792 10:04:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:04:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:04:20 INFO - Fetch https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:04:21 INFO - Expected file size: 14404171 10:04:21 INFO - Obtained file size: 14404171 10:04:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:21 INFO - trying https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:04:21 INFO - Downloading https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:04:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:04:23 INFO - Downloaded 75072766 bytes. 10:04:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:04:23 INFO - mkdir: /builds/slave/test/properties 10:04:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:04:23 INFO - Writing to file /builds/slave/test/properties/build_url 10:04:23 INFO - Contents: 10:04:23 INFO - build_url:https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:04:24 INFO - Running post-action listener: _resource_record_post_action 10:04:24 INFO - Running post-action listener: set_extra_try_arguments 10:04:24 INFO - [mozharness: 2017-03-17 17:04:24.335160Z] Finished download-and-extract step (success) 10:04:24 INFO - [mozharness: 2017-03-17 17:04:24.335281Z] Running populate-webroot step. 10:04:24 INFO - Running pre-action listener: _resource_record_pre_action 10:04:24 INFO - Running main action method: populate_webroot 10:04:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:04:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:04:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:04:24 INFO - '--tppagecycles', 10:04:24 INFO - '7'], 10:04:24 INFO - 'tests': ['tsvgm']}}, 10:04:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:04:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:04:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:04:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:04:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:04:24 INFO - 'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:04:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:04:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:04:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:04:24 INFO - 'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['damp', 'tps']}, 10:04:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:04:24 INFO - 'tests': ['damp', 'tps']}, 10:04:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['dromaeo_dom']}, 10:04:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:04:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['basic_compositor_video']}, 10:04:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:04:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['a11yr', 10:04:24 INFO - 'ts_paint', 10:04:24 INFO - 'tpaint', 10:04:24 INFO - 'sessionrestore', 10:04:24 INFO - 'sessionrestore_no_auto_restore', 10:04:24 INFO - 'tabpaint']}, 10:04:24 INFO - 'other-e10s': {'tests': ['a11yr', 10:04:24 INFO - 'ts_paint', 10:04:24 INFO - 'tpaint', 10:04:24 INFO - 'sessionrestore', 10:04:24 INFO - 'sessionrestore_no_auto_restore', 10:04:24 INFO - 'tabpaint']}, 10:04:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:04:24 INFO - 'ts_paint', 10:04:24 INFO - 'tpaint', 10:04:24 INFO - 'sessionrestore', 10:04:24 INFO - 'sessionrestore_no_auto_restore', 10:04:24 INFO - 'tabpaint']}, 10:04:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:04:24 INFO - 'ts_paint', 10:04:24 INFO - 'tpaint', 10:04:24 INFO - 'sessionrestore', 10:04:24 INFO - 'sessionrestore_no_auto_restore', 10:04:24 INFO - 'tabpaint']}, 10:04:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['a11yr', 10:04:24 INFO - 'ts_paint', 10:04:24 INFO - 'tpaint', 10:04:24 INFO - 'sessionrestore', 10:04:24 INFO - 'sessionrestore_no_auto_restore', 10:04:24 INFO - 'tabpaint']}, 10:04:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['a11yr', 10:04:24 INFO - 'ts_paint', 10:04:24 INFO - 'tpaint', 10:04:24 INFO - 'sessionrestore', 10:04:24 INFO - 'sessionrestore_no_auto_restore', 10:04:24 INFO - 'tabpaint']}, 10:04:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['tsvgx', 10:04:24 INFO - 'tsvgr_opacity', 10:04:24 INFO - 'tart', 10:04:24 INFO - 'tscrollx', 10:04:24 INFO - 'cart', 10:04:24 INFO - 'tsvg_static']}, 10:04:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:04:24 INFO - 'tsvgr_opacity', 10:04:24 INFO - 'tart', 10:04:24 INFO - 'tscrollx', 10:04:24 INFO - 'cart', 10:04:24 INFO - 'tsvg_static']}, 10:04:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:04:24 INFO - 'talos_options': ['--disable-e10s'], 10:04:24 INFO - 'tests': ['tp5o']}, 10:04:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:04:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:04:24 INFO - 'talos_options': ['--disable-e10s', 10:04:24 INFO - '--xperf_path', 10:04:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:04:24 INFO - 'tests': ['tp5n']}, 10:04:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:04:24 INFO - 'talos_options': ['--xperf_path', 10:04:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:04:24 INFO - 'tests': ['tp5n']}}, 10:04:24 INFO - 'talos.zip': {'path': '', 10:04:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:04:24 INFO - Running post-action listener: _resource_record_post_action 10:04:24 INFO - [mozharness: 2017-03-17 17:04:24.353545Z] Finished populate-webroot step (success) 10:04:24 INFO - [mozharness: 2017-03-17 17:04:24.353678Z] Running create-virtualenv step. 10:04:24 INFO - Running pre-action listener: _resource_record_pre_action 10:04:24 INFO - Running main action method: create_virtualenv 10:04:24 INFO - Creating virtualenv /builds/slave/test/build/venv 10:04:24 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 10:04:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:04:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:04:24 INFO - Using real prefix '/tools/python27' 10:04:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:04:25 INFO - Installing distribute.............................................................................................................................................................................................done. 10:04:28 INFO - Installing pip.................done. 10:04:28 INFO - Return code: 0 10:04:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:04:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:04:28 INFO - Reading from file tmpfile_stdout 10:04:28 INFO - Output received: 10:04:28 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) 10:04:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:04: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')]} 10:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04: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')]} 10:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:28 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 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:28 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 10:04:28 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 10:04:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:28 INFO - 'HOME': '/Users/cltbld', 10:04:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:28 INFO - 'LOGNAME': 'cltbld', 10:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:28 INFO - 'MOZ_NO_REMOTE': '1', 10:04:28 INFO - 'NO_EM_RESTART': '1', 10:04:28 INFO - 'PAGER': '/bin/cat', 10:04:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:28 INFO - 'PWD': '/builds/slave/test', 10:04:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:28 INFO - 'SHELL': '/bin/bash', 10:04:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:28 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:28 INFO - 'USER': 'cltbld', 10:04:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:28 INFO - 'XPC_FLAGS': '0x0', 10:04:28 INFO - 'XPC_SERVICE_NAME': '0', 10:04:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:28 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 10:04:28 INFO - Cleaning up... 10:04:28 INFO - Return code: 0 10:04:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:04: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')]} 10:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04: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')]} 10:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:28 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 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:28 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 10:04:28 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 10:04:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:28 INFO - 'HOME': '/Users/cltbld', 10:04:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:28 INFO - 'LOGNAME': 'cltbld', 10:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:28 INFO - 'MOZ_NO_REMOTE': '1', 10:04:28 INFO - 'NO_EM_RESTART': '1', 10:04:28 INFO - 'PAGER': '/bin/cat', 10:04:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:28 INFO - 'PWD': '/builds/slave/test', 10:04:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:28 INFO - 'SHELL': '/bin/bash', 10:04:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:28 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:28 INFO - 'USER': 'cltbld', 10:04:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:28 INFO - 'XPC_FLAGS': '0x0', 10:04:28 INFO - 'XPC_SERVICE_NAME': '0', 10:04:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:28 INFO - Downloading/unpacking psutil>=3.1.1 10:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:04:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:04:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:04:32 INFO - Installing collected packages: psutil 10:04:32 INFO - Running setup.py install for psutil 10:04:32 INFO - building 'psutil._psutil_osx' extension 10:04:32 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 10:04:33 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 10:04:33 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 10:04:33 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 10:04:33 INFO - building 'psutil._psutil_posix' extension 10:04:33 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 10:04:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:04:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:04:33 INFO - ^ 10:04:33 INFO - 1 warning generated. 10:04:33 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 10:04:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:04:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:04:33 INFO - Successfully installed psutil 10:04:33 INFO - Cleaning up... 10:04:33 INFO - Return code: 0 10:04:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:04:33 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')]} 10:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:33 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')]} 10:04:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:04:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:04:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:33 INFO - 'HOME': '/Users/cltbld', 10:04:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:33 INFO - 'LOGNAME': 'cltbld', 10:04:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:33 INFO - 'MOZ_NO_REMOTE': '1', 10:04:33 INFO - 'NO_EM_RESTART': '1', 10:04:33 INFO - 'PAGER': '/bin/cat', 10:04:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:33 INFO - 'PWD': '/builds/slave/test', 10:04:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:33 INFO - 'SHELL': '/bin/bash', 10:04:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:33 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:33 INFO - 'USER': 'cltbld', 10:04:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:33 INFO - 'XPC_FLAGS': '0x0', 10:04:33 INFO - 'XPC_SERVICE_NAME': '0', 10:04:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:04:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:04:36 INFO - Installing collected packages: mozsystemmonitor 10:04:36 INFO - Running setup.py install for mozsystemmonitor 10:04:36 INFO - Successfully installed mozsystemmonitor 10:04:36 INFO - Cleaning up... 10:04:36 INFO - Return code: 0 10:04:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04: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')]} 10:04:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:37 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 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:37 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 10:04:37 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 10:04:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:37 INFO - 'HOME': '/Users/cltbld', 10:04:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:37 INFO - 'LOGNAME': 'cltbld', 10:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:37 INFO - 'MOZ_NO_REMOTE': '1', 10:04:37 INFO - 'NO_EM_RESTART': '1', 10:04:37 INFO - 'PAGER': '/bin/cat', 10:04:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:37 INFO - 'PWD': '/builds/slave/test', 10:04:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:37 INFO - 'SHELL': '/bin/bash', 10:04:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:37 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:37 INFO - 'USER': 'cltbld', 10:04:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:37 INFO - 'XPC_FLAGS': '0x0', 10:04:37 INFO - 'XPC_SERVICE_NAME': '0', 10:04:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:37 INFO - Downloading/unpacking jsonschema==2.5.1 10:04:37 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 10:04:37 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 10:04:37 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 10:04:37 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 10:04:37 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 10:04:37 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 10:04:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:04:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:04:40 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 10:04:40 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 10:04:40 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 10:04:40 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 10:04:40 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 10:04:40 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 10:04:41 INFO - Downloading functools32-3.2.3-2.tar.gz 10:04:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:04:41 INFO - warning: no files found matching '*.txt' 10:04:41 INFO - no previously-included directories found matching 'build' 10:04:41 INFO - no previously-included directories found matching 'dist' 10:04:41 INFO - no previously-included directories found matching '.git*' 10:04:41 INFO - Installing collected packages: jsonschema, functools32 10:04:41 INFO - Running setup.py install for functools32 10:04:41 INFO - warning: no files found matching '*.txt' 10:04:41 INFO - no previously-included directories found matching 'build' 10:04:41 INFO - no previously-included directories found matching 'dist' 10:04:41 INFO - no previously-included directories found matching '.git*' 10:04:41 INFO - Successfully installed jsonschema functools32 10:04:41 INFO - Cleaning up... 10:04:41 INFO - Return code: 0 10:04:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:04:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:04:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:41 INFO - 'HOME': '/Users/cltbld', 10:04:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:41 INFO - 'LOGNAME': 'cltbld', 10:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:41 INFO - 'MOZ_NO_REMOTE': '1', 10:04:41 INFO - 'NO_EM_RESTART': '1', 10:04:41 INFO - 'PAGER': '/bin/cat', 10:04:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:41 INFO - 'PWD': '/builds/slave/test', 10:04:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:41 INFO - 'SHELL': '/bin/bash', 10:04:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:41 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:41 INFO - 'USER': 'cltbld', 10:04:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:41 INFO - 'XPC_FLAGS': '0x0', 10:04:41 INFO - 'XPC_SERVICE_NAME': '0', 10:04:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:04:41 INFO - Cleaning up... 10:04:41 INFO - Return code: 0 10:04:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:04:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:04:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:41 INFO - 'HOME': '/Users/cltbld', 10:04:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:41 INFO - 'LOGNAME': 'cltbld', 10:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:41 INFO - 'MOZ_NO_REMOTE': '1', 10:04:41 INFO - 'NO_EM_RESTART': '1', 10:04:41 INFO - 'PAGER': '/bin/cat', 10:04:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:41 INFO - 'PWD': '/builds/slave/test', 10:04:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:41 INFO - 'SHELL': '/bin/bash', 10:04:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:41 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:41 INFO - 'USER': 'cltbld', 10:04:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:41 INFO - 'XPC_FLAGS': '0x0', 10:04:41 INFO - 'XPC_SERVICE_NAME': '0', 10:04:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:42 INFO - Downloading/unpacking blobuploader==1.2.4 10:04:42 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 10:04:42 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 10:04:42 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 10:04:42 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 10:04:42 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 10:04:42 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 10:04:44 INFO - Downloading blobuploader-1.2.4.tar.gz 10:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:04:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:04:44 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 10:04:44 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 10:04:44 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 10:04:44 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 10:04:44 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 10:04:44 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 10:04:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:04:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:46 INFO - Downloading docopt-0.6.1.tar.gz 10:04:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:04:46 INFO - Installing collected packages: blobuploader, requests, docopt 10:04:46 INFO - Running setup.py install for blobuploader 10:04:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:04:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:04:46 INFO - Running setup.py install for requests 10:04:46 INFO - Running setup.py install for docopt 10:04:46 INFO - Successfully installed blobuploader requests docopt 10:04:46 INFO - Cleaning up... 10:04:47 INFO - Return code: 0 10:04:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04:47 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')]} 10:04:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:47 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')]} 10:04:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:47 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 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:47 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 10:04:47 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 10:04:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:47 INFO - 'HOME': '/Users/cltbld', 10:04:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:47 INFO - 'LOGNAME': 'cltbld', 10:04:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:47 INFO - 'MOZ_NO_REMOTE': '1', 10:04:47 INFO - 'NO_EM_RESTART': '1', 10:04:47 INFO - 'PAGER': '/bin/cat', 10:04:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:47 INFO - 'PWD': '/builds/slave/test', 10:04:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:47 INFO - 'SHELL': '/bin/bash', 10:04:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:47 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:47 INFO - 'USER': 'cltbld', 10:04:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:47 INFO - 'XPC_FLAGS': '0x0', 10:04:47 INFO - 'XPC_SERVICE_NAME': '0', 10:04:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:04:47 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-_C33Ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:04:47 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-BPWi95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:04:47 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-GfRfMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:04:47 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-VMpg6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:04:47 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-wnjD91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:04:47 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-4Foin1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:04:47 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-95fW5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:04:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:04:47 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-MY7cXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-lJtk9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-trFPGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-3v9ro7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-AmQoxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-TrPVHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-6r5Jh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-teAJ3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-V48_Nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:04:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:04:48 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-lNzNvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:04:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:04:49 INFO - Running setup.py install for manifestparser 10:04:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:04:49 INFO - Running setup.py install for mozcrash 10:04:49 INFO - Running setup.py install for mozdebug 10:04:49 INFO - Running setup.py install for mozdevice 10:04:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:04:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:04:49 INFO - Running setup.py install for mozfile 10:04:49 INFO - Running setup.py install for mozhttpd 10:04:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:04:49 INFO - Running setup.py install for mozinfo 10:04:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Running setup.py install for mozInstall 10:04:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Running setup.py install for mozleak 10:04:50 INFO - Running setup.py install for mozlog 10:04:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Running setup.py install for moznetwork 10:04:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Running setup.py install for mozprocess 10:04:50 INFO - Running setup.py install for mozprofile 10:04:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:04:50 INFO - Running setup.py install for mozrunner 10:04:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:04:51 INFO - Running setup.py install for mozscreenshot 10:04:51 INFO - Running setup.py install for moztest 10:04:51 INFO - Running setup.py install for mozversion 10:04:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:04:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:04:51 INFO - Cleaning up... 10:04:51 INFO - Return code: 0 10:04:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:51 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 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:51 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 10:04:51 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 10:04:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:51 INFO - 'HOME': '/Users/cltbld', 10:04:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:51 INFO - 'LOGNAME': 'cltbld', 10:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:51 INFO - 'MOZ_NO_REMOTE': '1', 10:04:51 INFO - 'NO_EM_RESTART': '1', 10:04:51 INFO - 'PAGER': '/bin/cat', 10:04:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:51 INFO - 'PWD': '/builds/slave/test', 10:04:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:51 INFO - 'SHELL': '/bin/bash', 10:04:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:51 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:51 INFO - 'USER': 'cltbld', 10:04:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:51 INFO - 'XPC_FLAGS': '0x0', 10:04:51 INFO - 'XPC_SERVICE_NAME': '0', 10:04:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:04:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-EwK0DB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:04:51 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)) 10:04:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:04:51 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-TU3yvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-gj3_qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ndbr3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ySCdUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-KCG2uw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-kfBniC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-DACCiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-9qJhXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-GQU8ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:04:52 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)) 10:04:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:04:52 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-91j77S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:04:53 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)) 10:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:04:53 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-kVXJBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:04:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:04:53 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-awbxHo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:04:53 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)) 10:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:04:53 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-fAcN0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:04:53 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)) 10:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:53 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-S0cAaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:04:53 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)) 10:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:04:53 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-CgAfa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:04:53 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)) 10:04:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:04:53 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-kOMJJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:04:53 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)) 10:04:53 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)) 10:04:53 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)) 10:04:53 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)) 10:04:53 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)) 10:04:53 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)) 10:04:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:04:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:04:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:04:56 INFO - Downloading blessings-1.6.tar.gz 10:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:04:56 INFO - Installing collected packages: blessings 10:04:56 INFO - Running setup.py install for blessings 10:04:56 INFO - Successfully installed blessings 10:04:56 INFO - Cleaning up... 10:04:56 INFO - Return code: 0 10:04:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:04:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:04:56 INFO - Reading from file tmpfile_stdout 10:04:56 INFO - Current package versions: 10:04:56 INFO - blessings == 1.6 10:04:56 INFO - blobuploader == 1.2.4 10:04:56 INFO - docopt == 0.6.1 10:04:56 INFO - functools32 == 3.2.3-2 10:04:56 INFO - jsonschema == 2.5.1 10:04:56 INFO - manifestparser == 1.1 10:04:56 INFO - mozInstall == 1.12 10:04:56 INFO - mozcrash == 1.0 10:04:56 INFO - mozdebug == 0.1 10:04:56 INFO - mozdevice == 0.49 10:04:56 INFO - mozfile == 1.2 10:04:56 INFO - mozhttpd == 0.7 10:04:56 INFO - mozinfo == 0.9 10:04:56 INFO - mozleak == 0.1 10:04:56 INFO - mozlog == 3.4 10:04:56 INFO - moznetwork == 0.27 10:04:56 INFO - mozprocess == 0.25 10:04:56 INFO - mozprofile == 0.28 10:04:56 INFO - mozrunner == 6.13 10:04:56 INFO - mozscreenshot == 0.1 10:04:56 INFO - mozsystemmonitor == 0.3 10:04:56 INFO - moztest == 0.8 10:04:56 INFO - mozversion == 1.4 10:04:56 INFO - psutil == 3.1.1 10:04:56 INFO - requests == 1.2.3 10:04:56 INFO - wsgiref == 0.1.2 10:04:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:56 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 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:56 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 10:04:56 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 10:04:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:56 INFO - 'HOME': '/Users/cltbld', 10:04:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:56 INFO - 'LOGNAME': 'cltbld', 10:04:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:56 INFO - 'MOZ_NO_REMOTE': '1', 10:04:56 INFO - 'NO_EM_RESTART': '1', 10:04:56 INFO - 'PAGER': '/bin/cat', 10:04:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:56 INFO - 'PWD': '/builds/slave/test', 10:04:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:56 INFO - 'SHELL': '/bin/bash', 10:04:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:56 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:56 INFO - 'USER': 'cltbld', 10:04:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:56 INFO - 'XPC_FLAGS': '0x0', 10:04:56 INFO - 'XPC_SERVICE_NAME': '0', 10:04:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:04:57 INFO - Cleaning up... 10:04:57 INFO - Return code: 0 10:04:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:04:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104e58b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e5b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffc60562830>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, '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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 10:04:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:04:57 INFO - 'HOME': '/Users/cltbld', 10:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:04:57 INFO - 'LOGNAME': 'cltbld', 10:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:04:57 INFO - 'MOZ_NO_REMOTE': '1', 10:04:57 INFO - 'NO_EM_RESTART': '1', 10:04:57 INFO - 'PAGER': '/bin/cat', 10:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:04:57 INFO - 'PWD': '/builds/slave/test', 10:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:04:57 INFO - 'SHELL': '/bin/bash', 10:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:04:57 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:04:57 INFO - 'USER': 'cltbld', 10:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:04:57 INFO - 'XPC_FLAGS': '0x0', 10:04:57 INFO - 'XPC_SERVICE_NAME': '0', 10:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:04:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:04:57 INFO - Cleaning up... 10:04:57 INFO - Return code: 0 10:04:57 INFO - Running post-action listener: _resource_record_post_action 10:04:57 INFO - Running post-action listener: _start_resource_monitoring 10:04:57 INFO - Starting resource monitoring. 10:04:57 INFO - [mozharness: 2017-03-17 17:04:57.440627Z] Finished create-virtualenv step (success) 10:04:57 INFO - [mozharness: 2017-03-17 17:04:57.441608Z] Running install step. 10:04:57 INFO - Running pre-action listener: _resource_record_pre_action 10:04:57 INFO - Running main action method: install 10:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:04:57 INFO - Reading from file tmpfile_stdout 10:04:57 INFO - Detecting whether we're running mozinstall >=1.0... 10:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:04:57 INFO - Reading from file tmpfile_stdout 10:04:57 INFO - Output received: 10:04:57 INFO - Usage: mozinstall [options] installer 10:04:57 INFO - Options: 10:04:57 INFO - -h, --help show this help message and exit 10:04:57 INFO - -d DEST, --destination=DEST 10:04:57 INFO - Directory to install application into. [default: 10:04:57 INFO - "/builds/slave/test"] 10:04:57 INFO - --app=APP Application being installed. [default: firefox] 10:04:57 INFO - mkdir: /builds/slave/test/build/application 10:04:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:04:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:05:18 INFO - Reading from file tmpfile_stdout 10:05:18 INFO - Output received: 10:05:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:05:18 INFO - Running post-action listener: _resource_record_post_action 10:05:18 INFO - [mozharness: 2017-03-17 17:05:18.174337Z] Finished install step (success) 10:05:18 INFO - [mozharness: 2017-03-17 17:05:18.174475Z] Running run-tests step. 10:05:18 INFO - Running pre-action listener: _resource_record_pre_action 10:05:18 INFO - Running main action method: run_tests 10:05:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:05:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:05:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:05:18 INFO - 2017-03-17 10:05:18.188 screenresolution[1622:7306] starting screenresolution argv=screenresolution get 10:05:18 INFO - 2017-03-17 10:05:18.191 screenresolution[1622:7306] Display 0: 1600x1200x32@60 10:05:18 INFO - 2017-03-17 10:05:18.197 screenresolution[1623:7308] starting screenresolution argv=screenresolution list 10:05:18 INFO - Available Modes on Display 0 10:05:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:05:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:05:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:05:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:05:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:05:18 INFO - Intel Iris: 10:05:18 INFO - Chipset Model: Intel Iris 10:05:18 INFO - Type: GPU 10:05:18 INFO - Bus: Built-In 10:05:18 INFO - VRAM (Dynamic, Max): 1536 MB 10:05:18 INFO - Vendor: Intel (0x8086) 10:05:18 INFO - Device ID: 0x0a2e 10:05:18 INFO - Revision ID: 0x0009 10:05:18 INFO - Displays: 10:05:18 INFO - Display: 10:05:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:05:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:05:18 INFO - Display Serial Number: 1600x1200 60 10:05:18 INFO - Main Display: Yes 10:05:18 INFO - Mirror: Off 10:05:18 INFO - Online: Yes 10:05:18 INFO - Rotation: Supported 10:05:18 INFO - Return code: 0 10:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:05:18 INFO - Python 2.7.3 10:05:18 INFO - Return code: 0 10:05:18 INFO - grabbing minidump binary from tooltool 10:05: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')]} 10:05:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c36d30>, '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 0x104b5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104c2bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:05:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:05:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:05:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:05:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:05:18 INFO - Return code: 0 10:05:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:05:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:05:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:05:18 INFO - ENV: RUST_BACKTRACE is now 1 10:05:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:05:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:05:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 10:05:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0096 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 10:05:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwDjGR3gbM/Render', 10:05:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:05:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:05:18 INFO - 'HOME': '/Users/cltbld', 10:05:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:05:18 INFO - 'LOGNAME': 'cltbld', 10:05:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:05:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:05:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:05:18 INFO - 'MOZ_NO_REMOTE': '1', 10:05:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:05:18 INFO - 'NO_EM_RESTART': '1', 10:05:18 INFO - 'PAGER': '/bin/cat', 10:05:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:05:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:05:18 INFO - 'PWD': '/builds/slave/test', 10:05:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:05:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:05:18 INFO - 'RUST_BACKTRACE': '1', 10:05:18 INFO - 'SHELL': '/bin/bash', 10:05:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nmlvOhP4JV/Listeners', 10:05:18 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 10:05:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:05:18 INFO - 'USER': 'cltbld', 10:05:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:05:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:05:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:05:18 INFO - 'XPC_FLAGS': '0x0', 10:05:18 INFO - 'XPC_SERVICE_NAME': '0', 10:05:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:05:18 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 10:05:18 INFO - mozversion application_buildid: 20170317083920 10:05:18 INFO - mozversion application_changeset: b7ba1d2827750be9bc599ca5365aa350ae3995d3 10:05:18 INFO - mozversion application_display_name: Nightly 10:05:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:05:18 INFO - mozversion application_name: Firefox 10:05:18 INFO - mozversion application_remotingname: firefox 10:05:18 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:05:18 INFO - mozversion application_vendor: Mozilla 10:05:18 INFO - mozversion application_version: 55.0a1 10:05:18 INFO - mozversion platform_buildid: 20170317083920 10:05:18 INFO - mozversion platform_changeset: b7ba1d2827750be9bc599ca5365aa350ae3995d3 10:05:18 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:05:18 INFO - mozversion platform_version: 55.0a1 10:05:18 INFO - using testdate: 1489770318 10:05:18 INFO - actual date: 1489770318 10:05:18 INFO - starting webserver on 'localhost:49239' 10:05:18 INFO - SUITE-START | Running 2 tests 10:05:18 INFO - TEST-START | dromaeo_css 10:05:18 INFO - operating with platform_type : mac_ 10:05:18 INFO - Initialising browser for dromaeo_css test... 10:05:18 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpwCqUke/profile http://localhost:49239/getInfo.html) 10:05:21 INFO - PID 1633 | __metrics Screen width/height:1600/1200 10:05:21 INFO - PID 1633 | colorDepth:24 10:05:21 INFO - PID 1633 | Browser inner width/height: 1024/689 10:05:21 INFO - PID 1633 | __metrics 10:05:21 INFO - PID 1633 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:05:21 INFO - PID 1633 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:05:22 INFO - TEST-INFO | 1633: exit 0 10:05:22 INFO - Browser initialized. 10:05:22 INFO - Running cycle 1/1 for dromaeo_css test... 10:05:22 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpwCqUke/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:05:34 INFO - PID 1635 | RSS: Main: 233541632 10:05:34 INFO - PID 1635 | 10:08:39 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49239/tests/dromaeo/cssquery-ext.html) 10:08:40 INFO - PID 1635 | RSS: Main: 383623168 10:08:40 INFO - PID 1635 | 10:10:23 INFO - PID 1635 | ************************* 10:10:23 INFO - PID 1635 | A coding exception was thrown and uncaught in a Task. 10:10:23 INFO - PID 1635 | 10:10:23 ERROR - PID 1635 | Full message: TypeError: NetworkError when attempting to fetch resource. 10:10:23 INFO - PID 1635 | Full stack: 10:10:23 INFO - PID 1635 | ************************* 10:11:43 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-ext.html (next: http://localhost:49239/tests/dromaeo/cssquery-jquery.html) 10:11:44 INFO - PID 1635 | RSS: Main: 368009216 10:11:44 INFO - PID 1635 | 10:14:46 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49239/tests/dromaeo/cssquery-mootools.html) 10:14:47 INFO - PID 1635 | RSS: Main: 346308608 10:14:47 INFO - PID 1635 | 10:17:49 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49239/tests/dromaeo/cssquery-prototype.html) 10:17:50 INFO - PID 1635 | RSS: Main: 337063936 10:17:50 INFO - PID 1635 | 10:20:54 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49239/tests/dromaeo/cssquery-yui.html) 10:20:54 INFO - PID 1635 | RSS: Main: 344477696 10:20:54 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | Cycle 1(1): loaded http://localhost:49239/tests/dromaeo/cssquery-yui.html (next: http://localhost:49239/tests/dromaeo/cssquery-dojo.html) 10:23:58 INFO - PID 1635 | RSS: Main: 465825792 10:23:58 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | __start_tp_report 10:23:58 INFO - PID 1635 | _x_x_mozilla_page_load 10:23:58 INFO - PID 1635 | _x_x_mozilla_page_load_details 10:23:58 INFO - PID 1635 | |i|pagename|runs| 10:23:58 INFO - PID 1635 | |0;dojo.html;2764.557670772677;3196.2169268529206;3322.9335413291733;3328.6671332866717;3353.480210567363;5473.781048758055;5585.687363469591;5664.037113690672;5701.971490142549;5704.8003319883765;5623.943760562393;5662.801801936933;5693.3452652944925;5721.256236689235;5723.198752174699;4157.66738660908;5125.000624878159;5151.716655583941;5155.097857874875;5198.636095473318;5224.085784987631;5233.73831308435;5264.263003179558;5269.262303277545;5278.604104692164;5088.313077734532;5161.3806343238675;5161.896762064775;5190.766415511311;5221.634485585992;4602.355670206174;5068.467810879842;5174.172132458826;5219.321488206547;5225.529702326771;5131.563817075553;5144.125498665236;5166.509181627777;5182.714950677711;5220.765065572058;5501.174823776426;5544.16837474378;5648.265725455705;5659.462351076641;5681.0910254359105;5088.821891233826;6107.328193898712;6140.394703972021;6187.690615469209;6192.783252586138;6181.629102253879;6238.656873871935;6251.3123556408755;6269.2476902771505;6269.8746025079245;5762.740676669518;5764.538836893039;5765.625234359753;5774.624649397776;5816.97091514544;6112.5946701277735;6197.876042479125;6247.562670613104;6255.718492667841;6269;5967.373425790316;6019.367966363468;6023.608465449732;6144.662043587646;6148.754049837957;6204.534659900525;6219.751209951552;6247.250329960434;6255.311915689271;6255.499560035186;6111.144439778345;6206.162168107338;6224.408681175281;6224.688765561704;6225.28409232935;6068.4841788448475;6222.097795819581;6229.50163986889;6238.812835614939;6242;4740.146773580788;4828.179209534317;4916.950830491721;4937.136001199776;4943.209086546135;4409.845655402046;4413.668974826901;4436.822527098944;4439.289713645801;4446.044100518404;5753.021986262345;5754.280714910637;5762.683052432073;5763.711814409263;5772.191893134965;6024.728887200086;6105.763021687103;6122.2761798032625;6128.411459112287;6238.095476155932;5643.35101463337;5646.181303710939;5674.290713660793;5685.431456854281;5702.486776190163;5327.493888080626;5328.706921119376;5330;5330.280412144389;5345.01117293302;6288.5598008139905;6298.559100862895;6298.937010629927;6306.527010474141;6310.936890631218;5221.660592061504;6177.135201071764;6193.659348735863;6194.535409844189;6214.72033758491;6177.907331389943;6186.505079593711;6199.411055949678;6225.346338634378;6226.439620434183;6132.938670613236;6139.662318572521;6167.229096362955;6172.197614310111;6175.197224360804;5933.228680271537;6226.315105338319;6251.343608920998;6263.96868015657;6270.372962703693;5911.615744976735;6004.279486461653;6053.636781793107;6054.122152287806;6079.422454866781;5843.532517398513;5885;5908.822735317947;6012.398760124128;6038.818835434944;5869.4717475427415;5880.911786323294;5911.142884281841;5916.733746981482;5922.703864806656;5973.223480947449;6039.365866584121;6055.394460553909;6067.666278354556;6095;5660.886782264414;5710.543156547549;5725.083986562297;5779.306483222041;5829.950608890441 10:23:58 INFO - PID 1635 | |1;ext.html;607.4866737606723;728.1553398058251;790.6086487188841;869.2915274051647;879.788850675838;27194.45611087777;35861.103472413204;36316.09209769757;36441.45337819928;36558;37672.68145614904;38059.619403805955;38098.428523572096;39681.80159099208;39721.8083457496;7104.786856394317;7296.890546641778;7302.598357090358;7328.303811137934;7339.449541284398;7272.16370117438;8778.56107194641;10664.360138391683;10696.78606427871;10699.893001069968;10874.293170943864;10938.617148399808;10967.561297548089;10970.177236707239;10977;10811.729706757314;10926.180536459797;10932.508037138348;10936.070434013118;10936.890631093667;7034.6834392452465;7198.7840364789145;10092.495375231209;10309.587616495332;10332.63835765752;36572.81713591415;36883;37009.444858327144;37032;37042.814785926166;3813.7902415367143;3830.425436184567;3840.596737342567;3865.1779608990155;3872.8257228424786;27082.32294192661;27137.45725085507;27143.321416964536;28176.859115704494;28228;3098.612673415823;3142.402943440739;3215.646278909318;3221.597300337458;3239.060672405;276226;311150.44424777955;311305;311580.99999999773;312339;41774;50036;50285.49714502808;50321.49678503168;50345.49654503481;50145;50146.498535014915;50179.74910125499;50302.93335466192;50326.199439087;13965.3715582797;14398.496052638107;14410.495632652806;14440.855591443951;14460.783088253685;9750.171235445066;9888.258380621483;11799.52801887915;11810.704732381759;11816.940915295541;10789.83815242772;10873.891261087289;10901.1279097672;11068.00387965087;11980.820287695691;5224.92162617561;5464.808731694372;5495.752691128909;5534.501894829485;5554.555635549146;769.3345256353326;890.7773056735816;930.851063829784;945.7351941456403;992.9007099290158;27299.31751706223;27545.173644790688;27683.861580691966;28014.43971120607;28037.158885233475;1020.0972139656458;1022.6625213679467;1064;1064.9680509584725;1067.530286673861;11427.314361138358;13106.737865242641;14054;14391.85608143926;14460.349284282232;385766;386415;386879;387186;387225.06387468445;53666.65833354119;53812.73093634585;53831;53858.461415386126;53956;1404.8595140486075;1454.0475988227515;1455.9417623294948;1458.7155504676698;1468.8090548228663;25026.74973250317;26479.205623830545;26770.866145669148;27170.1848944532;27372.589411159246;45314.773426132655;45355.86610334768;45404.77297613491;45447.318290226336;45478;379672;381403;381807;382090;383028;32487.350252995282;33352.83323583366;33412.83293583516;33556.83221583877;33593.824216152316;33346.4998025025;42084;42695.146097078505;42717.78641106775;43011.35482967695;33476;34608.82695586506;34663.480047798796;35895.64104358923;35959.28081438304;33298.833505832314;34716.826415867756;34851.4772278411;35876.103097422776;37048.814755927124;34985;35377.469337960465;35510.82244588761;36113.638863612076;36143.81928090343;30153.698463015968;31607.683923161392;31824.204394890312;31901;31968;20342.593148136657;23374.883125584263;23760.4059898497;24155.51688966246;24296 10:23:58 INFO - PID 1635 | |2;jquery.html;378.3851241732185;391.7257919456379;402.16951994132114;420.6235419299727;431.2259494207897;13022.934885325572;14936.327865246063;17783.64432711345;20686.27598034069;21019.68470472944;22097.226597069104;22302.21942232022;22858.314250572508;22992.8850355748;23330.300090997254;5488.368837583689;6499.447546958514;6500.577462464948;6686.297938716438;6688.531802773808;4129.277376459123;6568.507361947849;6701.229358623747;6711.597304161742;6717.966410167943;4401.647868170555;4453.087117140994;7609;7611.238876112372;7615.923840761605;7292.635368231568;7522.247775222489;7592;7597.088349398053;7603.695852165907;6957.0955775749335;7346.63266836656;7349.265073492661;7362.300581444754;7365.963170184168;21571.784282157136;26712.797924093462;26796.866015669795;27191.416815402506;27231.183064508095;3300.224447254893;3308.267695068339;3318.784279021856;3354.647761985005;3440.2603440260464;15632.296546655427;16641.583960400967;16905.661886762195;17038.82961170397;17324.740128898076;2014.4863059919774;2040.6122836661136;2073.6163809695104;2119.8940052997286;2225.4213904384815;141783;149416.90233738793;154342.22828885785;154732.2263388687;158942;21781.693098413827;21812.45468863291;21828.672569911465;22027.449313767283;22212.111515539196;37417.812910935274;37501.87494375119;37545.62454375476;37636.05909852275;37637.247255054746;10383.404460318881;10578.735531611772;10768.515416806262;10971.670849874517;11051.834222486668;8720.389572729848;8720.563971801384;8759;8945.105489451003;8946.776330591787;10109.848352274721;10214.693559193236;10584.312019718695;10670.786584268271;10674.733131671615;3565.35823551758;3658.853645854136;3904.2972264992004;4177.436046133733;4244.957550424518;1037.749511838233;1044.613493007592;1047.9109275711498;1057.2493529593921;1102.1513434655271;19645;22708.205212817804;22900.65649015266;23438.531229375316;23493.53012939766;1063.3247887591435;1071.6356438810842;1100.4497751124438;1103.5144536404116;1112.978264013208;11266.43667816606;11903.285802851857;12046.939765301293;12160.513579456723;12199.817002744965;505217;627891;628355;641165;644243.7787811031;134773;148170;149900.50099499084;152183;154721;1063.4682658670665;1134.8354488599273;1241.4224259835414;1243.185713357752;1270.866559011309;19385.8061419384;20232.39302820918;20354.28759993393;20401.69397459069;20491.897540512797;42765;58614.534636634424;58941;59904.40095599162;60613.78772424439;81079;82175.35649286861;82611;82639.586802068;82941;6830.043793868763;7517.661705223169;8460;9665.371750836173;10567.418791966516;10838.729031774268;13829.585112446643;13990;14087.366068526735;14373.209473479059;10933.617323393642;11713.824292635787;12045.217060891015;12110.45502952387;12122.27266364021;11355.772884542097;11615.303081815118;11627.59303424376;11797.351145686725;11924.940375298069;11759.882401175879;11872.82190767122;11974.58088966882;12164.148509604243;12243.020558355132;12091.455884485395;12095.21381110225;12433.37833108323;12457.626271211879;12564.623061308175;11656.358900260228;11884.26661867758;11886.643400697993;11928.701782455508;12002.279863208236 10:23:58 INFO - PID 1635 | |3;mootools.html;1547.8297387287403;1570.7957965464489;1597.6245582288168;1604.7913771209737;1659.9917000414998;10451.895481045187;10952.780944381118;11088;11094.722631934186;11104.500297486613;10411.583536658525;10792.082672972787;10813.729656758585;10818.296810707317;10837.349759014465;2330.6853574767442;2375.4417711837705;2379.238643634038;2384.5588566115325;2385.5348207099623;2325.4186453386656;2360.24472168906;2364.4679947011955;2364.527094581082;2373.845700029501;2067.687018743094;2251.1445650652813;2337.883105844701;2339.508703172336;2349.5770761262966;2300.367398965281;2313.282882306482;2319.060780383947;2322.465832858452;2329.988350058256;2258.8870556472275;2316.420894776306;2321.721393432782;2325.162941341116;2328.301509547146;10830.296030757976;10914.454277286104;10918.0173784359;10918.836217456745;10927.961843624844;5661.801836935688;5874.471297583195;5883.970580147115;5891.79378721747;5893.58744887858;3013.4877070897783;3266.6080070391704;3284.934301313984;3287.1124796305103;3361.445361515347;1167.6030149749042;1194.988050119505;1206.699261416825;1227.2943057741834;1239.9814002789965;13749.725005499835;14104.717905641932;14118.505852295222;15226;15264;4513.54864513546;4672.135654903854;4672.322513235562;4673.392458980311;4683.859484215479;11430.657080287605;11439.485223164978;11447.25592836463;11475.426228688531;11556.94221528887;4890;5015.072211640858;5028.572571331478;5712.400197979236;5712.65724056558;4155.771432571177;5014.222795466709;5250.133727934848;5262.578993680497;5268.762905669254;3741.5323084614424;3831.4061320495366;3837.597052309523;3859.7684138951754;3869.2648396804516;991.7520619845038;1017.704865588971;1024.8872624011353;1029.6859457865273;1029.7528593137743;838.8573942429801;877.2367541242149;910.4946754551288;911.7538264668635;921.0697195832257;6231.40801623845;6323.36766323364;6533.346665333429;6582.210134783761;6613.611141660209;926.3330402110604;928.9907100929039;931.2829121576349;940.3041749105613;972.7908499672604;3948.6841052715713;4003.538708371482;4021.577734337911;4058.6144316289906;4095.2014357200655;12909.612711618667;13466;13469.191848489121;13474.595762127152;13493.460261589622;12989.350532473338;13213.603591892259;13215.14101583394;13226.867731322563;13237.536686215935;8753.562321883881;8987.651852222089;9701.369410988264;9994.550245238981;10002.249831262685;6049.832725820108;6395.744170233139;6514.4788416925585;6515.185601799775;6541.70562324687;11760.76478470443;11880.940595296968;12115.575954841539;12157.02743780513;12181.025517958718;10817;11048.834267486154;12274.447649855956;12405.503779849067;12463.813042804184;9940.602375904882;9964.451955142536;10000.449975251431;10047.698569042941;10090.798184036425;9931;10038.19694424459;10135.493225338556;10142.137918277029;10155.136813370946;8907.287417006495;8951;8958.059403762772;8976.147266009719;9055.411398259092;8727.301815854844;8805.823883522422;8807.07525709817;8808.603612837565;8888.466691998501;7403.8149046274275;7577.280158384945;7678.308952194329;7685.193054729397;7696.961515192388;5764.510016648632;5850.561207909339;5855.912161317498;5929.229200203946;5936.910946335895;5794.449527294901;5875;5959.195508606283;6049.939500604937;6167.722452489559 10:23:58 INFO - PID 1635 | |4;prototype.html;319.6132679457856;387.7983448606723;388.134460153857;389.08564872549493;392.4878034165413;5667.874547044858;5699.601027928046;5739.110437882125;5783.5373170146395;5822.563307751925;5329.493698098684;5475.86441287897;5628.43715628438;5650.971745141269;5676.602637815355;4199.664026877843;4320.719153255044;4369.497507786614;4437.911241775162;4458.152950939328;3975.880723578283;4059.4113853491226;4417.1607394595085;4429.313456414261;4456.955430445702;4092.2830238475863;4385.912281754379;4393.517662520666;4423.77881105945;4428.051657270807;3659.9085022874374;3750.6061863504347;4353.629941454964;4370.650347972186;4385.8245670173155;3888.0474283800368;3888.3778595424596;3890.8443662253476;3903.551091624471;3929.9214015719813;5486.286782718261;5546.69493177875;5577.581681373913;5586.944130558723;5597.216389705444;4391.670624703128;4906.497686180038;4997.575206107485;5105.5149760772665;5115.7442127893455;5889.705514724246;5905;5954.439590975879;5974.551908606828;6001.789937352171;4174.958250417488;4308.138372325548;4324.789059063467;4324.978375108136;4343.326784348431;5995.010823214165;6164.506839452833;6196;6220.844478888064;6242.375762423767;4272.530021697611;4482.462104547475;5155.226715992556;5169.870753231124;5188.533032027136;5146.330976972969;5238.852070651014;5252.290940722954;5438.401775804658;5446.2647542582035;4093.3859921012086;4757.548032936866;5108.646208754683;5610.579206559524;5643.492085712223;5225.790968361299;5454;5608.018596745554;6010.248718910137;6017.729202185825;1630.853223209893;1648.934042638305;1844.837752216122;1851.8333349998566;1875.493616723477;1159.6173262823247;1464.6484843637663;1508.7435136026902;1514.8186611279723;1524.4664367471296;5653.123765816305;5662.631928924607;5672.773089076473;5675.858103547444;5677.403872593401;6176.6528719087355;6179.16581261533;6179.969100154471;6191.566590338633;6194.194754681863;5398.541124004425;5547.306586676666;5630.74661640219;5686.43135686428;5717.799877004368;2691.7039125696156;2966.6440027196873;3224.2584205632834;3269.8038117713013;3296.258341873059;5087.516685914832;6037.973544497446;6105.786297479567;6386.201724784402;6387.425131738073;6027.660084738981;6090.994985827378;6325.715342809584;6343.682815859189;6348.650824204621;6068.726907289182;6393.712282947278;6412.947856685816;6417.294097649348;6438.7746428873825;5509.889802204014;5795.565332600157;5857.092150716646;6205.844853878509;6252.187215661935;5850.561207909509;5945.821625351246;5994.28068631767;6073.726682299395;6099.359567245376;6167.352427994996;6349.015902535114;6401.799100449775;6407.743690252524;6423.614583125028;4296.76367799774;5294.8941021180135;5322.33470816148;5342.679439233659;5343.4389389113585;5413.160960051198;6117.480014198843;6130.693465326805;6156.107364432043;6211.81364559064;5629.605927585029;5962.850928726816;5966.522678185822;6013.849653758691;6025.879482410415;5400.624609618988;5757.971210143923;5859.648421094748;5916.964531206935;5921.230240068763;5844.824655260349;5845.561582881386;5855.707214639337;5866.413358664099;5874.765009399576;4830.9275360870315;5047.974760126176;5587.972060139673;6012.5791194617705;6052.152698622109;4840.854774356775;5414.3232095988005;5596.916046259228;5754.223179870832;5800.912986305293 10:23:58 INFO - PID 1635 | |5;yui.html;743.9367653749431;754.2985023927748;815.1766715617226;815.4821688227976;818.4148333941231;13126.540571080013;14704.926475367634;15482.690346193083;15765.92117039416;15773.053616783001;12123.515059397636;14479.5656130316;14633.487827926025;14702;14751;928.2434815625278;940.0505489455664;959.4339339789523;1007.279794946613;1012.1649639047778;998.366037566147;1006.954687039085;1008.8234558952191;1008.8839783424927;1010.9494525273743;1007.3452256033601;1029.8352263637819;1030.6289735695182;1032.1484775060567;1032.989670103297;1023.3603997501561;1025.1337619711333;1026.4303311662024;1028.98644834838;1029.4749677664408;763.5495057915856;763.6029264782283;764.1174443517746;764.3464837563914;764.9082110146762;15721.37114515418;15895.52313430599;16087.195640218059;16621.916890415592;16695.499135025846;14560.927195363955;14564.49024284145;14597;14613.634659133606;14704.33830477631;10701.571937122506;11391.772164556745;11529;11709.70725731855;12235.082368822284;3399.2351720862857;3607;3703.5555733311908;3861.2663593917346;3863.0728625130046;80711.596442018;82022.76965845517;82709;82884.17115828885;87461.56269218613;11269.380184089954;11404.543818247177;11444.599439019761;11519.136064795006;11529.538818447169;24397.39006524851;25008.749912501007;25009.249722508353;25011.87494062518;25125.371865703502;7837.960810195913;7842.490238134503;7858.0570331560575;7997.920020799717;8059.395545334123;4112.897177570585;4170.9582904171175;4231.978840105841;4250.957490425056;4342.6308763754805;3495.1611613213154;3522.260325331709;3552.0409489437707;3580.2839432113683;3586.049696830342;655.7442597387023;658.980230593083;659.2550418027598;674.649182425146;676.6278546799241;664.4649868542839;682.931706829313;694.5381321421198;720.9330191316776;723.7901008707521;9021.097890211058;9632.86565576741;10460.215483838743;10545.407564946023;10587.15302775776;642.5084810120262;660.5640277416882;686.9519133660696;692.51869950384;727.1749254147675;4952.4057113146655;5004.42449118356;5005.599552035901;5011.223260394645;5027.902477740067;100901;103456.44815327776;103516;105354;105853;75349.62325188449;88117.1188288109;88317.11682883088;88352.5582372084;88997.3256695809;76971.23028769753;77840.61079694565;78000.60999695193;78054;78341.60829195932;7517.887231691639;8761.693340733043;9520.952395237979;9601.519924003911;9723.854142188015;49406;49504.75247623883;49976;52231.95536089333;52335.47664523306;63186.68406657937;63961.040584392205;64150.35849641444;64801.351986481415;65315.36711582248;13360.131591446525;13466;13575.185488870698;13758.93120534431;14065.694561778178;14907.403703852158;14935.85064149388;15097.920714439088;15116.924415377853;15255.542333730005;12190.390480476119;13679;13756.724865502834;13879.236641984788;13972.510962116277;11463.885361146615;13793.448262069405;13843.023749893418;14199.290035497977;14315.856841431452;13464;13737.725245494834;13750.793738094137;13762.380692869045;14066.578002659937;11060.778784424427;11119.413393906892;11347.432628368384;11406;11704.94147529257;9388.624455021996;9481.431114133175;10497.632582859564;10904.34573925567;11173.55305787792 10:23:58 INFO - PID 1635 | __end_tp_report 10:23:58 INFO - PID 1635 | __start_cc_report 10:23:58 INFO - PID 1635 | _x_x_mozilla_cycle_collect,1522 10:23:58 INFO - PID 1635 | __end_cc_report 10:23:58 INFO - PID 1635 | __startTimestamp1489771438569__endTimestamp 10:23:58 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | ------- Summary: start ------- 10:23:58 INFO - PID 1635 | Number of tests: 6 10:23:58 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | [#0] dojo.html Cycles:165 Average:5675.02 Median:5843.53 stddev:660.76 (11.3%) stddev-sans-first:622.09 10:23:58 INFO - PID 1635 | Values: 2764.6 3196.2 3322.9 3328.7 3353.5 5473.8 5585.7 5664.0 5702.0 5704.8 5623.9 5662.8 5693.3 5721.3 5723.2 4157.7 5125.0 5151.7 5155.1 5198.6 5224.1 5233.7 5264.3 5269.3 5278.6 5088.3 5161.4 5161.9 5190.8 5221.6 4602.4 5068.5 5174.2 5219.3 5225.5 5131.6 5144.1 5166.5 5182.7 5220.8 5501.2 5544.2 5648.3 5659.5 5681.1 5088.8 6107.3 6140.4 6187.7 6192.8 6181.6 6238.7 6251.3 6269.2 6269.9 5762.7 5764.5 5765.6 5774.6 5817.0 6112.6 6197.9 6247.6 6255.7 6269.0 5967.4 6019.4 6023.6 6144.7 6148.8 6204.5 6219.8 6247.3 6255.3 6255.5 6111.1 6206.2 6224.4 6224.7 6225.3 6068.5 6222.1 6229.5 6238.8 6242.0 4740.1 4828.2 4917.0 4937.1 4943.2 4409.8 4413.7 4436.8 4439.3 4446.0 5753.0 5754.3 5762.7 5763.7 5772.2 6024.7 6105.8 6122.3 6128.4 6238.1 5643.4 5646.2 5674.3 5685.4 5702.5 5327.5 5328.7 5330.0 5330.3 5345.0 6288.6 6298.6 6298.9 6306.5 6310.9 5221.7 6177.1 6193.7 6194.5 6214.7 6177.9 6186.5 6199.4 6225.3 6226.4 6132.9 6139.7 6167.2 6172.2 6175.2 5933.2 6226.3 6251.3 6264.0 6270.4 5911.6 6004.3 6053.6 6054.1 6079.4 5843.5 5885.0 5908.8 6012.4 6038.8 5869.5 5880.9 5911.1 5916.7 5922.7 5973.2 6039.4 6055.4 6067.7 6095.0 5660.9 5710.5 5725.1 5779.3 5830.0 10:23:58 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | [#1] ext.html Cycles:180 Average:50318.68 Median:34784.15 stddev:94880.55 (272.8%) stddev-sans-first:95073.31 10:23:58 INFO - PID 1635 | Values: 607.5 728.2 790.6 869.3 879.8 27194.5 35861.1 36316.1 36441.5 36558.0 37672.7 38059.6 38098.4 39681.8 39721.8 7104.8 7296.9 7302.6 7328.3 7339.4 7272.2 8778.6 10664.4 10696.8 10699.9 10874.3 10938.6 10967.6 10970.2 10977.0 10811.7 10926.2 10932.5 10936.1 10936.9 7034.7 7198.8 10092.5 10309.6 10332.6 36572.8 36883.0 37009.4 37032.0 37042.8 3813.8 3830.4 3840.6 3865.2 3872.8 27082.3 27137.5 27143.3 28176.9 28228.0 3098.6 3142.4 3215.6 3221.6 3239.1 276226.0 311150.4 311305.0 311581.0 312339.0 41774.0 50036.0 50285.5 50321.5 50345.5 50145.0 50146.5 50179.7 50302.9 50326.2 13965.4 14398.5 14410.5 14440.9 14460.8 9750.2 9888.3 11799.5 11810.7 11816.9 10789.8 10873.9 10901.1 11068.0 11980.8 5224.9 5464.8 5495.8 5534.5 5554.6 769.3 890.8 930.9 945.7 992.9 27299.3 27545.2 27683.9 28014.4 28037.2 1020.1 1022.7 1064.0 1065.0 1067.5 11427.3 13106.7 14054.0 14391.9 14460.3 385766.0 386415.0 386879.0 387186.0 387225.1 53666.7 53812.7 53831.0 53858.5 53956.0 1404.9 1454.0 1455.9 1458.7 1468.8 25026.7 26479.2 26770.9 27170.2 27372.6 45314.8 45355.9 45404.8 45447.3 45478.0 379672.0 381403.0 381807.0 382090.0 383028.0 32487.4 33352.8 33412.8 33556.8 33593.8 33346.5 42084.0 42695.1 42717.8 43011.4 33476.0 34608.8 34663.5 35895.6 35959.3 33298.8 34716.8 34851.5 35876.1 37048.8 34985.0 35377.5 35510.8 36113.6 36143.8 30153.7 31607.7 31824.2 31901.0 31968.0 20342.6 23374.9 23760.4 24155.5 24296.0 10:23:58 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | [#2] jquery.html Cycles:180 Average:38969.93 Median:11845.84 stddev:103120.83 (870.5%) stddev-sans-first:103369.40 10:23:58 INFO - PID 1635 | Values: 378.4 391.7 402.2 420.6 431.2 13022.9 14936.3 17783.6 20686.3 21019.7 22097.2 22302.2 22858.3 22992.9 23330.3 5488.4 6499.4 6500.6 6686.3 6688.5 4129.3 6568.5 6701.2 6711.6 6718.0 4401.6 4453.1 7609.0 7611.2 7615.9 7292.6 7522.2 7592.0 7597.1 7603.7 6957.1 7346.6 7349.3 7362.3 7366.0 21571.8 26712.8 26796.9 27191.4 27231.2 3300.2 3308.3 3318.8 3354.6 3440.3 15632.3 16641.6 16905.7 17038.8 17324.7 2014.5 2040.6 2073.6 2119.9 2225.4 141783.0 149416.9 154342.2 154732.2 158942.0 21781.7 21812.5 21828.7 22027.4 22212.1 37417.8 37501.9 37545.6 37636.1 37637.2 10383.4 10578.7 10768.5 10971.7 11051.8 8720.4 8720.6 8759.0 8945.1 8946.8 10109.8 10214.7 10584.3 10670.8 10674.7 3565.4 3658.9 3904.3 4177.4 4245.0 1037.7 1044.6 1047.9 1057.2 1102.2 19645.0 22708.2 22900.7 23438.5 23493.5 1063.3 1071.6 1100.4 1103.5 1113.0 11266.4 11903.3 12046.9 12160.5 12199.8 505217.0 627891.0 628355.0 641165.0 644243.8 134773.0 148170.0 149900.5 152183.0 154721.0 1063.5 1134.8 1241.4 1243.2 1270.9 19385.8 20232.4 20354.3 20401.7 20491.9 42765.0 58614.5 58941.0 59904.4 60613.8 81079.0 82175.4 82611.0 82639.6 82941.0 6830.0 7517.7 8460.0 9665.4 10567.4 10838.7 13829.6 13990.0 14087.4 14373.2 10933.6 11713.8 12045.2 12110.5 12122.3 11355.8 11615.3 11627.6 11797.4 11924.9 11759.9 11872.8 11974.6 12164.1 12243.0 12091.5 12095.2 12433.4 12457.6 12564.6 11656.4 11884.3 11886.6 11928.7 12002.3 10:23:58 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | [#3] mootools.html Cycles:180 Average:6602.18 Median:2385.05 stddev:4120.58 (172.8%) stddev-sans-first:4114.64 10:23:58 INFO - PID 1635 | Values: 1547.8 1570.8 1597.6 1604.8 1660.0 10451.9 10952.8 11088.0 11094.7 11104.5 10411.6 10792.1 10813.7 10818.3 10837.3 2330.7 2375.4 2379.2 2384.6 2385.5 2325.4 2360.2 2364.5 2364.5 2373.8 2067.7 2251.1 2337.9 2339.5 2349.6 2300.4 2313.3 2319.1 2322.5 2330.0 2258.9 2316.4 2321.7 2325.2 2328.3 10830.3 10914.5 10918.0 10918.8 10928.0 5661.8 5874.5 5884.0 5891.8 5893.6 3013.5 3266.6 3284.9 3287.1 3361.4 1167.6 1195.0 1206.7 1227.3 1240.0 13749.7 14104.7 14118.5 15226.0 15264.0 4513.5 4672.1 4672.3 4673.4 4683.9 11430.7 11439.5 11447.3 11475.4 11556.9 4890.0 5015.1 5028.6 5712.4 5712.7 4155.8 5014.2 5250.1 5262.6 5268.8 3741.5 3831.4 3837.6 3859.8 3869.3 991.8 1017.7 1024.9 1029.7 1029.8 838.9 877.2 910.5 911.8 921.1 6231.4 6323.4 6533.3 6582.2 6613.6 926.3 929.0 931.3 940.3 972.8 3948.7 4003.5 4021.6 4058.6 4095.2 12909.6 13466.0 13469.2 13474.6 13493.5 12989.4 13213.6 13215.1 13226.9 13237.5 8753.6 8987.7 9701.4 9994.6 10002.2 6049.8 6395.7 6514.5 6515.2 6541.7 11760.8 11880.9 12115.6 12157.0 12181.0 10817.0 11048.8 12274.4 12405.5 12463.8 9940.6 9964.5 10000.4 10047.7 10090.8 9931.0 10038.2 10135.5 10142.1 10155.1 8907.3 8951.0 8958.1 8976.1 9055.4 8727.3 8805.8 8807.1 8808.6 8888.5 7403.8 7577.3 7678.3 7685.2 7697.0 5764.5 5850.6 5855.9 5929.2 5936.9 5794.4 5875.0 5959.2 6049.9 6167.7 10:23:58 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | [#4] prototype.html Cycles:180 Average:5017.27 Median:5587.46 stddev:1417.57 (25.4%) stddev-sans-first:1377.00 10:23:58 INFO - PID 1635 | Values: 319.6 387.8 388.1 389.1 392.5 5667.9 5699.6 5739.1 5783.5 5822.6 5329.5 5475.9 5628.4 5651.0 5676.6 4199.7 4320.7 4369.5 4437.9 4458.2 3975.9 4059.4 4417.2 4429.3 4457.0 4092.3 4385.9 4393.5 4423.8 4428.1 3659.9 3750.6 4353.6 4370.7 4385.8 3888.0 3888.4 3890.8 3903.6 3929.9 5486.3 5546.7 5577.6 5586.9 5597.2 4391.7 4906.5 4997.6 5105.5 5115.7 5889.7 5905.0 5954.4 5974.6 6001.8 4175.0 4308.1 4324.8 4325.0 4343.3 5995.0 6164.5 6196.0 6220.8 6242.4 4272.5 4482.5 5155.2 5169.9 5188.5 5146.3 5238.9 5252.3 5438.4 5446.3 4093.4 4757.5 5108.6 5610.6 5643.5 5225.8 5454.0 5608.0 6010.2 6017.7 1630.9 1648.9 1844.8 1851.8 1875.5 1159.6 1464.6 1508.7 1514.8 1524.5 5653.1 5662.6 5672.8 5675.9 5677.4 6176.7 6179.2 6180.0 6191.6 6194.2 5398.5 5547.3 5630.7 5686.4 5717.8 2691.7 2966.6 3224.3 3269.8 3296.3 5087.5 6038.0 6105.8 6386.2 6387.4 6027.7 6091.0 6325.7 6343.7 6348.7 6068.7 6393.7 6412.9 6417.3 6438.8 5509.9 5795.6 5857.1 6205.8 6252.2 5850.6 5945.8 5994.3 6073.7 6099.4 6167.4 6349.0 6401.8 6407.7 6423.6 4296.8 5294.9 5322.3 5342.7 5343.4 5413.2 6117.5 6130.7 6156.1 6211.8 5629.6 5962.9 5966.5 6013.8 6025.9 5400.6 5758.0 5859.6 5917.0 5921.2 5844.8 5845.6 5855.7 5866.4 5874.8 4830.9 5048.0 5588.0 6012.6 6052.2 4840.9 5414.3 5596.9 5754.2 5800.9 10:23:58 INFO - PID 1635 | 10:23:58 INFO - PID 1635 | [#5] yui.html Cycles:180 Average:19876.98 Median:25068.62 stddev:27435.68 (109.4%) stddev-sans-first:27475.03 10:23:58 INFO - PID 1635 | Values: 743.9 754.3 815.2 815.5 818.4 13126.5 14704.9 15482.7 15765.9 15773.1 12123.5 14479.6 14633.5 14702.0 14751.0 928.2 940.1 959.4 1007.3 1012.2 998.4 1007.0 1008.8 1008.9 1010.9 1007.3 1029.8 1030.6 1032.1 1033.0 1023.4 1025.1 1026.4 1029.0 1029.5 763.5 763.6 764.1 764.3 764.9 15721.4 15895.5 16087.2 16621.9 16695.5 14560.9 14564.5 14597.0 14613.6 14704.3 10701.6 11391.8 11529.0 11709.7 12235.1 3399.2 3607.0 3703.6 3861.3 3863.1 80711.6 82022.8 82709.0 82884.2 87461.6 11269.4 11404.5 11444.6 11519.1 11529.5 24397.4 25008.7 25009.2 25011.9 25125.4 7838.0 7842.5 7858.1 7997.9 8059.4 4112.9 4171.0 4232.0 4251.0 4342.6 3495.2 3522.3 3552.0 3580.3 3586.0 655.7 659.0 659.3 674.6 676.6 664.5 682.9 694.5 720.9 723.8 9021.1 9632.9 10460.2 10545.4 10587.2 642.5 660.6 687.0 692.5 727.2 4952.4 5004.4 5005.6 5011.2 5027.9 100901.0 103456.4 103516.0 105354.0 105853.0 75349.6 88117.1 88317.1 88352.6 88997.3 76971.2 77840.6 78000.6 78054.0 78341.6 7517.9 8761.7 9521.0 9601.5 9723.9 49406.0 49504.8 49976.0 52232.0 52335.5 63186.7 63961.0 64150.4 64801.4 65315.4 13360.1 13466.0 13575.2 13758.9 14065.7 14907.4 14935.9 15097.9 15116.9 15255.5 12190.4 13679.0 13756.7 13879.2 13972.5 11463.9 13793.4 13843.0 14199.3 14315.9 13464.0 13737.7 13750.8 13762.4 14066.6 11060.8 11119.4 11347.4 11406.0 11704.9 9388.6 9481.4 10497.6 10904.3 11173.6 10:23:58 INFO - PID 1635 | -------- Summary: end -------- 10:23:58 INFO - PID 1635 | 10:23:59 INFO - TEST-INFO | 1635: exit 0 10:23:59 INFO - TEST-OK | dromaeo_css | took 1120509ms 10:23:59 INFO - TEST-START | kraken 10:23:59 INFO - operating with platform_type : mac_ 10:23:59 INFO - Initialising browser for kraken test... 10:23:59 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpUHcFaj/profile http://localhost:49239/getInfo.html) 10:24:01 INFO - PID 1645 | __metrics Screen width/height:1600/1200 10:24:01 INFO - PID 1645 | colorDepth:24 10:24:01 INFO - PID 1645 | Browser inner width/height: 1024/689 10:24:01 INFO - PID 1645 | __metrics 10:24:02 INFO - PID 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:24:02 INFO - PID 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:24:02 INFO - TEST-INFO | 1645: exit 0 10:24:02 INFO - Browser initialized. 10:24:02 INFO - Running cycle 1/1 for kraken test... 10:24:02 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpUHcFaj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:24:15 INFO - PID 1646 | RSS: Main: 234508288 10:24:15 INFO - PID 1646 | 10:24:17 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection) 10:24:18 INFO - PID 1646 | RSS: Main: 285020160 10:24:18 INFO - PID 1646 | 10:24:21 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-dft) 10:24:21 INFO - PID 1646 | RSS: Main: 285618176 10:24:21 INFO - PID 1646 | 10:24:25 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-fft) 10:24:26 INFO - PID 1646 | RSS: Main: 377356288 10:24:26 INFO - PID 1646 | 10:24:28 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator) 10:24:28 INFO - PID 1646 | RSS: Main: 281104384 10:24:28 INFO - PID 1646 | 10:24:30 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:24:31 INFO - PID 1646 | RSS: Main: 284581888 10:24:31 INFO - PID 1646 | 10:24:34 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom) 10:24:34 INFO - PID 1646 | RSS: Main: 326578176 10:24:34 INFO - PID 1646 | 10:24:37 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate) 10:24:38 INFO - PID 1646 | RSS: Main: 339800064 10:24:38 INFO - PID 1646 | 10:24:41 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial) 10:24:42 INFO - PID 1646 | RSS: Main: 350040064 10:24:42 INFO - PID 1646 | 10:24:43 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:24:44 INFO - PID 1646 | RSS: Main: 300613632 10:24:44 INFO - PID 1646 | 10:24:46 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:24:46 INFO - PID 1646 | RSS: Main: 307404800 10:24:46 INFO - PID 1646 | 10:24:49 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:24:49 INFO - PID 1646 | RSS: Main: 309514240 10:24:49 INFO - PID 1646 | 10:24:52 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:24:53 INFO - PID 1646 | RSS: Main: 327299072 10:24:53 INFO - PID 1646 | 10:24:56 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:24:57 INFO - PID 1646 | RSS: Main: 320962560 10:24:57 INFO - PID 1646 | 10:24:58 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49239/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49239/tests/kraken/driver.html?testName=ai-astar) 10:24:59 INFO - PID 1646 | RSS: Main: 382550016 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | __start_tp_report 10:24:59 INFO - PID 1646 | _x_x_mozilla_page_load 10:24:59 INFO - PID 1646 | _x_x_mozilla_page_load_details 10:24:59 INFO - PID 1646 | |i|pagename|runs| 10:24:59 INFO - PID 1646 | |0;ai-astar;114;114;115;114;113;115;115;112;114;114 10:24:59 INFO - PID 1646 | |1;audio-beat-detection;150;127;122;152;114;113;109;112;130;112 10:24:59 INFO - PID 1646 | |2;audio-dft;184;184;197;258;183;185;184;184;185;183 10:24:59 INFO - PID 1646 | |3;audio-fft;80;59;60;90;62;61;59;62;76;48 10:24:59 INFO - PID 1646 | |4;audio-oscillator;84;70;87;70;70;71;83;69;69;85 10:24:59 INFO - PID 1646 | |5;imaging-gaussian-blur;86;86;91;134;86;87;87;87;86;86 10:24:59 INFO - PID 1646 | |6;imaging-darkroom;99;94;94;94;93;94;98;94;94;94 10:24:59 INFO - PID 1646 | |7;imaging-desaturate;84;86;79;84;86;85;85;85;84;87 10:24:59 INFO - PID 1646 | |8;json-parse-financial;62;61;60;61;62;62;62;63;64;62 10:24:59 INFO - PID 1646 | |9;json-stringify-tinderbox;54;54;59;69;64;63;59;58;59;59 10:24:59 INFO - PID 1646 | |10;stanford-crypto-aes;84;83;99;109;105;91;82;83;84;82 10:24:59 INFO - PID 1646 | |11;stanford-crypto-ccm;148;146;145;140;153;126;143;124;199;155 10:24:59 INFO - PID 1646 | |12;stanford-crypto-pbkdf2;188;181;184;184;181;185;183;185;183;184 10:24:59 INFO - PID 1646 | |13;stanford-crypto-sha256-iterative;69;67;67;68;69;67;68;67;67;68 10:24:59 INFO - PID 1646 | __end_tp_report 10:24:59 INFO - PID 1646 | __start_cc_report 10:24:59 INFO - PID 1646 | _x_x_mozilla_cycle_collect,2227 10:24:59 INFO - PID 1646 | __end_cc_report 10:24:59 INFO - PID 1646 | __startTimestamp1489771499213__endTimestamp 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | ------- Summary: start ------- 10:24:59 INFO - PID 1646 | Number of tests: 14 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#0] ai-astar Cycles:10 Average:114.00 Median:114.00 stddev:0.94 (0.8%) stddev-sans-first:1.00 10:24:59 INFO - PID 1646 | Values: 114.0 114.0 115.0 114.0 113.0 115.0 115.0 112.0 114.0 114.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#1] audio-beat-detection Cycles:10 Average:124.10 Median:124.50 stddev:15.79 (12.7%) stddev-sans-first:13.68 10:24:59 INFO - PID 1646 | Values: 150.0 127.0 122.0 152.0 114.0 113.0 109.0 112.0 130.0 112.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#2] audio-dft Cycles:10 Average:192.70 Median:184.50 stddev:23.31 (12.6%) stddev-sans-first:24.52 10:24:59 INFO - PID 1646 | Values: 184.0 184.0 197.0 258.0 183.0 185.0 184.0 184.0 185.0 183.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#3] audio-fft Cycles:10 Average:65.70 Median:62.00 stddev:12.41 (20.0%) stddev-sans-first:12.04 10:24:59 INFO - PID 1646 | Values: 80.0 59.0 60.0 90.0 62.0 61.0 59.0 62.0 76.0 48.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:77.00 stddev:7.79 (10.1%) stddev-sans-first:7.67 10:24:59 INFO - PID 1646 | Values: 84.0 70.0 87.0 70.0 70.0 71.0 83.0 69.0 69.0 85.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:86.50 stddev:14.98 (17.3%) stddev-sans-first:15.75 10:24:59 INFO - PID 1646 | Values: 86.0 86.0 91.0 134.0 86.0 87.0 87.0 87.0 86.0 86.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:94.00 stddev:1.99 (2.1%) stddev-sans-first:1.41 10:24:59 INFO - PID 1646 | Values: 99.0 94.0 94.0 94.0 93.0 94.0 98.0 94.0 94.0 94.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#7] imaging-desaturate Cycles:10 Average:84.50 Median:85.00 stddev:2.17 (2.6%) stddev-sans-first:2.30 10:24:59 INFO - PID 1646 | Values: 84.0 86.0 79.0 84.0 86.0 85.0 85.0 85.0 84.0 87.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:1.10 (1.8%) stddev-sans-first:1.17 10:24:59 INFO - PID 1646 | Values: 62.0 61.0 60.0 61.0 62.0 62.0 62.0 63.0 64.0 62.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:59.00 stddev:4.54 (7.7%) stddev-sans-first:4.30 10:24:59 INFO - PID 1646 | Values: 54.0 54.0 59.0 69.0 64.0 63.0 59.0 58.0 59.0 59.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:83.50 stddev:10.36 (12.4%) stddev-sans-first:10.74 10:24:59 INFO - PID 1646 | Values: 84.0 83.0 99.0 109.0 105.0 91.0 82.0 83.0 84.0 82.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:147.00 stddev:20.65 (14.0%) stddev-sans-first:21.90 10:24:59 INFO - PID 1646 | Values: 148.0 146.0 145.0 140.0 153.0 126.0 143.0 124.0 199.0 155.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.80 Median:184.00 stddev:2.04 (1.1%) stddev-sans-first:1.50 10:24:59 INFO - PID 1646 | Values: 188.0 181.0 184.0 184.0 181.0 185.0 183.0 185.0 183.0 184.0 10:24:59 INFO - PID 1646 | 10:24:59 INFO - PID 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.73 10:24:59 INFO - PID 1646 | Values: 69.0 67.0 67.0 68.0 69.0 67.0 68.0 67.0 67.0 68.0 10:24:59 INFO - PID 1646 | -------- Summary: end -------- 10:24:59 INFO - PID 1646 | 10:25:00 INFO - TEST-INFO | 1646: exit 0 10:25:00 INFO - TEST-OK | kraken | took 60853ms 10:25:00 INFO - SUITE-END | took 1181s 10:25:00 INFO - Completed test suite (00:19:41) 10:25:00 INFO - javascript benchmark 10:25:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7711.326593233517, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2764.557670772677, 3196.2169268529206, 3322.9335413291733, 3328.6671332866717, 3353.480210567363, 5473.781048758055, 5585.687363469591, 5664.037113690672, 5701.971490142549, 5704.8003319883765, 5623.943760562393, 5662.801801936933, 5693.3452652944925, 5721.256236689235, 5723.198752174699, 4157.66738660908, 5125.000624878159, 5151.716655583941, 5155.097857874875, 5198.636095473318, 5224.085784987631, 5233.73831308435, 5264.263003179558, 5269.262303277545, 5278.604104692164, 5088.313077734532, 5161.3806343238675, 5161.896762064775, 5190.766415511311, 5221.634485585992, 4602.355670206174, 5068.467810879842, 5174.172132458826, 5219.321488206547, 5225.529702326771, 5131.563817075553, 5144.125498665236, 5166.509181627777, 5182.714950677711, 5220.765065572058, 5501.174823776426, 5544.16837474378, 5648.265725455705, 5659.462351076641, 5681.0910254359105, 5088.821891233826, 6107.328193898712, 6140.394703972021, 6187.690615469209, 6192.783252586138, 6181.629102253879, 6238.656873871935, 6251.3123556408755, 6269.2476902771505, 6269.8746025079245, 5762.740676669518, 5764.538836893039, 5765.625234359753, 5774.624649397776, 5816.97091514544, 6112.5946701277735, 6197.876042479125, 6247.562670613104, 6255.718492667841, 6269.0, 5967.373425790316, 6019.367966363468, 6023.608465449732, 6144.662043587646, 6148.754049837957, 6204.534659900525, 6219.751209951552, 6247.250329960434, 6255.311915689271, 6255.499560035186, 6111.144439778345, 6206.162168107338, 6224.408681175281, 6224.688765561704, 6225.28409232935, 6068.4841788448475, 6222.097795819581, 6229.50163986889, 6238.812835614939, 6242.0, 4740.146773580788, 4828.179209534317, 4916.950830491721, 4937.136001199776, 4943.209086546135, 4409.845655402046, 4413.668974826901, 4436.822527098944, 4439.289713645801, 4446.044100518404, 5753.021986262345, 5754.280714910637, 5762.683052432073, 5763.711814409263, 5772.191893134965, 6024.728887200086, 6105.763021687103, 6122.2761798032625, 6128.411459112287, 6238.095476155932, 5643.35101463337, 5646.181303710939, 5674.290713660793, 5685.431456854281, 5702.486776190163, 5327.493888080626, 5328.706921119376, 5330.0, 5330.280412144389, 5345.01117293302, 6288.5598008139905, 6298.559100862895, 6298.937010629927, 6306.527010474141, 6310.936890631218, 5221.660592061504, 6177.135201071764, 6193.659348735863, 6194.535409844189, 6214.72033758491, 6177.907331389943, 6186.505079593711, 6199.411055949678, 6225.346338634378, 6226.439620434183, 6132.938670613236, 6139.662318572521, 6167.229096362955, 6172.197614310111, 6175.197224360804, 5933.228680271537, 6226.315105338319, 6251.343608920998, 6263.96868015657, 6270.372962703693, 5911.615744976735, 6004.279486461653, 6053.636781793107, 6054.122152287806, 6079.422454866781, 5843.532517398513, 5885.0, 5908.822735317947, 6012.398760124128, 6038.818835434944, 5869.4717475427415, 5880.911786323294, 5911.142884281841, 5916.733746981482, 5922.703864806656, 5973.223480947449, 6039.365866584121, 6055.394460553909, 6067.666278354556, 6095.0, 5660.886782264414, 5710.543156547549, 5725.083986562297, 5779.306483222041, 5829.950608890441], "value": 5630.814530071779, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [607.4866737606723, 728.1553398058251, 790.6086487188841, 869.2915274051647, 879.788850675838, 27194.45611087777, 35861.103472413204, 36316.09209769757, 36441.45337819928, 36558.0, 37672.68145614904, 38059.619403805955, 38098.428523572096, 39681.80159099208, 39721.8083457496, 7104.786856394317, 7296.890546641778, 7302.598357090358, 7328.303811137934, 7339.449541284398, 7272.16370117438, 8778.56107194641, 10664.360138391683, 10696.78606427871, 10699.893001069968, 10874.293170943864, 10938.617148399808, 10967.561297548089, 10970.177236707239, 10977.0, 10811.729706757314, 10926.180536459797, 10932.508037138348, 10936.070434013118, 10936.890631093667, 7034.6834392452465, 7198.7840364789145, 10092.495375231209, 10309.587616495332, 10332.63835765752, 36572.81713591415, 36883.0, 37009.444858327144, 37032.0, 37042.814785926166, 3813.7902415367143, 3830.425436184567, 3840.596737342567, 3865.1779608990155, 3872.8257228424786, 27082.32294192661, 27137.45725085507, 27143.321416964536, 28176.859115704494, 28228.0, 3098.612673415823, 3142.402943440739, 3215.646278909318, 3221.597300337458, 3239.060672405, 276226.0, 311150.44424777955, 311305.0, 311580.99999999773, 312339.0, 41774.0, 50036.0, 50285.49714502808, 50321.49678503168, 50345.49654503481, 50145.0, 50146.498535014915, 50179.74910125499, 50302.93335466192, 50326.199439087, 13965.3715582797, 14398.496052638107, 14410.495632652806, 14440.855591443951, 14460.783088253685, 9750.171235445066, 9888.258380621483, 11799.52801887915, 11810.704732381759, 11816.940915295541, 10789.83815242772, 10873.891261087289, 10901.1279097672, 11068.00387965087, 11980.820287695691, 5224.92162617561, 5464.808731694372, 5495.752691128909, 5534.501894829485, 5554.555635549146, 769.3345256353326, 890.7773056735816, 930.851063829784, 945.7351941456403, 992.9007099290158, 27299.31751706223, 27545.173644790688, 27683.861580691966, 28014.43971120607, 28037.158885233475, 1020.0972139656458, 1022.6625213679467, 1064.0, 1064.9680509584725, 1067.530286673861, 11427.314361138358, 13106.737865242641, 14054.0, 14391.85608143926, 14460.349284282232, 385766.0, 386415.0, 386879.0, 387186.0, 387225.06387468445, 53666.65833354119, 53812.73093634585, 53831.0, 53858.461415386126, 53956.0, 1404.8595140486075, 1454.0475988227515, 1455.9417623294948, 1458.7155504676698, 1468.8090548228663, 25026.74973250317, 26479.205623830545, 26770.866145669148, 27170.1848944532, 27372.589411159246, 45314.773426132655, 45355.86610334768, 45404.77297613491, 45447.318290226336, 45478.0, 379672.0, 381403.0, 381807.0, 382090.0, 383028.0, 32487.350252995282, 33352.83323583366, 33412.83293583516, 33556.83221583877, 33593.824216152316, 33346.4998025025, 42084.0, 42695.146097078505, 42717.78641106775, 43011.35482967695, 33476.0, 34608.82695586506, 34663.480047798796, 35895.64104358923, 35959.28081438304, 33298.833505832314, 34716.826415867756, 34851.4772278411, 35876.103097422776, 37048.814755927124, 34985.0, 35377.469337960465, 35510.82244588761, 36113.638863612076, 36143.81928090343, 30153.698463015968, 31607.683923161392, 31824.204394890312, 31901.0, 31968.0, 20342.593148136657, 23374.883125584263, 23760.4059898497, 24155.51688966246, 24296.0], "value": 17974.860005892453, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.3851241732185, 391.7257919456379, 402.16951994132114, 420.6235419299727, 431.2259494207897, 13022.934885325572, 14936.327865246063, 17783.64432711345, 20686.27598034069, 21019.68470472944, 22097.226597069104, 22302.21942232022, 22858.314250572508, 22992.8850355748, 23330.300090997254, 5488.368837583689, 6499.447546958514, 6500.577462464948, 6686.297938716438, 6688.531802773808, 4129.277376459123, 6568.507361947849, 6701.229358623747, 6711.597304161742, 6717.966410167943, 4401.647868170555, 4453.087117140994, 7609.0, 7611.238876112372, 7615.923840761605, 7292.635368231568, 7522.247775222489, 7592.0, 7597.088349398053, 7603.695852165907, 6957.0955775749335, 7346.63266836656, 7349.265073492661, 7362.300581444754, 7365.963170184168, 21571.784282157136, 26712.797924093462, 26796.866015669795, 27191.416815402506, 27231.183064508095, 3300.224447254893, 3308.267695068339, 3318.784279021856, 3354.647761985005, 3440.2603440260464, 15632.296546655427, 16641.583960400967, 16905.661886762195, 17038.82961170397, 17324.740128898076, 2014.4863059919774, 2040.6122836661136, 2073.6163809695104, 2119.8940052997286, 2225.4213904384815, 141783.0, 149416.90233738793, 154342.22828885785, 154732.2263388687, 158942.0, 21781.693098413827, 21812.45468863291, 21828.672569911465, 22027.449313767283, 22212.111515539196, 37417.812910935274, 37501.87494375119, 37545.62454375476, 37636.05909852275, 37637.247255054746, 10383.404460318881, 10578.735531611772, 10768.515416806262, 10971.670849874517, 11051.834222486668, 8720.389572729848, 8720.563971801384, 8759.0, 8945.105489451003, 8946.776330591787, 10109.848352274721, 10214.693559193236, 10584.312019718695, 10670.786584268271, 10674.733131671615, 3565.35823551758, 3658.853645854136, 3904.2972264992004, 4177.436046133733, 4244.957550424518, 1037.749511838233, 1044.613493007592, 1047.9109275711498, 1057.2493529593921, 1102.1513434655271, 19645.0, 22708.205212817804, 22900.65649015266, 23438.531229375316, 23493.53012939766, 1063.3247887591435, 1071.6356438810842, 1100.4497751124438, 1103.5144536404116, 1112.978264013208, 11266.43667816606, 11903.285802851857, 12046.939765301293, 12160.513579456723, 12199.817002744965, 505217.0, 627891.0, 628355.0, 641165.0, 644243.7787811031, 134773.0, 148170.0, 149900.50099499084, 152183.0, 154721.0, 1063.4682658670665, 1134.8354488599273, 1241.4224259835414, 1243.185713357752, 1270.866559011309, 19385.8061419384, 20232.39302820918, 20354.28759993393, 20401.69397459069, 20491.897540512797, 42765.0, 58614.534636634424, 58941.0, 59904.40095599162, 60613.78772424439, 81079.0, 82175.35649286861, 82611.0, 82639.586802068, 82941.0, 6830.043793868763, 7517.661705223169, 8460.0, 9665.371750836173, 10567.418791966516, 10838.729031774268, 13829.585112446643, 13990.0, 14087.366068526735, 14373.209473479059, 10933.617323393642, 11713.824292635787, 12045.217060891015, 12110.45502952387, 12122.27266364021, 11355.772884542097, 11615.303081815118, 11627.59303424376, 11797.351145686725, 11924.940375298069, 11759.882401175879, 11872.82190767122, 11974.58088966882, 12164.148509604243, 12243.020558355132, 12091.455884485395, 12095.21381110225, 12433.37833108323, 12457.626271211879, 12564.623061308175, 11656.358900260228, 11884.26661867758, 11886.643400697993, 11928.701782455508, 12002.279863208236], "value": 11693.006158631093, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1547.8297387287403, 1570.7957965464489, 1597.6245582288168, 1604.7913771209737, 1659.9917000414998, 10451.895481045187, 10952.780944381118, 11088.0, 11094.722631934186, 11104.500297486613, 10411.583536658525, 10792.082672972787, 10813.729656758585, 10818.296810707317, 10837.349759014465, 2330.6853574767442, 2375.4417711837705, 2379.238643634038, 2384.5588566115325, 2385.5348207099623, 2325.4186453386656, 2360.24472168906, 2364.4679947011955, 2364.527094581082, 2373.845700029501, 2067.687018743094, 2251.1445650652813, 2337.883105844701, 2339.508703172336, 2349.5770761262966, 2300.367398965281, 2313.282882306482, 2319.060780383947, 2322.465832858452, 2329.988350058256, 2258.8870556472275, 2316.420894776306, 2321.721393432782, 2325.162941341116, 2328.301509547146, 10830.296030757976, 10914.454277286104, 10918.0173784359, 10918.836217456745, 10927.961843624844, 5661.801836935688, 5874.471297583195, 5883.970580147115, 5891.79378721747, 5893.58744887858, 3013.4877070897783, 3266.6080070391704, 3284.934301313984, 3287.1124796305103, 3361.445361515347, 1167.6030149749042, 1194.988050119505, 1206.699261416825, 1227.2943057741834, 1239.9814002789965, 13749.725005499835, 14104.717905641932, 14118.505852295222, 15226.0, 15264.0, 4513.54864513546, 4672.135654903854, 4672.322513235562, 4673.392458980311, 4683.859484215479, 11430.657080287605, 11439.485223164978, 11447.25592836463, 11475.426228688531, 11556.94221528887, 4890.0, 5015.072211640858, 5028.572571331478, 5712.400197979236, 5712.65724056558, 4155.771432571177, 5014.222795466709, 5250.133727934848, 5262.578993680497, 5268.762905669254, 3741.5323084614424, 3831.4061320495366, 3837.597052309523, 3859.7684138951754, 3869.2648396804516, 991.7520619845038, 1017.704865588971, 1024.8872624011353, 1029.6859457865273, 1029.7528593137743, 838.8573942429801, 877.2367541242149, 910.4946754551288, 911.7538264668635, 921.0697195832257, 6231.40801623845, 6323.36766323364, 6533.346665333429, 6582.210134783761, 6613.611141660209, 926.3330402110604, 928.9907100929039, 931.2829121576349, 940.3041749105613, 972.7908499672604, 3948.6841052715713, 4003.538708371482, 4021.577734337911, 4058.6144316289906, 4095.2014357200655, 12909.612711618667, 13466.0, 13469.191848489121, 13474.595762127152, 13493.460261589622, 12989.350532473338, 13213.603591892259, 13215.14101583394, 13226.867731322563, 13237.536686215935, 8753.562321883881, 8987.651852222089, 9701.369410988264, 9994.550245238981, 10002.249831262685, 6049.832725820108, 6395.744170233139, 6514.4788416925585, 6515.185601799775, 6541.70562324687, 11760.76478470443, 11880.940595296968, 12115.575954841539, 12157.02743780513, 12181.025517958718, 10817.0, 11048.834267486154, 12274.447649855956, 12405.503779849067, 12463.813042804184, 9940.602375904882, 9964.451955142536, 10000.449975251431, 10047.698569042941, 10090.798184036425, 9931.0, 10038.19694424459, 10135.493225338556, 10142.137918277029, 10155.136813370946, 8907.287417006495, 8951.0, 8958.059403762772, 8976.147266009719, 9055.411398259092, 8727.301815854844, 8805.823883522422, 8807.07525709817, 8808.603612837565, 8888.466691998501, 7403.8149046274275, 7577.280158384945, 7678.308952194329, 7685.193054729397, 7696.961515192388, 5764.510016648632, 5850.561207909339, 5855.912161317498, 5929.229200203946, 5936.910946335895, 5794.449527294901, 5875.0, 5959.195508606283, 6049.939500604937, 6167.722452489559], "value": 5027.264126626385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.6132679457856, 387.7983448606723, 388.134460153857, 389.08564872549493, 392.4878034165413, 5667.874547044858, 5699.601027928046, 5739.110437882125, 5783.5373170146395, 5822.563307751925, 5329.493698098684, 5475.86441287897, 5628.43715628438, 5650.971745141269, 5676.602637815355, 4199.664026877843, 4320.719153255044, 4369.497507786614, 4437.911241775162, 4458.152950939328, 3975.880723578283, 4059.4113853491226, 4417.1607394595085, 4429.313456414261, 4456.955430445702, 4092.2830238475863, 4385.912281754379, 4393.517662520666, 4423.77881105945, 4428.051657270807, 3659.9085022874374, 3750.6061863504347, 4353.629941454964, 4370.650347972186, 4385.8245670173155, 3888.0474283800368, 3888.3778595424596, 3890.8443662253476, 3903.551091624471, 3929.9214015719813, 5486.286782718261, 5546.69493177875, 5577.581681373913, 5586.944130558723, 5597.216389705444, 4391.670624703128, 4906.497686180038, 4997.575206107485, 5105.5149760772665, 5115.7442127893455, 5889.705514724246, 5905.0, 5954.439590975879, 5974.551908606828, 6001.789937352171, 4174.958250417488, 4308.138372325548, 4324.789059063467, 4324.978375108136, 4343.326784348431, 5995.010823214165, 6164.506839452833, 6196.0, 6220.844478888064, 6242.375762423767, 4272.530021697611, 4482.462104547475, 5155.226715992556, 5169.870753231124, 5188.533032027136, 5146.330976972969, 5238.852070651014, 5252.290940722954, 5438.401775804658, 5446.2647542582035, 4093.3859921012086, 4757.548032936866, 5108.646208754683, 5610.579206559524, 5643.492085712223, 5225.790968361299, 5454.0, 5608.018596745554, 6010.248718910137, 6017.729202185825, 1630.853223209893, 1648.934042638305, 1844.837752216122, 1851.8333349998566, 1875.493616723477, 1159.6173262823247, 1464.6484843637663, 1508.7435136026902, 1514.8186611279723, 1524.4664367471296, 5653.123765816305, 5662.631928924607, 5672.773089076473, 5675.858103547444, 5677.403872593401, 6176.6528719087355, 6179.16581261533, 6179.969100154471, 6191.566590338633, 6194.194754681863, 5398.541124004425, 5547.306586676666, 5630.74661640219, 5686.43135686428, 5717.799877004368, 2691.7039125696156, 2966.6440027196873, 3224.2584205632834, 3269.8038117713013, 3296.258341873059, 5087.516685914832, 6037.973544497446, 6105.786297479567, 6386.201724784402, 6387.425131738073, 6027.660084738981, 6090.994985827378, 6325.715342809584, 6343.682815859189, 6348.650824204621, 6068.726907289182, 6393.712282947278, 6412.947856685816, 6417.294097649348, 6438.7746428873825, 5509.889802204014, 5795.565332600157, 5857.092150716646, 6205.844853878509, 6252.187215661935, 5850.561207909509, 5945.821625351246, 5994.28068631767, 6073.726682299395, 6099.359567245376, 6167.352427994996, 6349.015902535114, 6401.799100449775, 6407.743690252524, 6423.614583125028, 4296.76367799774, 5294.8941021180135, 5322.33470816148, 5342.679439233659, 5343.4389389113585, 5413.160960051198, 6117.480014198843, 6130.693465326805, 6156.107364432043, 6211.81364559064, 5629.605927585029, 5962.850928726816, 5966.522678185822, 6013.849653758691, 6025.879482410415, 5400.624609618988, 5757.971210143923, 5859.648421094748, 5916.964531206935, 5921.230240068763, 5844.824655260349, 5845.561582881386, 5855.707214639337, 5866.413358664099, 5874.765009399576, 4830.9275360870315, 5047.974760126176, 5587.972060139673, 6012.5791194617705, 6052.152698622109, 4840.854774356775, 5414.3232095988005, 5596.916046259228, 5754.223179870832, 5800.912986305293], "value": 4604.333063409193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.9367653749431, 754.2985023927748, 815.1766715617226, 815.4821688227976, 818.4148333941231, 13126.540571080013, 14704.926475367634, 15482.690346193083, 15765.92117039416, 15773.053616783001, 12123.515059397636, 14479.5656130316, 14633.487827926025, 14702.0, 14751.0, 928.2434815625278, 940.0505489455664, 959.4339339789523, 1007.279794946613, 1012.1649639047778, 998.366037566147, 1006.954687039085, 1008.8234558952191, 1008.8839783424927, 1010.9494525273743, 1007.3452256033601, 1029.8352263637819, 1030.6289735695182, 1032.1484775060567, 1032.989670103297, 1023.3603997501561, 1025.1337619711333, 1026.4303311662024, 1028.98644834838, 1029.4749677664408, 763.5495057915856, 763.6029264782283, 764.1174443517746, 764.3464837563914, 764.9082110146762, 15721.37114515418, 15895.52313430599, 16087.195640218059, 16621.916890415592, 16695.499135025846, 14560.927195363955, 14564.49024284145, 14597.0, 14613.634659133606, 14704.33830477631, 10701.571937122506, 11391.772164556745, 11529.0, 11709.70725731855, 12235.082368822284, 3399.2351720862857, 3607.0, 3703.5555733311908, 3861.2663593917346, 3863.0728625130046, 80711.596442018, 82022.76965845517, 82709.0, 82884.17115828885, 87461.56269218613, 11269.380184089954, 11404.543818247177, 11444.599439019761, 11519.136064795006, 11529.538818447169, 24397.39006524851, 25008.749912501007, 25009.249722508353, 25011.87494062518, 25125.371865703502, 7837.960810195913, 7842.490238134503, 7858.0570331560575, 7997.920020799717, 8059.395545334123, 4112.897177570585, 4170.9582904171175, 4231.978840105841, 4250.957490425056, 4342.6308763754805, 3495.1611613213154, 3522.260325331709, 3552.0409489437707, 3580.2839432113683, 3586.049696830342, 655.7442597387023, 658.980230593083, 659.2550418027598, 674.649182425146, 676.6278546799241, 664.4649868542839, 682.931706829313, 694.5381321421198, 720.9330191316776, 723.7901008707521, 9021.097890211058, 9632.86565576741, 10460.215483838743, 10545.407564946023, 10587.15302775776, 642.5084810120262, 660.5640277416882, 686.9519133660696, 692.51869950384, 727.1749254147675, 4952.4057113146655, 5004.42449118356, 5005.599552035901, 5011.223260394645, 5027.902477740067, 100901.0, 103456.44815327776, 103516.0, 105354.0, 105853.0, 75349.62325188449, 88117.1188288109, 88317.11682883088, 88352.5582372084, 88997.3256695809, 76971.23028769753, 77840.61079694565, 78000.60999695193, 78054.0, 78341.60829195932, 7517.887231691639, 8761.693340733043, 9520.952395237979, 9601.519924003911, 9723.854142188015, 49406.0, 49504.75247623883, 49976.0, 52231.95536089333, 52335.47664523306, 63186.68406657937, 63961.040584392205, 64150.35849641444, 64801.351986481415, 65315.36711582248, 13360.131591446525, 13466.0, 13575.185488870698, 13758.93120534431, 14065.694561778178, 14907.403703852158, 14935.85064149388, 15097.920714439088, 15116.924415377853, 15255.542333730005, 12190.390480476119, 13679.0, 13756.724865502834, 13879.236641984788, 13972.510962116277, 11463.885361146615, 13793.448262069405, 13843.023749893418, 14199.290035497977, 14315.856841431452, 13464.0, 13737.725245494834, 13750.793738094137, 13762.380692869045, 14066.578002659937, 11060.778784424427, 11119.413393906892, 11347.432628368384, 11406.0, 11704.94147529257, 9388.624455021996, 9481.431114133175, 10497.632582859564, 10904.34573925567, 11173.55305787792], "value": 7674.947712969616, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1454.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 114.0, 113.0, 115.0, 115.0, 112.0, 114.0, 114.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 127.0, 122.0, 152.0, 114.0, 113.0, 109.0, 112.0, 130.0, 112.0], "value": 124.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 197.0, 258.0, 183.0, 185.0, 184.0, 184.0, 185.0, 183.0], "value": 192.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 60.0, 90.0, 62.0, 61.0, 59.0, 62.0, 76.0, 48.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 87.0, 70.0, 70.0, 71.0, 83.0, 69.0, 69.0, 85.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 91.0, 134.0, 86.0, 87.0, 87.0, 87.0, 86.0, 86.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 94.0, 94.0, 93.0, 94.0, 98.0, 94.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 79.0, 84.0, 86.0, 85.0, 85.0, 85.0, 84.0, 87.0], "value": 84.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 60.0, 61.0, 62.0, 62.0, 62.0, 63.0, 64.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 59.0, 69.0, 64.0, 63.0, 59.0, 58.0, 59.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 99.0, 109.0, 105.0, 91.0, 82.0, 83.0, 84.0, 82.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 145.0, 140.0, 153.0, 126.0, 143.0, 124.0, 199.0, 155.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 181.0, 184.0, 184.0, 181.0, 185.0, 183.0, 185.0, 183.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 68.0, 69.0, 67.0, 68.0, 67.0, 67.0, 68.0], "value": 67.7, "unit": "score"}]}]} 10:25:00 INFO - Return code: 0 10:25:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:25:00 ERROR - # TBPL SUCCESS # 10:25:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:25:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:25:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:25:00 INFO - 2017-03-17 10:25:00.544 screenresolution[1650:9783] starting screenresolution argv=screenresolution get 10:25:00 INFO - 2017-03-17 10:25:00.547 screenresolution[1650:9783] Display 0: 1600x1200x32@60 10:25:00 INFO - 2017-03-17 10:25:00.554 screenresolution[1651:9787] starting screenresolution argv=screenresolution list 10:25:00 INFO - Available Modes on Display 0 10:25:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:25:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:25:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:25:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:25:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:25:00 INFO - Intel Iris: 10:25:00 INFO - Chipset Model: Intel Iris 10:25:00 INFO - Type: GPU 10:25:00 INFO - Bus: Built-In 10:25:00 INFO - VRAM (Dynamic, Max): 1536 MB 10:25:00 INFO - Vendor: Intel (0x8086) 10:25:00 INFO - Device ID: 0x0a2e 10:25:00 INFO - Revision ID: 0x0009 10:25:00 INFO - Displays: 10:25:00 INFO - Display: 10:25:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:25:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:25:00 INFO - Display Serial Number: 1600x1200 60 10:25:00 INFO - Main Display: Yes 10:25:00 INFO - Mirror: Off 10:25:00 INFO - Online: Yes 10:25:00 INFO - Rotation: Supported 10:25:00 INFO - Return code: 0 10:25:00 INFO - Running post-action listener: _resource_record_post_action 10:25:00 INFO - [mozharness: 2017-03-17 17:25:00.788949Z] Finished run-tests step (success) 10:25:00 INFO - Running post-run listener: _resource_record_post_run 10:25:00 INFO - Total resource usage - Wall time: 1202s; CPU: 53.0%; Read bytes: 39064576; Write bytes: 512357888; Read time: 380; Write time: 1591 10:25:00 INFO - TinderboxPrint: CPU usage
53.4% 10:25:00 INFO - TinderboxPrint: I/O read bytes / time
39,064,576 / 380 10:25:00 INFO - TinderboxPrint: I/O write bytes / time
512,357,888 / 1,591 10:25:00 INFO - TinderboxPrint: CPU idle
2,240.2 (46.6%) 10:25:00 INFO - TinderboxPrint: CPU system
956.9 (19.9%) 10:25:00 INFO - TinderboxPrint: CPU user
1,612.6 (33.5%) 10:25:00 INFO - TinderboxPrint: Swap in / out
248,938,496 / 0 10:25:00 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202605056; Write bytes: 203069440; Read time: 16019; Write time: 405 10:25:00 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 38110208; Write bytes: 299347456; Read time: 366; Write time: 1152 10:25:01 INFO - Running post-run listener: _upload_blobber_files 10:25:01 INFO - Blob upload gear active. 10:25:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:25:01 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 10:25:01 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'] 10:25:01 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 10:25:01 INFO - (blobuploader) - INFO - Open directory for files ... 10:25:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:25:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:25:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:02 INFO - (blobuploader) - INFO - Done attempting. 10:25:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:25:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:25:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:03 INFO - (blobuploader) - INFO - Done attempting. 10:25:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:25:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:25:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:04 INFO - (blobuploader) - INFO - Done attempting. 10:25:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:25:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:25:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:25:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:25:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:25:07 INFO - (blobuploader) - INFO - Done attempting. 10:25:07 INFO - (blobuploader) - INFO - Iteration through files over. 10:25:07 INFO - Return code: 0 10:25:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:25:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:25:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/332c476949f8d1664607af7ca7511b5793098a44512ad7a0cd7455a2ab2f3e07c9d0eeb5814d8c379e23ee8af96a02d2d6f4712c16ef296bb2c3cd57804c3e23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e4a149eb19f913bb172522809580a895080d9bab7b3001b185798f61ec145a933847afeb2a0269f92477e26d9cf78f7acc81e30d22215ba4047091a524e6257", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/001e7459bd2cacdf9166f2acce8b3b4f8454ff37fc00edac2ef03606084664f7f034523b9c459b97148321792eba21305dbe797f3248335e793d9914c2f3276c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/488531ffbf0e1816c1431640828820f2516102bd9f6a4b03c060ac4936b0fcd7e8d9f21e744008832e3e71267d1a734cec47c8e53a9087bf7788aa88ee851c3f"} 10:25:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:25:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:25:07 INFO - Contents: 10:25:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/332c476949f8d1664607af7ca7511b5793098a44512ad7a0cd7455a2ab2f3e07c9d0eeb5814d8c379e23ee8af96a02d2d6f4712c16ef296bb2c3cd57804c3e23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e4a149eb19f913bb172522809580a895080d9bab7b3001b185798f61ec145a933847afeb2a0269f92477e26d9cf78f7acc81e30d22215ba4047091a524e6257", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/001e7459bd2cacdf9166f2acce8b3b4f8454ff37fc00edac2ef03606084664f7f034523b9c459b97148321792eba21305dbe797f3248335e793d9914c2f3276c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/488531ffbf0e1816c1431640828820f2516102bd9f6a4b03c060ac4936b0fcd7e8d9f21e744008832e3e71267d1a734cec47c8e53a9087bf7788aa88ee851c3f"} 10:25:07 INFO - Running post-run listener: copy_logs_to_upload_dir 10:25:07 INFO - Copying logs to upload dir... 10:25:07 INFO - mkdir: /builds/slave/test/build/upload/logs 10:25:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.502747 ========= master_lag: 1.66 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 28 secs) (at 2017-03-17 10:25:08.852450) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 10:25:08.857542) ========= 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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/332c476949f8d1664607af7ca7511b5793098a44512ad7a0cd7455a2ab2f3e07c9d0eeb5814d8c379e23ee8af96a02d2d6f4712c16ef296bb2c3cd57804c3e23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e4a149eb19f913bb172522809580a895080d9bab7b3001b185798f61ec145a933847afeb2a0269f92477e26d9cf78f7acc81e30d22215ba4047091a524e6257", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/001e7459bd2cacdf9166f2acce8b3b4f8454ff37fc00edac2ef03606084664f7f034523b9c459b97148321792eba21305dbe797f3248335e793d9914c2f3276c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/488531ffbf0e1816c1431640828820f2516102bd9f6a4b03c060ac4936b0fcd7e8d9f21e744008832e3e71267d1a734cec47c8e53a9087bf7788aa88ee851c3f"} build_url:https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/FYN-66oXS4eXIcPtr9DCgg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/332c476949f8d1664607af7ca7511b5793098a44512ad7a0cd7455a2ab2f3e07c9d0eeb5814d8c379e23ee8af96a02d2d6f4712c16ef296bb2c3cd57804c3e23", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1e4a149eb19f913bb172522809580a895080d9bab7b3001b185798f61ec145a933847afeb2a0269f92477e26d9cf78f7acc81e30d22215ba4047091a524e6257", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/001e7459bd2cacdf9166f2acce8b3b4f8454ff37fc00edac2ef03606084664f7f034523b9c459b97148321792eba21305dbe797f3248335e793d9914c2f3276c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/488531ffbf0e1816c1431640828820f2516102bd9f6a4b03c060ac4936b0fcd7e8d9f21e744008832e3e71267d1a734cec47c8e53a9087bf7788aa88ee851c3f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-17 10:25:08.893209) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 10:25:08.893573) ========= 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.jwDjGR3gbM/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.nmlvOhP4JV/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.005046 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-17 10:25:08.942413) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 10:25:08.942771) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-17 10:25:08.943152) ========= ========= Total master_lag: 1.80 =========