builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0364 starttime: 1486350120.44 results: success (0) buildid: 20170205181908 builduid: ee3d9e8cc9d74abebe76246128f8f45a revision: 20a8536b0bfac74389d3a57bd8dd957d98779ce1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:00.439476) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:00.439901) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:00.440201) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:00.663340) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:00.663702) ========= 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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-05 19:02:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2017-02-05 19:02:01 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.635566 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:01.323570) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:01.323847) ========= 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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.025279 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:01.374627) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:01.374931) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 20a8536b0bfac74389d3a57bd8dd957d98779ce1 --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 20a8536b0bfac74389d3a57bd8dd957d98779ce1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-05 19:02:01,485 truncating revision to first 12 chars 2017-02-05 19:02:01,485 Setting DEBUG logging. 2017-02-05 19:02:01,485 attempt 1/10 2017-02-05 19:02:01,485 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/20a8536b0bfa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-05 19:02:02,128 unpacking tar archive at: mozilla-central-20a8536b0bfa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913130 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:02.373617) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:02.373884) ========= script_repo_revision: 20a8536b0bfac74389d3a57bd8dd957d98779ce1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:02.374261) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:02.374523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-05 19:02:02.399883) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-05 19:02:02.400181) ========= /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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:02 INFO - MultiFileLogger online at 20170205 19:02:02 in /builds/slave/test 19:02:02 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 19:02:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:02:02 INFO - {'append_to_log': False, 19:02:02 INFO - 'base_work_dir': '/builds/slave/test', 19:02:02 INFO - 'blob_upload_branch': 'Firefox', 19:02:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:02:02 INFO - 'branch': 'Firefox', 19:02:02 INFO - 'buildbot_json_path': 'buildprops.json', 19:02:02 INFO - 'config_files': ('talos/mac_config.py',), 19:02:02 INFO - 'default_actions': ('clobber', 19:02:02 INFO - 'read-buildbot-config', 19:02:02 INFO - 'download-and-extract', 19:02:02 INFO - 'populate-webroot', 19:02:02 INFO - 'create-virtualenv', 19:02:02 INFO - 'install', 19:02:02 INFO - 'run-tests'), 19:02:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:02:02 INFO - 'download_minidump_stackwalk': True, 19:02:02 INFO - 'download_symbols': 'ondemand', 19:02:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:02:02 INFO - 'tooltool.py': '/tools/tooltool.py', 19:02:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:02:02 INFO - '/tools/misc-python/virtualenv.py')}, 19:02:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:02:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:02:02 INFO - 'installer_path': 'installer.exe', 19:02:02 INFO - 'log_level': 'info', 19:02:02 INFO - 'log_name': 'talos', 19:02:02 INFO - 'log_to_console': True, 19:02:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:02:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:02:02 INFO - 'opt_config_files': (), 19:02:02 INFO - 'pip_index': False, 19:02:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:02 INFO - 'cmd': ('bash', 19:02:02 INFO - '-c', 19:02:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:02 INFO - 'enabled': True, 19:02:02 INFO - 'halt_on_failure': False, 19:02:02 INFO - 'name': 'check_screen_resolution'},), 19:02:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:02 INFO - 'cmd': ('bash', 19:02:02 INFO - '-c', 19:02:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:02 INFO - 'enabled': True, 19:02:02 INFO - 'halt_on_failure': False, 19:02:02 INFO - 'name': 'check_screen_resolution'},), 19:02:02 INFO - 'run_cmd_checks_enabled': True, 19:02:02 INFO - 'sps_profile': False, 19:02:02 INFO - 'sps_profile_interval': 0, 19:02:02 INFO - 'suite': 'dromaeojs', 19:02:02 INFO - 'system_bits': '32', 19:02:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:02:02 INFO - 'title': 't-yosemite-r7-0364', 19:02:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:02:02 INFO - 'use_talos_json': True, 19:02:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:02:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:02:02 INFO - 'work_dir': 'build'} 19:02:02 INFO - [mozharness: 2017-02-06 03:02:02.615102Z] Running clobber step. 19:02:02 INFO - Running pre-action listener: _resource_record_pre_action 19:02:02 INFO - Running main action method: clobber 19:02:02 INFO - rmtree: /builds/slave/test/build 19:02:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:02:05 INFO - Running post-action listener: _resource_record_post_action 19:02:05 INFO - [mozharness: 2017-02-06 03:02:05.044850Z] Finished clobber step (success) 19:02:05 INFO - [mozharness: 2017-02-06 03:02:05.045010Z] Running read-buildbot-config step. 19:02:05 INFO - Running pre-action listener: _resource_record_pre_action 19:02:05 INFO - Running main action method: read_buildbot_config 19:02:05 INFO - Using buildbot properties: 19:02:05 INFO - { 19:02:05 INFO - "project": "", 19:02:05 INFO - "product": "firefox", 19:02:05 INFO - "script_repo_revision": "production", 19:02:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 19:02:05 INFO - "repository": "", 19:02:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 19:02:05 INFO - "buildid": "20170205181908", 19:02:05 INFO - "pgo_build": "False", 19:02:05 INFO - "basedir": "/builds/slave/test", 19:02:05 INFO - "buildnumber": 68, 19:02:05 INFO - "slavename": "t-yosemite-r7-0364", 19:02:05 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 19:02:05 INFO - "platform": "yosemite_r7", 19:02:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:02:05 INFO - "branch": "mozilla-central", 19:02:05 INFO - "repo_path": "mozilla-central", 19:02:05 INFO - "revision": "20a8536b0bfac74389d3a57bd8dd957d98779ce1", 19:02:05 INFO - "stage_platform": "macosx64", 19:02:05 INFO - "builduid": "ee3d9e8cc9d74abebe76246128f8f45a", 19:02:05 INFO - "slavebuilddir": "test" 19:02:05 INFO - } 19:02:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 19:02:05 INFO - Running post-action listener: _resource_record_post_action 19:02:05 INFO - [mozharness: 2017-02-06 03:02:05.048417Z] Finished read-buildbot-config step (success) 19:02:05 INFO - [mozharness: 2017-02-06 03:02:05.048540Z] Running download-and-extract step. 19:02:05 INFO - Running pre-action listener: _resource_record_pre_action 19:02:05 INFO - Running main action method: download_and_extract 19:02:05 INFO - mkdir: /builds/slave/test/build/tests 19:02:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:05 INFO - trying https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 19:02:05 INFO - Downloading https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 19:02:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 19:02:06 INFO - Downloaded 1199 bytes. 19:02:06 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 19:02:06 INFO - Using the following test package requirements: 19:02:06 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 19:02:06 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 19:02:06 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 19:02:06 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 19:02:06 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 19:02:06 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 19:02:06 INFO - u'jsshell-mac.zip'], 19:02:06 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 19:02:06 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 19:02:06 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 19:02:06 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 19:02:06 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 19:02:06 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 19:02:06 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 19:02:06 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 19:02:06 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 19:02:06 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 19:02:06 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 19:02:06 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:02:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 19:02:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:02:06 INFO - Fetch https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 19:02:07 INFO - Expected file size: 34850933 19:02:07 INFO - Obtained file size: 34850933 19:02:14 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:02:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 19:02:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:02:14 INFO - Fetch https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 19:02:15 INFO - Expected file size: 34850933 19:02:15 INFO - Obtained file size: 34850933 19:02:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 19:02:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:02:21 INFO - Fetch https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 19:02:23 INFO - Expected file size: 14403454 19:02:23 INFO - Obtained file size: 14403454 19:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:23 INFO - trying https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 19:02:23 INFO - Downloading https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 19:02:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 19:02:26 INFO - Downloaded 70288827 bytes. 19:02:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 19:02:26 INFO - mkdir: /builds/slave/test/properties 19:02:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:02:26 INFO - Writing to file /builds/slave/test/properties/build_url 19:02:26 INFO - Contents: 19:02:26 INFO - build_url:https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 19:02:26 INFO - Running post-action listener: _resource_record_post_action 19:02:26 INFO - Running post-action listener: set_extra_try_arguments 19:02:26 INFO - [mozharness: 2017-02-06 03:02:26.831154Z] Finished download-and-extract step (success) 19:02:26 INFO - [mozharness: 2017-02-06 03:02:26.831271Z] Running populate-webroot step. 19:02:26 INFO - Running pre-action listener: _resource_record_pre_action 19:02:26 INFO - Running main action method: populate_webroot 19:02:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:02:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:02:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:02:26 INFO - '--tppagecycles', 19:02:26 INFO - '7'], 19:02:26 INFO - 'tests': ['tsvgm']}}, 19:02:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:02:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:02:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:02:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:02:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:02:26 INFO - 'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:02:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:02:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:02:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:02:26 INFO - 'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['damp', 'tps']}, 19:02:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:02:26 INFO - 'tests': ['damp', 'tps']}, 19:02:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['dromaeo_dom']}, 19:02:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:02:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['basic_compositor_video']}, 19:02:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:02:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['a11yr', 19:02:26 INFO - 'ts_paint', 19:02:26 INFO - 'tpaint', 19:02:26 INFO - 'sessionrestore', 19:02:26 INFO - 'sessionrestore_no_auto_restore', 19:02:26 INFO - 'tabpaint']}, 19:02:26 INFO - 'other-e10s': {'tests': ['a11yr', 19:02:26 INFO - 'ts_paint', 19:02:26 INFO - 'tpaint', 19:02:26 INFO - 'sessionrestore', 19:02:26 INFO - 'sessionrestore_no_auto_restore', 19:02:26 INFO - 'tabpaint']}, 19:02:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:02:26 INFO - 'ts_paint', 19:02:26 INFO - 'tpaint', 19:02:26 INFO - 'sessionrestore', 19:02:26 INFO - 'sessionrestore_no_auto_restore', 19:02:26 INFO - 'tabpaint']}, 19:02:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:02:26 INFO - 'ts_paint', 19:02:26 INFO - 'tpaint', 19:02:26 INFO - 'sessionrestore', 19:02:26 INFO - 'sessionrestore_no_auto_restore', 19:02:26 INFO - 'tabpaint']}, 19:02:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['a11yr', 19:02:26 INFO - 'ts_paint', 19:02:26 INFO - 'tpaint', 19:02:26 INFO - 'sessionrestore', 19:02:26 INFO - 'sessionrestore_no_auto_restore', 19:02:26 INFO - 'tabpaint']}, 19:02:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['a11yr', 19:02:26 INFO - 'ts_paint', 19:02:26 INFO - 'tpaint', 19:02:26 INFO - 'sessionrestore', 19:02:26 INFO - 'sessionrestore_no_auto_restore', 19:02:26 INFO - 'tabpaint']}, 19:02:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['tsvgx', 19:02:26 INFO - 'tsvgr_opacity', 19:02:26 INFO - 'tart', 19:02:26 INFO - 'tscrollx', 19:02:26 INFO - 'cart', 19:02:26 INFO - 'tsvg_static']}, 19:02:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:02:26 INFO - 'tsvgr_opacity', 19:02:26 INFO - 'tart', 19:02:26 INFO - 'tscrollx', 19:02:26 INFO - 'cart', 19:02:26 INFO - 'tsvg_static']}, 19:02:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:02:26 INFO - 'talos_options': ['--disable-e10s'], 19:02:26 INFO - 'tests': ['tp5o']}, 19:02:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:02:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:02:26 INFO - 'talos_options': ['--disable-e10s', 19:02:26 INFO - '--xperf_path', 19:02:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:02:26 INFO - 'tests': ['tp5n']}, 19:02:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:02:26 INFO - 'talos_options': ['--xperf_path', 19:02:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:02:26 INFO - 'tests': ['tp5n']}}, 19:02:26 INFO - 'talos.zip': {'path': '', 19:02:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:02:26 INFO - Running post-action listener: _resource_record_post_action 19:02:26 INFO - [mozharness: 2017-02-06 03:02:26.849443Z] Finished populate-webroot step (success) 19:02:26 INFO - [mozharness: 2017-02-06 03:02:26.849556Z] Running create-virtualenv step. 19:02:26 INFO - Running pre-action listener: _resource_record_pre_action 19:02:26 INFO - Running main action method: create_virtualenv 19:02:26 INFO - Creating virtualenv /builds/slave/test/build/venv 19:02:26 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 19:02:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:02:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:02:26 INFO - Using real prefix '/tools/python27' 19:02:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:02:27 INFO - Installing distribute.............................................................................................................................................................................................done. 19:02:30 INFO - Installing pip.................done. 19:02:30 INFO - Return code: 0 19:02:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:02:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:02:30 INFO - Reading from file tmpfile_stdout 19:02:30 INFO - Output received: 19:02:30 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) 19:02:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:02:30 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')]} 19:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:30 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')]} 19:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:30 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 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:30 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 19:02:30 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 19:02:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:30 INFO - 'HOME': '/Users/cltbld', 19:02:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:30 INFO - 'LOGNAME': 'cltbld', 19:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:30 INFO - 'MOZ_NO_REMOTE': '1', 19:02:30 INFO - 'NO_EM_RESTART': '1', 19:02:30 INFO - 'PAGER': '/bin/cat', 19:02:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:30 INFO - 'PWD': '/builds/slave/test', 19:02:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:30 INFO - 'SHELL': '/bin/bash', 19:02:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:30 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:30 INFO - 'USER': 'cltbld', 19:02:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:30 INFO - 'XPC_FLAGS': '0x0', 19:02:30 INFO - 'XPC_SERVICE_NAME': '0', 19:02:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:31 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 19:02:31 INFO - Cleaning up... 19:02:31 INFO - Return code: 0 19:02:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:02:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:31 INFO - 'HOME': '/Users/cltbld', 19:02:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:31 INFO - 'LOGNAME': 'cltbld', 19:02:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:31 INFO - 'MOZ_NO_REMOTE': '1', 19:02:31 INFO - 'NO_EM_RESTART': '1', 19:02:31 INFO - 'PAGER': '/bin/cat', 19:02:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:31 INFO - 'PWD': '/builds/slave/test', 19:02:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:31 INFO - 'SHELL': '/bin/bash', 19:02:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:31 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:31 INFO - 'USER': 'cltbld', 19:02:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:31 INFO - 'XPC_FLAGS': '0x0', 19:02:31 INFO - 'XPC_SERVICE_NAME': '0', 19:02:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:31 INFO - Downloading/unpacking psutil>=3.1.1 19:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:02:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:02:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:02:35 INFO - Installing collected packages: psutil 19:02:35 INFO - Running setup.py install for psutil 19:02:35 INFO - building 'psutil._psutil_osx' extension 19:02:35 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 19:02:36 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 19:02:36 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 19:02:36 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 19:02:36 INFO - building 'psutil._psutil_posix' extension 19:02:36 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 19:02:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:02:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:02:36 INFO - ^ 19:02:36 INFO - 1 warning generated. 19:02:36 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 19:02:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:02:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:02:36 INFO - Successfully installed psutil 19:02:36 INFO - Cleaning up... 19:02:36 INFO - Return code: 0 19:02:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:02:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:36 INFO - 'HOME': '/Users/cltbld', 19:02:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:36 INFO - 'LOGNAME': 'cltbld', 19:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:36 INFO - 'MOZ_NO_REMOTE': '1', 19:02:36 INFO - 'NO_EM_RESTART': '1', 19:02:36 INFO - 'PAGER': '/bin/cat', 19:02:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:36 INFO - 'PWD': '/builds/slave/test', 19:02:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:36 INFO - 'SHELL': '/bin/bash', 19:02:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:36 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:36 INFO - 'USER': 'cltbld', 19:02:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:36 INFO - 'XPC_FLAGS': '0x0', 19:02:36 INFO - 'XPC_SERVICE_NAME': '0', 19:02:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:02:39 INFO - Installing collected packages: mozsystemmonitor 19:02:39 INFO - Running setup.py install for mozsystemmonitor 19:02:39 INFO - Successfully installed mozsystemmonitor 19:02:39 INFO - Cleaning up... 19:02:39 INFO - Return code: 0 19:02:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:39 INFO - 'HOME': '/Users/cltbld', 19:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:39 INFO - 'LOGNAME': 'cltbld', 19:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:39 INFO - 'MOZ_NO_REMOTE': '1', 19:02:39 INFO - 'NO_EM_RESTART': '1', 19:02:39 INFO - 'PAGER': '/bin/cat', 19:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:39 INFO - 'PWD': '/builds/slave/test', 19:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:39 INFO - 'SHELL': '/bin/bash', 19:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:39 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:39 INFO - 'USER': 'cltbld', 19:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:39 INFO - 'XPC_FLAGS': '0x0', 19:02:39 INFO - 'XPC_SERVICE_NAME': '0', 19:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:40 INFO - Downloading/unpacking jsonschema==2.5.1 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:02:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02:43 INFO - Downloading functools32-3.2.3-2.tar.gz 19:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:02:43 INFO - warning: no files found matching '*.txt' 19:02:43 INFO - no previously-included directories found matching 'build' 19:02:43 INFO - no previously-included directories found matching 'dist' 19:02:43 INFO - no previously-included directories found matching '.git*' 19:02:43 INFO - Installing collected packages: jsonschema, functools32 19:02:43 INFO - Running setup.py install for functools32 19:02:43 INFO - warning: no files found matching '*.txt' 19:02:43 INFO - no previously-included directories found matching 'build' 19:02:43 INFO - no previously-included directories found matching 'dist' 19:02:43 INFO - no previously-included directories found matching '.git*' 19:02:43 INFO - Successfully installed jsonschema functools32 19:02:43 INFO - Cleaning up... 19:02:43 INFO - Return code: 0 19:02:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:43 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 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:43 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 19:02:43 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 19:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:43 INFO - 'HOME': '/Users/cltbld', 19:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:43 INFO - 'LOGNAME': 'cltbld', 19:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:43 INFO - 'MOZ_NO_REMOTE': '1', 19:02:43 INFO - 'NO_EM_RESTART': '1', 19:02:43 INFO - 'PAGER': '/bin/cat', 19:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:43 INFO - 'PWD': '/builds/slave/test', 19:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:43 INFO - 'SHELL': '/bin/bash', 19:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:43 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:43 INFO - 'USER': 'cltbld', 19:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:43 INFO - 'XPC_FLAGS': '0x0', 19:02:43 INFO - 'XPC_SERVICE_NAME': '0', 19:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:02:43 INFO - Cleaning up... 19:02:43 INFO - Return code: 0 19:02:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:43 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 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:43 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 19:02:43 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 19:02:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:43 INFO - 'HOME': '/Users/cltbld', 19:02:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:43 INFO - 'LOGNAME': 'cltbld', 19:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:43 INFO - 'MOZ_NO_REMOTE': '1', 19:02:43 INFO - 'NO_EM_RESTART': '1', 19:02:43 INFO - 'PAGER': '/bin/cat', 19:02:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:43 INFO - 'PWD': '/builds/slave/test', 19:02:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:43 INFO - 'SHELL': '/bin/bash', 19:02:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:43 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:43 INFO - 'USER': 'cltbld', 19:02:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:43 INFO - 'XPC_FLAGS': '0x0', 19:02:43 INFO - 'XPC_SERVICE_NAME': '0', 19:02:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:44 INFO - Downloading/unpacking blobuploader==1.2.4 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02:46 INFO - Downloading blobuploader-1.2.4.tar.gz 19:02:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:02:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:02:46 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 19:02:46 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 19:02:46 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 19:02:46 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 19:02:46 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 19:02:46 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 19:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:02:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:47 INFO - Downloading docopt-0.6.1.tar.gz 19:02:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:02:48 INFO - Installing collected packages: blobuploader, requests, docopt 19:02:48 INFO - Running setup.py install for blobuploader 19:02:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:02:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:02:48 INFO - Running setup.py install for requests 19:02:48 INFO - Running setup.py install for docopt 19:02:48 INFO - Successfully installed blobuploader requests docopt 19:02:48 INFO - Cleaning up... 19:02:48 INFO - Return code: 0 19:02:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:48 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')]} 19:02:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:48 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')]} 19:02:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:48 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 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:48 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 19:02:48 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 19:02:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:48 INFO - 'HOME': '/Users/cltbld', 19:02:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:48 INFO - 'LOGNAME': 'cltbld', 19:02:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:48 INFO - 'MOZ_NO_REMOTE': '1', 19:02:48 INFO - 'NO_EM_RESTART': '1', 19:02:48 INFO - 'PAGER': '/bin/cat', 19:02:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:48 INFO - 'PWD': '/builds/slave/test', 19:02:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:48 INFO - 'SHELL': '/bin/bash', 19:02:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:48 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:48 INFO - 'USER': 'cltbld', 19:02:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:48 INFO - 'XPC_FLAGS': '0x0', 19:02:48 INFO - 'XPC_SERVICE_NAME': '0', 19:02:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:49 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-7q3WJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:49 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-1PwtAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:49 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-ui1T1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:49 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-E5UDa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:49 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-dTZNwm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:49 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-uYhkrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:49 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-kHiDXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:49 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-PIdPfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-kyl6Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-QMcMBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-7vQwj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-xFlX55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-k5vYwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-R_wAj1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-IBW1en-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-dBZ99B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:50 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-XXYv5a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:02:50 INFO - Running setup.py install for manifestparser 19:02:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:02:51 INFO - Running setup.py install for mozcrash 19:02:51 INFO - Running setup.py install for mozdebug 19:02:51 INFO - Running setup.py install for mozdevice 19:02:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:02:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:02:51 INFO - Running setup.py install for mozfile 19:02:51 INFO - Running setup.py install for mozhttpd 19:02:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:02:51 INFO - Running setup.py install for mozinfo 19:02:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Running setup.py install for mozInstall 19:02:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Running setup.py install for mozleak 19:02:52 INFO - Running setup.py install for mozlog 19:02:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Running setup.py install for moznetwork 19:02:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Running setup.py install for mozprocess 19:02:52 INFO - Running setup.py install for mozprofile 19:02:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:02:52 INFO - Running setup.py install for mozrunner 19:02:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:02:53 INFO - Running setup.py install for mozscreenshot 19:02:53 INFO - Running setup.py install for moztest 19:02:53 INFO - Running setup.py install for mozversion 19:02:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:02:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:02:53 INFO - Cleaning up... 19:02:53 INFO - Return code: 0 19:02:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:53 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')]} 19:02:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:53 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')]} 19:02:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:53 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 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:53 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 19:02:53 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 19:02:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:53 INFO - 'HOME': '/Users/cltbld', 19:02:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:53 INFO - 'LOGNAME': 'cltbld', 19:02:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:53 INFO - 'MOZ_NO_REMOTE': '1', 19:02:53 INFO - 'NO_EM_RESTART': '1', 19:02:53 INFO - 'PAGER': '/bin/cat', 19:02:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:53 INFO - 'PWD': '/builds/slave/test', 19:02:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:53 INFO - 'SHELL': '/bin/bash', 19:02:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:53 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:53 INFO - 'USER': 'cltbld', 19:02:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:53 INFO - 'XPC_FLAGS': '0x0', 19:02:53 INFO - 'XPC_SERVICE_NAME': '0', 19:02:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:53 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-g19RkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:53 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)) 19:02:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:53 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-_HqxCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-YDIaD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-709rTr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-izIgbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-dRwzV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-v14RbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-wYIygy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-TL0AD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-sLuk3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:54 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)) 19:02:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:54 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-chVQo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:55 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)) 19:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-4_8HnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-VWaw94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:55 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)) 19:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-X2LTps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:55 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)) 19:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-c9yoRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:55 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)) 19:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-nXBIr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:55 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)) 19:02:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:55 INFO - Running setup.py (path:/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/pip-g7gMIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 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)) 19:02:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:02:55 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 19:02:55 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 19:02:55 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 19:02:55 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 19:02:55 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 19:02:55 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 19:02:58 INFO - Downloading blessings-1.6.tar.gz 19:02:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:02:58 INFO - Installing collected packages: blessings 19:02:58 INFO - Running setup.py install for blessings 19:02:58 INFO - Successfully installed blessings 19:02:58 INFO - Cleaning up... 19:02:58 INFO - Return code: 0 19:02:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:58 INFO - Reading from file tmpfile_stdout 19:02:58 INFO - Current package versions: 19:02:58 INFO - blessings == 1.6 19:02:58 INFO - blobuploader == 1.2.4 19:02:58 INFO - docopt == 0.6.1 19:02:58 INFO - functools32 == 3.2.3-2 19:02:58 INFO - jsonschema == 2.5.1 19:02:58 INFO - manifestparser == 1.1 19:02:58 INFO - mozInstall == 1.12 19:02:58 INFO - mozcrash == 1.0 19:02:58 INFO - mozdebug == 0.1 19:02:58 INFO - mozdevice == 0.49 19:02:58 INFO - mozfile == 1.2 19:02:58 INFO - mozhttpd == 0.7 19:02:58 INFO - mozinfo == 0.9 19:02:58 INFO - mozleak == 0.1 19:02:58 INFO - mozlog == 3.4 19:02:58 INFO - moznetwork == 0.27 19:02:58 INFO - mozprocess == 0.24 19:02:58 INFO - mozprofile == 0.28 19:02:58 INFO - mozrunner == 6.13 19:02:58 INFO - mozscreenshot == 0.1 19:02:58 INFO - mozsystemmonitor == 0.3 19:02:58 INFO - moztest == 0.8 19:02:58 INFO - mozversion == 1.4 19:02:58 INFO - psutil == 3.1.1 19:02:58 INFO - requests == 1.2.3 19:02:58 INFO - wsgiref == 0.1.2 19:02:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:58 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 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:58 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 19:02:58 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 19:02:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:58 INFO - 'HOME': '/Users/cltbld', 19:02:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:58 INFO - 'LOGNAME': 'cltbld', 19:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:58 INFO - 'MOZ_NO_REMOTE': '1', 19:02:58 INFO - 'NO_EM_RESTART': '1', 19:02:58 INFO - 'PAGER': '/bin/cat', 19:02:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:58 INFO - 'PWD': '/builds/slave/test', 19:02:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:58 INFO - 'SHELL': '/bin/bash', 19:02:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:58 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:58 INFO - 'USER': 'cltbld', 19:02:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:58 INFO - 'XPC_FLAGS': '0x0', 19:02:58 INFO - 'XPC_SERVICE_NAME': '0', 19:02:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 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)) 19:02:59 INFO - Cleaning up... 19:02:59 INFO - Return code: 0 19:02:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044bab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1044bd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff17ad88440>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, '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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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 19:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:02:59 INFO - 'HOME': '/Users/cltbld', 19:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:02:59 INFO - 'LOGNAME': 'cltbld', 19:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:59 INFO - 'MOZ_NO_REMOTE': '1', 19:02:59 INFO - 'NO_EM_RESTART': '1', 19:02:59 INFO - 'PAGER': '/bin/cat', 19:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:59 INFO - 'PWD': '/builds/slave/test', 19:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:02:59 INFO - 'SHELL': '/bin/bash', 19:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:02:59 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:02:59 INFO - 'USER': 'cltbld', 19:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:59 INFO - 'XPC_FLAGS': '0x0', 19:02:59 INFO - 'XPC_SERVICE_NAME': '0', 19:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:02:59 INFO - Cleaning up... 19:02:59 INFO - Return code: 0 19:02:59 INFO - Running post-action listener: _resource_record_post_action 19:02:59 INFO - Running post-action listener: _start_resource_monitoring 19:02:59 INFO - Starting resource monitoring. 19:02:59 INFO - [mozharness: 2017-02-06 03:02:59.554485Z] Finished create-virtualenv step (success) 19:02:59 INFO - [mozharness: 2017-02-06 03:02:59.555505Z] Running install step. 19:02:59 INFO - Running pre-action listener: _resource_record_pre_action 19:02:59 INFO - Running main action method: install 19:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:59 INFO - Reading from file tmpfile_stdout 19:02:59 INFO - Detecting whether we're running mozinstall >=1.0... 19:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:02:59 INFO - Reading from file tmpfile_stdout 19:02:59 INFO - Output received: 19:02:59 INFO - Usage: mozinstall [options] installer 19:02:59 INFO - Options: 19:02:59 INFO - -h, --help show this help message and exit 19:02:59 INFO - -d DEST, --destination=DEST 19:02:59 INFO - Directory to install application into. [default: 19:02:59 INFO - "/builds/slave/test"] 19:02:59 INFO - --app=APP Application being installed. [default: firefox] 19:02:59 INFO - mkdir: /builds/slave/test/build/application 19:02:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:03:22 INFO - Reading from file tmpfile_stdout 19:03:22 INFO - Output received: 19:03:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:03:22 INFO - Running post-action listener: _resource_record_post_action 19:03:22 INFO - [mozharness: 2017-02-06 03:03:22.146942Z] Finished install step (success) 19:03:22 INFO - [mozharness: 2017-02-06 03:03:22.147039Z] Running run-tests step. 19:03:22 INFO - Running pre-action listener: _resource_record_pre_action 19:03:22 INFO - Running main action method: run_tests 19:03:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:03:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:03:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:03:22 INFO - 2017-02-05 19:03:22.160 screenresolution[1636:8274] starting screenresolution argv=screenresolution get 19:03:22 INFO - 2017-02-05 19:03:22.162 screenresolution[1636:8274] Display 0: 1600x1200x32@60 19:03:22 INFO - 2017-02-05 19:03:22.168 screenresolution[1637:8277] starting screenresolution argv=screenresolution list 19:03:22 INFO - Available Modes on Display 0 19:03:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:03:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:03:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:03:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:03:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:03:22 INFO - Intel Iris: 19:03:22 INFO - Chipset Model: Intel Iris 19:03:22 INFO - Type: GPU 19:03:22 INFO - Bus: Built-In 19:03:22 INFO - VRAM (Dynamic, Max): 1536 MB 19:03:22 INFO - Vendor: Intel (0x8086) 19:03:22 INFO - Device ID: 0x0a2e 19:03:22 INFO - Revision ID: 0x0009 19:03:22 INFO - Displays: 19:03:22 INFO - Display: 19:03:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:03:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:03:22 INFO - Display Serial Number: 1600x1200 60 19:03:22 INFO - Main Display: Yes 19:03:22 INFO - Mirror: Off 19:03:22 INFO - Online: Yes 19:03:22 INFO - Rotation: Supported 19:03:22 INFO - Return code: 0 19:03:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:03:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:03:22 INFO - Python 2.7.3 19:03:22 INFO - Return code: 0 19:03:22 INFO - grabbing minidump binary from tooltool 19:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:22 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 0x104298d30>, '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 0x1041bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10428dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:03:22 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 19:03:22 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 19:03:22 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 19:03:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:03:22 INFO - Return code: 0 19:03:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:03:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:03:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:03:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:03:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:03:22 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/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 19:03:22 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/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0364 --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 19:03:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GYYH5FpOg1/Render', 19:03:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:22 INFO - 'HOME': '/Users/cltbld', 19:03:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:22 INFO - 'LOGNAME': 'cltbld', 19:03:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:03:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:22 INFO - 'MOZ_NO_REMOTE': '1', 19:03:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:03:22 INFO - 'NO_EM_RESTART': '1', 19:03:22 INFO - 'PAGER': '/bin/cat', 19:03:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:22 INFO - 'PWD': '/builds/slave/test', 19:03:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:03:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:22 INFO - 'SHELL': '/bin/bash', 19:03:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hbkw9qU1aQ/Listeners', 19:03:22 INFO - 'TMPDIR': '/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/', 19:03:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:22 INFO - 'USER': 'cltbld', 19:03:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:22 INFO - 'XPC_FLAGS': '0x0', 19:03:22 INFO - 'XPC_SERVICE_NAME': '0', 19:03:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:22 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/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0364', '--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 19:03:22 INFO - mozversion application_buildid: 20170205181908 19:03:22 INFO - mozversion application_changeset: 20a8536b0bfac74389d3a57bd8dd957d98779ce1 19:03:22 INFO - mozversion application_display_name: Nightly 19:03:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:03:22 INFO - mozversion application_name: Firefox 19:03:22 INFO - mozversion application_remotingname: firefox 19:03:22 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:03:22 INFO - mozversion application_vendor: Mozilla 19:03:22 INFO - mozversion application_version: 54.0a1 19:03:22 INFO - mozversion platform_buildid: 20170205181908 19:03:22 INFO - mozversion platform_changeset: 20a8536b0bfac74389d3a57bd8dd957d98779ce1 19:03:22 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:03:22 INFO - mozversion platform_version: 54.0a1 19:03:22 INFO - using testdate: 1486350202 19:03:22 INFO - actual date: 1486350202 19:03:22 INFO - starting webserver on 'localhost:49244' 19:03:22 INFO - SUITE-START | Running 2 tests 19:03:22 INFO - TEST-START | dromaeo_css 19:03:22 INFO - operating with platform_type : mac_ 19:03:22 INFO - Initialising browser for dromaeo_css test... 19:03:22 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpwsRrYY/profile http://localhost:49244/getInfo.html) 19:03:24 INFO - PROCESS | 1647 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 19:03:25 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 19:03:25 INFO - PROCESS | 1647 | colorDepth:24 19:03:25 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 19:03:25 INFO - PROCESS | 1647 | __metrics 19:03:25 INFO - PROCESS | 1647 | 1486350205204 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 19:03:25 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:03:25 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:03:26 INFO - TEST-INFO | 1647: exit 0 19:03:26 INFO - Browser initialized. 19:03:26 INFO - Running cycle 1/1 for dromaeo_css test... 19:03:26 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmpwsRrYY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:03:38 INFO - PROCESS | 1650 | RSS: Main: 236605440 19:03:38 INFO - PROCESS | 1650 | 19:06:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 19:06:43 INFO - PROCESS | 1650 | RSS: Main: 396181504 19:06:43 INFO - PROCESS | 1650 | 19:08:27 INFO - PROCESS | 1650 | ************************* 19:08:27 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 19:08:27 INFO - PROCESS | 1650 | 19:08:27 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 19:08:27 INFO - PROCESS | 1650 | Full stack: 19:08:27 INFO - PROCESS | 1650 | ************************* 19:09:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 19:09:47 INFO - PROCESS | 1650 | RSS: Main: 374996992 19:09:47 INFO - PROCESS | 1650 | 19:12:49 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 19:12:50 INFO - PROCESS | 1650 | RSS: Main: 366227456 19:12:50 INFO - PROCESS | 1650 | 19:15:52 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 19:15:53 INFO - PROCESS | 1650 | RSS: Main: 337174528 19:15:53 INFO - PROCESS | 1650 | 19:18:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 19:18:57 INFO - PROCESS | 1650 | RSS: Main: 335052800 19:18:57 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 19:22:00 INFO - PROCESS | 1650 | RSS: Main: 440651776 19:22:00 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | __start_tp_report 19:22:00 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 19:22:00 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 19:22:00 INFO - PROCESS | 1650 | |i|pagename|runs| 19:22:00 INFO - PROCESS | 1650 | |0;dojo.html;2668.5997100434934;3258.989713188911;3280.7375409967203;3280.9835950820243;3283.901482955511;5332.57339412847;5410.513053825155;5511.476409741078;5545.916811247829;5567.749451274687;5433.592480563953;5459;5472.699001554913;5495.587830912677;5523.25436066131;4868.391451068616;4904.411470623521;4964.230544265644;5078.822241221539;5107.055194788957;4175.394567787669;5039.596832253429;5113.335266415361;5114;5149.665271757342;4909.975450122763;4955.752212389366;4965.255211718271;4992.675476094042;5056.747162641872;5003.4996500350035;5029.4216165141115;5037.546620804145;5086.084504789136;5105.33630628016;4973.229149481836;4983.800647974077;4996.625253106032;5007.223880298559;5037.168867136918;5479.150731636602;5498.642588231792;5506.862328441781;5516.586256030773;5541.5289700375115;5860.70696465175;5908.497777688902;5911.822645320647;6030.427109424598;6045.516358691294;5196.948030519684;6123.571350005502;6184.226971628546;6201.720922558496;6202.069689546559;5640.286915068979;5694.117411801178;5720.771169153229;5722.084466485342;5728.112142617901;6065.423784740531;6104.2064531611495;6108.236470441195;6115.785947491817;6204.193454850841;5854.5901786875365;5886.735096920649;5900.940990590125;5964.015937370372;5984.551158663117;6071.848203794941;6077.878442431127;6079.665618391031;6085.4523092921845;6089.238845144357;5720.170575266563;5790.739416726257;5816.970915145397;6017.608855424383;6032.728527216286;6018.097285407224;6046.637201767908;6086.056661217518;6111.227117700271;6128.019516877278;4780.354652121989;4806.110869489155;4815.133276010281;4817.590504807061;4858.17411040124;3721.5720192178246;4286.914261714748;4323.135372925428;4326.697131200785;4329.0259691569145;5374.5700343972385;5379.704116273564;5418.295621569249;5471.261379713687;5510.504054635102;5155.2782610433815;6156.353582873823;6158.661273629993;6181;6182.28903676074;5562.694051827189;5578.776848926079;5582.385937546866;5583.413741557078;5627.831165065055;5094.516020978075;5177.663451875616;5307.708076055854;5323.760430780624;5326.840194794162;6138.171346868205;6140.754369825157;6140.933729673127;6152.569320147636;6160.969195153995;5952.136940143569;5995.550333724901;6021.277446706424;6132.325444201045;6153.138560601609;5163.302954101148;6045.365236650265;6095.2685677718955;6120.325235636462;6152.292486363946;6063.393660633901;6094;6113.572049956639;6120.663363514871;6125.571210015256;6123.112148738319;6134.079888016833;6135.417135372132;6145.754169833157;6164.7842325518395;5189.014087323397;5449.400565937664;5693.117566777156;5858.853528661817;5878.853028674317;5905.084711869667;5909.970450147722;5922.674252916131;5925.733341999707;5932;5746.482816546531;5755.683437410982;5796.652200867961;5797.449242321973;5813.447722466359;5643.633163844337;5683.8010669626365;5701.7149142541875;5891;5940.8811823764145;4684.180268452939;4736.17117004516;5729.398413166724;5750.108733146369;5807.680577568274 19:22:00 INFO - PROCESS | 1650 | |1;ext.html;465.3016489812186;489.2196945871334;497.927800468932;498.50647858619976;540.0953403049891;28492.430151396984;31581.84209078957;38614.420783688234;40108.398374024415;40355.39466907991;40680.77957661267;41094;41098.79450602743;41230;41236.79381603088;7823.178566250547;7985.520868747864;9001.999999999984;9219.95390023049;9237.261019118454;8901.376903616749;11175.608853690082;11177.161712871526;11252.718682032973;11272.32366058035;10997.450127493634;11014.724631884186;11080.05819505343;11141.72145696356;11156.497957591928;11111.666650000512;11160.720981975515;11171.329720216732;11174.553017879234;11180.217384783069;10787.137029037658;10854.837177442265;10855.45722713869;10856.782864342747;10933.617323393722;38085.428718569245;38114.80942595269;38557.42163867544;38782.80608596967;38784.80607596944;3890.4164375343644;3923.4899463069905;3932.665723413513;3973.36426171814;4067.1052368478886;28939.42121157586;29229.661627069785;29433.411331773244;29849.850750746326;29948.251293717614;3073.800202986821;3120.968790312113;3124.703153200442;3198.696123868229;3249.220187155089;382686.08656956814;382696;384174;384990;388579.1142088571;48451.27323090156;49120.01759964781;56511.71744141253;57017;57079.42920570741;51158.97682046338;59723.70138149368;60016.89856362171;60601.39398606046;63846.36153638404;13865.098768580212;13957.092788968685;14145.43418263258;14339.283035848166;14343.426262949573;10901.945490272497;11005.394703291397;11013.394263315595;11032.50353734084;11037.448127593589;11902.285862848257;12903.612891613267;13166.341682916007;14758.114513129247;15165.317560709795;5107.948920510822;5405.972970135124;5781.5257109436825;6102.938970610237;6163.907541386883;839.8782176584361;856.6016802187013;862.2756884217288;869.9260562852102;869.9956500217585;27884.30289242785;28904.277393065342;28944.855275723487;29762.851185743933;29923.26726486523;909.4497828813605;1011.8279892418307;1023.4729114506035;1046.2467023742893;1176.864660564046;12472.126951113334;15022.849771502364;15060.32228549718;15257.95067015622;15315.540533784004;559842;561212;561658;562392;563305.4677812841;56198.43801562014;56215;56343.43656563382;56351.71824140853;56357;1178.6817559258952;1311.3836496846468;1356.8778809907155;1362.7478916400498;1371.739369519788;21166.047527860974;28433.43133137367;28437;28870.845166193958;29030.274243143245;48458.27312590385;48729.5127048725;48910.51089489202;48910.554472276955;48981;479372;481950;482768;483132;483398;31486.212844679067;36076;36953.63046369609;36966;37046.814765926;37287.81356093202;45114;45474.772626136655;46328.536714633774;47228.52771472241;33065;33145.00564983054;36221.81889090538;36483;37489.625103748614;34684.47973280352;36242.63757362393;36317.27365452623;37445;37485.81257093697;36617.90146295616;36856.63143368532;37137;37372.43941340937;37481.2503749918;27092;28550.714492854804;29013.27466813262;32464.67535324711;32645.34709305753;23521.88431007916;23783.881080594485;24131.517369652858;24312.635310470003;24506.897189626154 19:22:00 INFO - PROCESS | 1650 | |2;jquery.html;359.22228375566897;387.5794762682489;388.14026930349286;396.0810918668688;419.10311932464515;13925.094868833532;14120.799732022777;15445.30496127674;19631.31290404836;20419.28532501363;20201.797982020176;21020.36938891832;21046;21157.365279041653;21305.893470532625;4469.843555475559;5645.2096706461125;5939.495142912836;5944.940550594482;5951.613145145573;3868;4115.135821477494;4123.546409894909;5213.635045546814;5833.445822646863;4130.958690413087;6250.874982500347;6554.705038273265;6564.113844630985;6662.100616416795;6360.077788720633;6506.512011599149;6582.572132811352;6590.967045164792;6642.402183803457;4821.180399332087;5754.539636829086;5763.567732420085;5769.394213607553;5771.971140144315;24386.87806560956;24875.875620621962;25152.87423562889;25297.747022529722;25625.359366016;2693.0978122329043;2727.9590806137926;2742.766864816493;2748.4228312054493;2761.4891245119716;14553.636159096108;15159.545213643609;15292.694146117015;15618.453354132664;16034.75947860783;1757.5078977886212;1759.52492826936;1762.5946032412617;1766.2846547148426;1815.391843732338;113461.86538134595;119776;120146.97427950073;145592.99999999895;147152.2642386792;11492.195546311845;11829.763404731857;11834.704132396759;11834.940825295818;11900.702482438008;15704.450344237897;15706.685866282838;15710.214489275719;15714.528564143095;15737.842621573867;7303.261277021117;7944.245296696805;8079.555624440713;8144.75565733029;8215.260626543639;7620.364750713938;7621.695132194761;7649.311561959339;7654.923450765421;7811.648475818602;3937.350337194388;4787.935721138474;4791.616670666338;4858.368412106403;4919.87700307488;1746.087418122517;1911.543666388684;1986.3742920979846;2042.8263597594073;2043.7445319335084;397.8508059477696;422.1556886227545;434.6088520331727;435.7908204062068;1033.5245786938092;11883.643490695293;21587.35237942864;23177.420564486023;23597.882010589838;23715.644265336035;843;924.7364501117149;977.3695966101825;982.3320142303303;1022.6778564752079;8965.968913574892;9379.343445958853;9650.758731031781;10601.787964240671;10650.094741946867;679761;689800;694096;697340;702994;111350.82176990456;114293.42853285681;134248;138529;141882.2905885464;974.7465658928729;1008.4503945349802;1014.6702321745462;1071.8017166824163;1074.1943542343242;17917.64164716672;18910.338138165647;19095.809041909404;19276.32532861343;19380.515487112934;49529.00941981068;52999.73500132475;54713.42515059928;55261.72369138129;56076.71961640166;61381;74843.62578187074;78748;78873.60563197147;80864;7039.40165085953;7367.447441441806;7648.961755191411;7906.288434040964;8617.526036068077;11034.337939723642;13072.673183170496;13284.53504127351;13535.59393218205;13785.310734463035;11476.196666233283;11510.59712910044;11631.302121872715;11673.182877198515;11696.473658685209;8194.664520611836;11332.036776874058;11472.369019703996;11534.480948357177;11707.239029463057;10002.849957250794;11449.96950274446;11493.540258389905;11910.940445298065;11938.462769175581;6552.803415897531;7758.495697779626;7759.456838021284;7880.369570434237;8015.6392962318005;10658.89341106579;11768.906521200099;11799;11811.409429528318;11892.940535297268 19:22:00 INFO - PROCESS | 1650 | |3;mootools.html;1154.2324354304387;1245.9127861049724;1267.8922291605213;1448.058761804826;1450.717110163518;8205.83588328233;8608.139186081396;8620.310375169987;8627.87058194126;8636.524991125494;8201;8385.161483851613;8492.447990880588;8615.698450554231;8625.654973801056;2255.9661605075935;2292.7019487466605;2294.242899843048;2295.9655605165854;2296.8392212545045;1894.1097684088456;2248.4716091718515;2299.1378233162563;2303.8617682939075;2307.1348244408346;2004.0681083296265;2217.6230040893006;2293.392251053464;2293.839431239814;2306.8615883047073;2232.497688020199;2254.661800729887;2264.354658922199;2265.4223173090754;2269.149069099096;2240.865548067105;2287.462446325129;2288.0962020002007;2293.839431239814;2294.1855641247284;8533.104024077444;8616;8622.482651040895;8630.352723545697;8630.741077767678;4398.900274931268;5253.86865328366;5257.185136303879;5263.973680131613;5269.841904742825;3301.96698033019;3380.340833537461;3483.878064267764;3527.6295988921306;3547.0600290922666;1198.922070065443;1214.15009493355;1218.281214083695;1230.4831970572382;1254.9811752823714;9276.628934842598;9366.812663746756;10965.671029869038;11058.778824423547;11064.446777661085;4087.4890638670167;4347.260965635849;4399.318105693623;4496.977515112403;4499.167653984024;8861.468311901306;8870.15733505316;8870.64517419296;8871.778205544784;8876.245519130816;5048.091343558195;5049.343585333883;5052.898942021139;5054.949450505522;5060.3168572242275;4119.876403707894;4620.768961551909;4663;4754.334393184957;4821.590164835958;3602.9819850900576;3632.8365223565;3651.799151046665;3697.279030589063;3698.926021479609;1066.0778426660881;1142.9714257143637;1160.8723040465543;1163.4648061891626;1165.1669056624385;679.300320669711;764.8087978005499;836.7531578184451;853.3557164340883;911.3711539038043;5499.642523236057;5513.696746678971;5658.603897727202;5774.913376299359;5826.708664566755;925.5557332480447;954.1841725324886;979.8138353712772;985.571276494727;1021.9897801022043;3593.2813437312643;3733.4213196954515;3785.413260944558;3825.158465137665;3837.0286753210294;9919.851202231972;10242.436665983443;10243.38539687621;10268.024537668909;10270.589176433008;9608.423494590346;10025.949870250603;10029.197664186848;10183.287169898183;10197.082262596401;7737.032870891138;7927.960360198162;7943.642536086006;7945.404094692921;7962.601869906482;5665.49010589049;5666;5686.488216060575;5925.585209035326;5998.970005149947;9230.538473076454;9299;9392.81214375695;9404.811903761749;9433.669821556212;8907.955460222658;9236.861447078434;9255.935567409833;9376.906230937877;9398.906010939803;7125.180604230372;7277.053982982178;7277.490575659653;7279.308465695751;7284.417246620364;7537.397008239218;7783.610819459118;7798.298153166243;7817.687292508236;7882.408819338458;5711.771529138954;7161.39128174111;7199.8560028800175;7262.527935684164;7280.490365674564;7080.150381954199;7100.928990710234;7174.784756457315;7207.783766487014;7284.198738138694;6358.364163583605;6388.872222555616;6434.613923164625;6442.549021568445;6446.903296450463;4870.707757534559;4922.630802689741;4925.753712314298;5000.399952005782;5146.125158722951;4936.136176169228;4974.005198960294;5011.498850115106;5173.430922598436;5216.295800066942 19:22:00 INFO - PROCESS | 1650 | |4;prototype.html;349.8967804497673;356.5204799544612;359.1721082903907;364.29327105415473;368.4454895382449;4719.90560188796;5038.370203724535;5129.1280482318;5153.613478989077;5170.02723434026;4260.31834906415;4484.5515448455135;4708.6468514861435;5023.120953833083;5150.660828184671;3764.548254209492;3859.903502412434;3927.5876033016552;3945.993771588241;3973.7814420207023;3510.999365180924;3899.3566061599804;3903.302063602328;3914.843406263746;3924.7432726182415;3892.746971446861;3920.764754114748;3928.776565192415;3929;3943.9211215775667;3600.3879340512176;3845.1732877431486;3949.6050394960416;3960;3960.2871483133026;2653.111207745388;3376.8311584420926;3392.525046493493;3401.0953865834963;3404.2000129969438;4945.431275403303;4982.789542836297;5000.924801167766;5026.924596131061;5042.747862606855;3939.763614183158;4002.579729128429;4131.091159944807;4344.9348259776125;4454.688171827974;4513.27787553993;5038.664753840235;5143.562797162208;5182.637215394924;5415.023516416519;3763.8870833875026;3766.5875296763716;3769.5853456119803;3771.679407250387;3774.8867533974026;5137.460566640524;5376.768641013975;5565.050523222699;5670.17263347239;5704.80033198836;3219.9195020124216;3255.4140254753897;3266.4283750343593;3699.7040236780995;3774.6971400024977;3822.967798694337;3834.4631751554803;3835.463035175078;3835.884923452301;3841.635044670752;4124.8143833527565;4275.935860962088;4383.07955329378;4423.987085875919;4454.5990860822685;4878.170710979142;5396.62223644349;5422.671445495879;5432.619716619878;5446.482584154499;1217.5129948020863;1239.324568110382;1249.3378509390038;1249.5564074753331;1260.5840072775961;786.4534148766552;827.5737994932615;843.4644001059371;846.7375113714645;854.474498183625;4178.791060446965;4758.881662809237;4765.475797662254;4772.832950846704;4775.379200703886;4811.927821082687;4826.886658105503;5261.868453288698;5278.577713782888;5335.6531825432;4322.200392927319;4523.977380113079;4562.8820938870185;4806.158922188603;4817;2421.1646981791255;2463.95072098557;2524.8232623716162;2776.5835124731047;2815.211740712604;4947.851564453073;5342.672435327398;5445.237666726662;5512.724363781875;5565.443455654402;4447.643468742;4639.86080417588;4644.724085198296;4807.687500312469;5460.563154947595;4692.662591161503;4744.074905393484;4820.590249828733;5455.699936503531;5507.228987941691;4441.95614030709;5078.873028174326;5290.9735451322495;5311.946880531145;5377.892442151213;4664.463586687439;4991.825286114969;5152.763336799217;5211.1401618734;5507;4803.133477550606;4854.888123666216;4885.95114048855;5455.509004189642;5805.168588731417;3663.4905090013426;3697.3899306614107;3701.722370822253;3805.200907809391;3811.5426148862316;5049.343585333883;5597.880423915151;5617.550595952396;5646.35066967304;5677.51741102011;4217.451731274915;4920.778564964514;5340.572754179734;5403.081476149143;5496.422875598005;4590.977045114753;5420.783168673209;5422.322209723785;5435.456454354533;5494.313210848644;5301.416844147064;5332.413434522277;5343.038253114321;5353.170258609922;5368.973155134356;4575.450945886515;5068.313793689351;5409.810656626999;5424.294841670716;5460.945390546202;4475.7985890634345;5343.225232341367;5350.866228344323;5361.51746342831;5375.763574377993 19:22:00 INFO - PROCESS | 1650 | |5;yui.html;703.222938652789;717.9389751871093;754.486948874765;806.1132753970631;818.116434251009;13845.58463246103;14438.205898675582;14439.494617688382;14463;14473.782893256597;12982.935085324583;13329.600111996631;13428.395722192501;13431;13447.193168409878;647.9578827376229;663.5620490476263;848.088305072047;858.6522458404356;865.2256230673574;861.2162931732125;865.03548543374;865.2040123086775;865.948043117415;868.3313848336777;918.3020904112889;920.9493477858715;921.5898924978354;922.7739203895055;924.5423515359872;916.2120576304372;916.5692124701413;916.734147097341;917.5274733512247;917.655879045358;683.4395795447734;684.4524380495633;684.7740245718901;685.3146853146853;687.6046273392819;12149.08881833866;12417.875821241852;12595.937020314932;12660.366981650881;12778.041646876522;11599.94200029003;11737.354445505494;11759.529618815322;11910;12283.01735861138;10501.474926253657;10695.251332406735;10776.67669969902;10946.890531094667;11050.226484146113;3469.288795796881;3592.604813470516;3659.048647351681;3671.6144804795645;3718.2935242304147;65072;81446.77829832557;86715;86901;94220;10250.692479225636;10419.791604167875;10479.690038745157;10601.098906592872;10852.72868178286;18529.258829646933;20868.165273389197;21167.15331386727;21208.15167393287;21218.939053047285;7188.101487314086;7209.711611535585;7231.132264128339;7288.9271107289305;7484.989526413864;4057.8376864925663;4142.502899652061;4146.150039241948;4147.108371700038;4150.27370210212;3492.4237500812587;3560.3947328953623;3579.463080537888;3584.820758962094;3589.7666651668083;660.8909529927604;661.7551505942832;663.5189487621436;679.3681875855403;679.8266442057343;540.5351397797891;543.8422857371397;615.2954866677629;638.4381744065192;639.6961443314482;9102.284794129251;9920.62901136213;10021.830025526708;10217.489125543692;10501.527431265458;562.443181250559;568.5224411494282;574.5949105880344;639.7536948274948;708.8334241453255;4593.724376537419;4926.941070602882;4959.79812473717;4961.107000739829;4982.978489409735;76669.7328427449;91331;96133.51933240429;96604;97857.51071244598;84113.8034984936;85547.57226213829;85732.57133714292;87946.56026719953;89166;73423.63288183525;77268.84096738553;78131.43737125225;79385;79415;9194.95402522983;9490.478023708762;9565.282603804604;9572.681935261886;9630.855537167088;48182.518174819204;49324;49373;49432.76418089576;50290.74854625703;62310.37689623227;64679;64998.35001650112;65353.67323163354;66359.66820166062;11301.604443844426;11477.081833453136;11504.769904602028;11697.88302117002;11942.940285298519;12362.876371236172;12517.374131293216;12556.058295627681;12924.935375323063;13024.609261722164;11712.941435292769;11823.586174483851;11902.837240401997;11910.642680719593;12408.19346742495;11769.17615766888;11871.584494542649;11889.583864564698;11938.76122477563;12039.277643341427;10469.738256543647;10483.318584291997;10483.475826208507;10562;11031.613893513688;9775.462349570558;9926.15627671656;10312.793744125225;10398.792024159324;10420.218483613911;7885.369170466234;8294.336453083859;8896.555172241491;9251.398659087137;9363.861595904264 19:22:00 INFO - PROCESS | 1650 | __end_tp_report 19:22:00 INFO - PROCESS | 1650 | __start_cc_report 19:22:00 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1792 19:22:00 INFO - PROCESS | 1650 | __end_cc_report 19:22:00 INFO - PROCESS | 1650 | __startTimestamp1486351320794__endTimestamp 19:22:00 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | ------- Summary: start ------- 19:22:00 INFO - PROCESS | 1650 | Number of tests: 6 19:22:00 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5523.64 Median:5701.71 stddev:660.16 (11.6%) stddev-sans-first:623.03 19:22:00 INFO - PROCESS | 1650 | Values: 2668.6 3259.0 3280.7 3281.0 3283.9 5332.6 5410.5 5511.5 5545.9 5567.7 5433.6 5459.0 5472.7 5495.6 5523.3 4868.4 4904.4 4964.2 5078.8 5107.1 4175.4 5039.6 5113.3 5114.0 5149.7 4910.0 4955.8 4965.3 4992.7 5056.7 5003.5 5029.4 5037.5 5086.1 5105.3 4973.2 4983.8 4996.6 5007.2 5037.2 5479.2 5498.6 5506.9 5516.6 5541.5 5860.7 5908.5 5911.8 6030.4 6045.5 5196.9 6123.6 6184.2 6201.7 6202.1 5640.3 5694.1 5720.8 5722.1 5728.1 6065.4 6104.2 6108.2 6115.8 6204.2 5854.6 5886.7 5900.9 5964.0 5984.6 6071.8 6077.9 6079.7 6085.5 6089.2 5720.2 5790.7 5817.0 6017.6 6032.7 6018.1 6046.6 6086.1 6111.2 6128.0 4780.4 4806.1 4815.1 4817.6 4858.2 3721.6 4286.9 4323.1 4326.7 4329.0 5374.6 5379.7 5418.3 5471.3 5510.5 5155.3 6156.4 6158.7 6181.0 6182.3 5562.7 5578.8 5582.4 5583.4 5627.8 5094.5 5177.7 5307.7 5323.8 5326.8 6138.2 6140.8 6140.9 6152.6 6161.0 5952.1 5995.6 6021.3 6132.3 6153.1 5163.3 6045.4 6095.3 6120.3 6152.3 6063.4 6094.0 6113.6 6120.7 6125.6 6123.1 6134.1 6135.4 6145.8 6164.8 5189.0 5449.4 5693.1 5858.9 5878.9 5905.1 5910.0 5922.7 5925.7 5932.0 5746.5 5755.7 5796.7 5797.4 5813.4 5643.6 5683.8 5701.7 5891.0 5940.9 4684.2 4736.2 5729.4 5750.1 5807.7 19:22:00 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:61458.82 Median:36905.13 stddev:128246.79 (347.5%) stddev-sans-first:128524.79 19:22:00 INFO - PROCESS | 1650 | Values: 465.3 489.2 497.9 498.5 540.1 28492.4 31581.8 38614.4 40108.4 40355.4 40680.8 41094.0 41098.8 41230.0 41236.8 7823.2 7985.5 9002.0 9220.0 9237.3 8901.4 11175.6 11177.2 11252.7 11272.3 10997.5 11014.7 11080.1 11141.7 11156.5 11111.7 11160.7 11171.3 11174.6 11180.2 10787.1 10854.8 10855.5 10856.8 10933.6 38085.4 38114.8 38557.4 38782.8 38784.8 3890.4 3923.5 3932.7 3973.4 4067.1 28939.4 29229.7 29433.4 29849.9 29948.3 3073.8 3121.0 3124.7 3198.7 3249.2 382686.1 382696.0 384174.0 384990.0 388579.1 48451.3 49120.0 56511.7 57017.0 57079.4 51159.0 59723.7 60016.9 60601.4 63846.4 13865.1 13957.1 14145.4 14339.3 14343.4 10901.9 11005.4 11013.4 11032.5 11037.4 11902.3 12903.6 13166.3 14758.1 15165.3 5107.9 5406.0 5781.5 6102.9 6163.9 839.9 856.6 862.3 869.9 870.0 27884.3 28904.3 28944.9 29762.9 29923.3 909.4 1011.8 1023.5 1046.2 1176.9 12472.1 15022.8 15060.3 15258.0 15315.5 559842.0 561212.0 561658.0 562392.0 563305.5 56198.4 56215.0 56343.4 56351.7 56357.0 1178.7 1311.4 1356.9 1362.7 1371.7 21166.0 28433.4 28437.0 28870.8 29030.3 48458.3 48729.5 48910.5 48910.6 48981.0 479372.0 481950.0 482768.0 483132.0 483398.0 31486.2 36076.0 36953.6 36966.0 37046.8 37287.8 45114.0 45474.8 46328.5 47228.5 33065.0 33145.0 36221.8 36483.0 37489.6 34684.5 36242.6 36317.3 37445.0 37485.8 36617.9 36856.6 37137.0 37372.4 37481.3 27092.0 28550.7 29013.3 32464.7 32645.3 23521.9 23783.9 24131.5 24312.6 24506.9 19:22:00 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:37930.34 Median:25461.55 stddev:115098.87 (452.0%) stddev-sans-first:115387.18 19:22:00 INFO - PROCESS | 1650 | Values: 359.2 387.6 388.1 396.1 419.1 13925.1 14120.8 15445.3 19631.3 20419.3 20201.8 21020.4 21046.0 21157.4 21305.9 4469.8 5645.2 5939.5 5944.9 5951.6 3868.0 4115.1 4123.5 5213.6 5833.4 4131.0 6250.9 6554.7 6564.1 6662.1 6360.1 6506.5 6582.6 6591.0 6642.4 4821.2 5754.5 5763.6 5769.4 5772.0 24386.9 24875.9 25152.9 25297.7 25625.4 2693.1 2728.0 2742.8 2748.4 2761.5 14553.6 15159.5 15292.7 15618.5 16034.8 1757.5 1759.5 1762.6 1766.3 1815.4 113461.9 119776.0 120147.0 145593.0 147152.3 11492.2 11829.8 11834.7 11834.9 11900.7 15704.5 15706.7 15710.2 15714.5 15737.8 7303.3 7944.2 8079.6 8144.8 8215.3 7620.4 7621.7 7649.3 7654.9 7811.6 3937.4 4787.9 4791.6 4858.4 4919.9 1746.1 1911.5 1986.4 2042.8 2043.7 397.9 422.2 434.6 435.8 1033.5 11883.6 21587.4 23177.4 23597.9 23715.6 843.0 924.7 977.4 982.3 1022.7 8966.0 9379.3 9650.8 10601.8 10650.1 679761.0 689800.0 694096.0 697340.0 702994.0 111350.8 114293.4 134248.0 138529.0 141882.3 974.7 1008.5 1014.7 1071.8 1074.2 17917.6 18910.3 19095.8 19276.3 19380.5 49529.0 52999.7 54713.4 55261.7 56076.7 61381.0 74843.6 78748.0 78873.6 80864.0 7039.4 7367.4 7649.0 7906.3 8617.5 11034.3 13072.7 13284.5 13535.6 13785.3 11476.2 11510.6 11631.3 11673.2 11696.5 8194.7 11332.0 11472.4 11534.5 11707.2 10002.8 11450.0 11493.5 11910.9 11938.5 6552.8 7758.5 7759.5 7880.4 8015.6 10658.9 11768.9 11799.0 11811.4 11892.9 19:22:00 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5422.08 Median:5159.78 stddev:2982.27 (57.8%) stddev-sans-first:2973.38 19:22:00 INFO - PROCESS | 1650 | Values: 1154.2 1245.9 1267.9 1448.1 1450.7 8205.8 8608.1 8620.3 8627.9 8636.5 8201.0 8385.2 8492.4 8615.7 8625.7 2256.0 2292.7 2294.2 2296.0 2296.8 1894.1 2248.5 2299.1 2303.9 2307.1 2004.1 2217.6 2293.4 2293.8 2306.9 2232.5 2254.7 2264.4 2265.4 2269.1 2240.9 2287.5 2288.1 2293.8 2294.2 8533.1 8616.0 8622.5 8630.4 8630.7 4398.9 5253.9 5257.2 5264.0 5269.8 3302.0 3380.3 3483.9 3527.6 3547.1 1198.9 1214.2 1218.3 1230.5 1255.0 9276.6 9366.8 10965.7 11058.8 11064.4 4087.5 4347.3 4399.3 4497.0 4499.2 8861.5 8870.2 8870.6 8871.8 8876.2 5048.1 5049.3 5052.9 5054.9 5060.3 4119.9 4620.8 4663.0 4754.3 4821.6 3603.0 3632.8 3651.8 3697.3 3698.9 1066.1 1143.0 1160.9 1163.5 1165.2 679.3 764.8 836.8 853.4 911.4 5499.6 5513.7 5658.6 5774.9 5826.7 925.6 954.2 979.8 985.6 1022.0 3593.3 3733.4 3785.4 3825.2 3837.0 9919.9 10242.4 10243.4 10268.0 10270.6 9608.4 10025.9 10029.2 10183.3 10197.1 7737.0 7928.0 7943.6 7945.4 7962.6 5665.5 5666.0 5686.5 5925.6 5999.0 9230.5 9299.0 9392.8 9404.8 9433.7 8908.0 9236.9 9255.9 9376.9 9398.9 7125.2 7277.1 7277.5 7279.3 7284.4 7537.4 7783.6 7798.3 7817.7 7882.4 5711.8 7161.4 7199.9 7262.5 7280.5 7080.2 7100.9 7174.8 7207.8 7284.2 6358.4 6388.9 6434.6 6442.5 6446.9 4870.7 4922.6 4925.8 5000.4 5146.1 4936.1 4974.0 5011.5 5173.4 5216.3 19:22:00 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4283.95 Median:4762.18 stddev:1294.15 (27.2%) stddev-sans-first:1263.65 19:22:00 INFO - PROCESS | 1650 | Values: 349.9 356.5 359.2 364.3 368.4 4719.9 5038.4 5129.1 5153.6 5170.0 4260.3 4484.6 4708.6 5023.1 5150.7 3764.5 3859.9 3927.6 3946.0 3973.8 3511.0 3899.4 3903.3 3914.8 3924.7 3892.7 3920.8 3928.8 3929.0 3943.9 3600.4 3845.2 3949.6 3960.0 3960.3 2653.1 3376.8 3392.5 3401.1 3404.2 4945.4 4982.8 5000.9 5026.9 5042.7 3939.8 4002.6 4131.1 4344.9 4454.7 4513.3 5038.7 5143.6 5182.6 5415.0 3763.9 3766.6 3769.6 3771.7 3774.9 5137.5 5376.8 5565.1 5670.2 5704.8 3219.9 3255.4 3266.4 3699.7 3774.7 3823.0 3834.5 3835.5 3835.9 3841.6 4124.8 4275.9 4383.1 4424.0 4454.6 4878.2 5396.6 5422.7 5432.6 5446.5 1217.5 1239.3 1249.3 1249.6 1260.6 786.5 827.6 843.5 846.7 854.5 4178.8 4758.9 4765.5 4772.8 4775.4 4811.9 4826.9 5261.9 5278.6 5335.7 4322.2 4524.0 4562.9 4806.2 4817.0 2421.2 2464.0 2524.8 2776.6 2815.2 4947.9 5342.7 5445.2 5512.7 5565.4 4447.6 4639.9 4644.7 4807.7 5460.6 4692.7 4744.1 4820.6 5455.7 5507.2 4442.0 5078.9 5291.0 5311.9 5377.9 4664.5 4991.8 5152.8 5211.1 5507.0 4803.1 4854.9 4886.0 5455.5 5805.2 3663.5 3697.4 3701.7 3805.2 3811.5 5049.3 5597.9 5617.6 5646.4 5677.5 4217.5 4920.8 5340.6 5403.1 5496.4 4591.0 5420.8 5422.3 5435.5 5494.3 5301.4 5332.4 5343.0 5353.2 5369.0 4575.5 5068.3 5409.8 5424.3 5460.9 4475.8 5343.2 5350.9 5361.5 5375.8 19:22:00 INFO - PROCESS | 1650 | 19:22:00 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:18721.47 Median:25415.64 stddev:26671.00 (104.9%) stddev-sans-first:26711.51 19:22:00 INFO - PROCESS | 1650 | Values: 703.2 717.9 754.5 806.1 818.1 13845.6 14438.2 14439.5 14463.0 14473.8 12982.9 13329.6 13428.4 13431.0 13447.2 648.0 663.6 848.1 858.7 865.2 861.2 865.0 865.2 865.9 868.3 918.3 920.9 921.6 922.8 924.5 916.2 916.6 916.7 917.5 917.7 683.4 684.5 684.8 685.3 687.6 12149.1 12417.9 12595.9 12660.4 12778.0 11599.9 11737.4 11759.5 11910.0 12283.0 10501.5 10695.3 10776.7 10946.9 11050.2 3469.3 3592.6 3659.0 3671.6 3718.3 65072.0 81446.8 86715.0 86901.0 94220.0 10250.7 10419.8 10479.7 10601.1 10852.7 18529.3 20868.2 21167.2 21208.2 21218.9 7188.1 7209.7 7231.1 7288.9 7485.0 4057.8 4142.5 4146.2 4147.1 4150.3 3492.4 3560.4 3579.5 3584.8 3589.8 660.9 661.8 663.5 679.4 679.8 540.5 543.8 615.3 638.4 639.7 9102.3 9920.6 10021.8 10217.5 10501.5 562.4 568.5 574.6 639.8 708.8 4593.7 4926.9 4959.8 4961.1 4983.0 76669.7 91331.0 96133.5 96604.0 97857.5 84113.8 85547.6 85732.6 87946.6 89166.0 73423.6 77268.8 78131.4 79385.0 79415.0 9195.0 9490.5 9565.3 9572.7 9630.9 48182.5 49324.0 49373.0 49432.8 50290.7 62310.4 64679.0 64998.4 65353.7 66359.7 11301.6 11477.1 11504.8 11697.9 11942.9 12362.9 12517.4 12556.1 12924.9 13024.6 11712.9 11823.6 11902.8 11910.6 12408.2 11769.2 11871.6 11889.6 11938.8 12039.3 10469.7 10483.3 10483.5 10562.0 11031.6 9775.5 9926.2 10312.8 10398.8 10420.2 7885.4 8294.3 8896.6 9251.4 9363.9 19:22:00 INFO - PROCESS | 1650 | -------- Summary: end -------- 19:22:00 INFO - PROCESS | 1650 | 19:22:01 INFO - TEST-INFO | 1650: exit 0 19:22:01 INFO - TEST-OK | dromaeo_css | took 1118658ms 19:22:01 INFO - TEST-START | kraken 19:22:01 INFO - operating with platform_type : mac_ 19:22:01 INFO - Initialising browser for kraken test... 19:22:01 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp_GHAiR/profile http://localhost:49244/getInfo.html) 19:22:03 INFO - PROCESS | 1656 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 19:22:03 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 19:22:03 INFO - PROCESS | 1656 | colorDepth:24 19:22:03 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 19:22:03 INFO - PROCESS | 1656 | __metrics 19:22:03 INFO - PROCESS | 1656 | 1486351323828 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 19:22:03 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:22:03 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:22:04 INFO - TEST-INFO | 1656: exit 0 19:22:04 INFO - Browser initialized. 19:22:04 INFO - Running cycle 1/1 for kraken test... 19:22:04 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/T/tmp_GHAiR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:22:16 INFO - PROCESS | 1657 | RSS: Main: 236429312 19:22:16 INFO - PROCESS | 1657 | 19:22:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 19:22:20 INFO - PROCESS | 1657 | RSS: Main: 288718848 19:22:20 INFO - PROCESS | 1657 | 19:22:23 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 19:22:24 INFO - PROCESS | 1657 | RSS: Main: 290693120 19:22:24 INFO - PROCESS | 1657 | 19:22:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 19:22:27 INFO - PROCESS | 1657 | RSS: Main: 382640128 19:22:27 INFO - PROCESS | 1657 | 19:22:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 19:22:30 INFO - PROCESS | 1657 | RSS: Main: 284704768 19:22:30 INFO - PROCESS | 1657 | 19:22:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:22:33 INFO - PROCESS | 1657 | RSS: Main: 288063488 19:22:33 INFO - PROCESS | 1657 | 19:22:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 19:22:36 INFO - PROCESS | 1657 | RSS: Main: 328437760 19:22:36 INFO - PROCESS | 1657 | 19:22:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 19:22:40 INFO - PROCESS | 1657 | RSS: Main: 331395072 19:22:40 INFO - PROCESS | 1657 | 19:22:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 19:22:43 INFO - PROCESS | 1657 | RSS: Main: 332296192 19:22:43 INFO - PROCESS | 1657 | 19:22:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:22:46 INFO - PROCESS | 1657 | RSS: Main: 305577984 19:22:46 INFO - PROCESS | 1657 | 19:22:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:22:48 INFO - PROCESS | 1657 | RSS: Main: 309977088 19:22:48 INFO - PROCESS | 1657 | 19:22:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:22:51 INFO - PROCESS | 1657 | RSS: Main: 311607296 19:22:51 INFO - PROCESS | 1657 | 19:22:54 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:22:55 INFO - PROCESS | 1657 | RSS: Main: 329740288 19:22:55 INFO - PROCESS | 1657 | 19:22:58 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:22:58 INFO - PROCESS | 1657 | RSS: Main: 323624960 19:22:58 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 19:23:00 INFO - PROCESS | 1657 | RSS: Main: 368324608 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | __start_tp_report 19:23:00 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 19:23:00 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 19:23:00 INFO - PROCESS | 1657 | |i|pagename|runs| 19:23:00 INFO - PROCESS | 1657 | |0;ai-astar;115;115;114;115;114;115;119;161;164;114 19:23:00 INFO - PROCESS | 1657 | |1;audio-beat-detection;142;109;112;171;138;111;109;110;132;109 19:23:00 INFO - PROCESS | 1657 | |2;audio-dft;183;185;185;185;183;184;184;184;185;184 19:23:00 INFO - PROCESS | 1657 | |3;audio-fft;79;58;59;89;60;59;58;59;85;82 19:23:00 INFO - PROCESS | 1657 | |4;audio-oscillator;86;72;86;71;90;100;119;77;70;83 19:23:00 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;86;90;87;89;88;116;87;91;88 19:23:00 INFO - PROCESS | 1657 | |6;imaging-darkroom;97;97;96;95;96;95;96;96;95;94 19:23:00 INFO - PROCESS | 1657 | |7;imaging-desaturate;85;88;88;86;88;86;88;88;85;86 19:23:00 INFO - PROCESS | 1657 | |8;json-parse-financial;61;58;58;57;59;59;58;59;58;59 19:23:00 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;63;68;72;75;72;67;68;67;69;69 19:23:00 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;88;86;87;86;85;95;97;86;90;88 19:23:00 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;142;141;140;141;139;139;140;139;186;173 19:23:00 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;174;172;173;174;173;171;173;174;170;181 19:23:00 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;67;68;66;71;86;83;63;58;80 19:23:00 INFO - PROCESS | 1657 | __end_tp_report 19:23:00 INFO - PROCESS | 1657 | __start_cc_report 19:23:00 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2098 19:23:00 INFO - PROCESS | 1657 | __end_cc_report 19:23:00 INFO - PROCESS | 1657 | __startTimestamp1486351380814__endTimestamp 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | ------- Summary: start ------- 19:23:00 INFO - PROCESS | 1657 | Number of tests: 14 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:124.60 Median:115.00 stddev:20.04 (17.4%) stddev-sans-first:20.95 19:23:00 INFO - PROCESS | 1657 | Values: 115.0 115.0 114.0 115.0 114.0 115.0 119.0 161.0 164.0 114.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:122.00 stddev:21.02 (17.2%) stddev-sans-first:21.30 19:23:00 INFO - PROCESS | 1657 | Values: 142.0 109.0 112.0 171.0 138.0 111.0 109.0 110.0 132.0 109.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:0.79 (0.4%) stddev-sans-first:0.71 19:23:00 INFO - PROCESS | 1657 | Values: 183.0 185.0 185.0 185.0 183.0 184.0 184.0 184.0 185.0 184.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:68.80 Median:69.50 stddev:13.11 (18.9%) stddev-sans-first:13.38 19:23:00 INFO - PROCESS | 1657 | Values: 79.0 58.0 59.0 89.0 60.0 59.0 58.0 59.0 85.0 82.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:80.00 stddev:15.15 (18.9%) stddev-sans-first:16.06 19:23:00 INFO - PROCESS | 1657 | Values: 86.0 72.0 86.0 71.0 90.0 100.0 119.0 77.0 70.0 83.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:88.50 stddev:8.88 (10.0%) stddev-sans-first:9.38 19:23:00 INFO - PROCESS | 1657 | Values: 89.0 86.0 90.0 87.0 89.0 88.0 116.0 87.0 91.0 88.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:0.95 (1.0%) stddev-sans-first:0.88 19:23:00 INFO - PROCESS | 1657 | Values: 97.0 97.0 96.0 95.0 96.0 95.0 96.0 96.0 95.0 94.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:86.80 Median:88.00 stddev:1.32 (1.5%) stddev-sans-first:1.22 19:23:00 INFO - PROCESS | 1657 | Values: 85.0 88.0 88.0 86.0 88.0 86.0 88.0 88.0 85.0 86.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.00 stddev:1.07 (1.8%) stddev-sans-first:0.71 19:23:00 INFO - PROCESS | 1657 | Values: 61.0 58.0 58.0 57.0 59.0 59.0 58.0 59.0 58.0 59.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:69.00 stddev:3.33 (4.8%) stddev-sans-first:2.74 19:23:00 INFO - PROCESS | 1657 | Values: 63.0 68.0 72.0 75.0 72.0 67.0 68.0 67.0 69.0 69.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:88.00 stddev:4.08 (4.6%) stddev-sans-first:4.31 19:23:00 INFO - PROCESS | 1657 | Values: 88.0 86.0 87.0 86.0 85.0 95.0 97.0 86.0 90.0 88.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:141.00 stddev:16.91 (12.0%) stddev-sans-first:17.80 19:23:00 INFO - PROCESS | 1657 | Values: 142.0 141.0 140.0 141.0 139.0 139.0 140.0 139.0 186.0 173.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:173.50 stddev:2.95 (1.7%) stddev-sans-first:3.13 19:23:00 INFO - PROCESS | 1657 | Values: 174.0 172.0 173.0 174.0 173.0 171.0 173.0 174.0 170.0 181.0 19:23:00 INFO - PROCESS | 1657 | 19:23:00 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:69.50 stddev:9.12 (13.1%) stddev-sans-first:9.57 19:23:00 INFO - PROCESS | 1657 | Values: 67.0 67.0 68.0 66.0 71.0 86.0 83.0 63.0 58.0 80.0 19:23:00 INFO - PROCESS | 1657 | -------- Summary: end -------- 19:23:00 INFO - PROCESS | 1657 | 19:23:01 INFO - TEST-INFO | 1657: exit 0 19:23:01 INFO - TEST-OK | kraken | took 60391ms 19:23:01 INFO - SUITE-END | took 1179s 19:23:01 INFO - Completed test suite (00:19:39) 19:23:01 INFO - javascript benchmark 19:23:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7005.891187866555, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2668.5997100434934, 3258.989713188911, 3280.7375409967203, 3280.9835950820243, 3283.901482955511, 5332.57339412847, 5410.513053825155, 5511.476409741078, 5545.916811247829, 5567.749451274687, 5433.592480563953, 5459.0, 5472.699001554913, 5495.587830912677, 5523.25436066131, 4868.391451068616, 4904.411470623521, 4964.230544265644, 5078.822241221539, 5107.055194788957, 4175.394567787669, 5039.596832253429, 5113.335266415361, 5114.0, 5149.665271757342, 4909.975450122763, 4955.752212389366, 4965.255211718271, 4992.675476094042, 5056.747162641872, 5003.4996500350035, 5029.4216165141115, 5037.546620804145, 5086.084504789136, 5105.33630628016, 4973.229149481836, 4983.800647974077, 4996.625253106032, 5007.223880298559, 5037.168867136918, 5479.150731636602, 5498.642588231792, 5506.862328441781, 5516.586256030773, 5541.5289700375115, 5860.70696465175, 5908.497777688902, 5911.822645320647, 6030.427109424598, 6045.516358691294, 5196.948030519684, 6123.571350005502, 6184.226971628546, 6201.720922558496, 6202.069689546559, 5640.286915068979, 5694.117411801178, 5720.771169153229, 5722.084466485342, 5728.112142617901, 6065.423784740531, 6104.2064531611495, 6108.236470441195, 6115.785947491817, 6204.193454850841, 5854.5901786875365, 5886.735096920649, 5900.940990590125, 5964.015937370372, 5984.551158663117, 6071.848203794941, 6077.878442431127, 6079.665618391031, 6085.4523092921845, 6089.238845144357, 5720.170575266563, 5790.739416726257, 5816.970915145397, 6017.608855424383, 6032.728527216286, 6018.097285407224, 6046.637201767908, 6086.056661217518, 6111.227117700271, 6128.019516877278, 4780.354652121989, 4806.110869489155, 4815.133276010281, 4817.590504807061, 4858.17411040124, 3721.5720192178246, 4286.914261714748, 4323.135372925428, 4326.697131200785, 4329.0259691569145, 5374.5700343972385, 5379.704116273564, 5418.295621569249, 5471.261379713687, 5510.504054635102, 5155.2782610433815, 6156.353582873823, 6158.661273629993, 6181.0, 6182.28903676074, 5562.694051827189, 5578.776848926079, 5582.385937546866, 5583.413741557078, 5627.831165065055, 5094.516020978075, 5177.663451875616, 5307.708076055854, 5323.760430780624, 5326.840194794162, 6138.171346868205, 6140.754369825157, 6140.933729673127, 6152.569320147636, 6160.969195153995, 5952.136940143569, 5995.550333724901, 6021.277446706424, 6132.325444201045, 6153.138560601609, 5163.302954101148, 6045.365236650265, 6095.2685677718955, 6120.325235636462, 6152.292486363946, 6063.393660633901, 6094.0, 6113.572049956639, 6120.663363514871, 6125.571210015256, 6123.112148738319, 6134.079888016833, 6135.417135372132, 6145.754169833157, 6164.7842325518395, 5189.014087323397, 5449.400565937664, 5693.117566777156, 5858.853528661817, 5878.853028674317, 5905.084711869667, 5909.970450147722, 5922.674252916131, 5925.733341999707, 5932.0, 5746.482816546531, 5755.683437410982, 5796.652200867961, 5797.449242321973, 5813.447722466359, 5643.633163844337, 5683.8010669626365, 5701.7149142541875, 5891.0, 5940.8811823764145, 4684.180268452939, 4736.17117004516, 5729.398413166724, 5750.108733146369, 5807.680577568274], "value": 5480.377463537964, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.3016489812186, 489.2196945871334, 497.927800468932, 498.50647858619976, 540.0953403049891, 28492.430151396984, 31581.84209078957, 38614.420783688234, 40108.398374024415, 40355.39466907991, 40680.77957661267, 41094.0, 41098.79450602743, 41230.0, 41236.79381603088, 7823.178566250547, 7985.520868747864, 9001.999999999984, 9219.95390023049, 9237.261019118454, 8901.376903616749, 11175.608853690082, 11177.161712871526, 11252.718682032973, 11272.32366058035, 10997.450127493634, 11014.724631884186, 11080.05819505343, 11141.72145696356, 11156.497957591928, 11111.666650000512, 11160.720981975515, 11171.329720216732, 11174.553017879234, 11180.217384783069, 10787.137029037658, 10854.837177442265, 10855.45722713869, 10856.782864342747, 10933.617323393722, 38085.428718569245, 38114.80942595269, 38557.42163867544, 38782.80608596967, 38784.80607596944, 3890.4164375343644, 3923.4899463069905, 3932.665723413513, 3973.36426171814, 4067.1052368478886, 28939.42121157586, 29229.661627069785, 29433.411331773244, 29849.850750746326, 29948.251293717614, 3073.800202986821, 3120.968790312113, 3124.703153200442, 3198.696123868229, 3249.220187155089, 382686.08656956814, 382696.0, 384174.0, 384990.0, 388579.1142088571, 48451.27323090156, 49120.01759964781, 56511.71744141253, 57017.0, 57079.42920570741, 51158.97682046338, 59723.70138149368, 60016.89856362171, 60601.39398606046, 63846.36153638404, 13865.098768580212, 13957.092788968685, 14145.43418263258, 14339.283035848166, 14343.426262949573, 10901.945490272497, 11005.394703291397, 11013.394263315595, 11032.50353734084, 11037.448127593589, 11902.285862848257, 12903.612891613267, 13166.341682916007, 14758.114513129247, 15165.317560709795, 5107.948920510822, 5405.972970135124, 5781.5257109436825, 6102.938970610237, 6163.907541386883, 839.8782176584361, 856.6016802187013, 862.2756884217288, 869.9260562852102, 869.9956500217585, 27884.30289242785, 28904.277393065342, 28944.855275723487, 29762.851185743933, 29923.26726486523, 909.4497828813605, 1011.8279892418307, 1023.4729114506035, 1046.2467023742893, 1176.864660564046, 12472.126951113334, 15022.849771502364, 15060.32228549718, 15257.95067015622, 15315.540533784004, 559842.0, 561212.0, 561658.0, 562392.0, 563305.4677812841, 56198.43801562014, 56215.0, 56343.43656563382, 56351.71824140853, 56357.0, 1178.6817559258952, 1311.3836496846468, 1356.8778809907155, 1362.7478916400498, 1371.739369519788, 21166.047527860974, 28433.43133137367, 28437.0, 28870.845166193958, 29030.274243143245, 48458.27312590385, 48729.5127048725, 48910.51089489202, 48910.554472276955, 48981.0, 479372.0, 481950.0, 482768.0, 483132.0, 483398.0, 31486.212844679067, 36076.0, 36953.63046369609, 36966.0, 37046.814765926, 37287.81356093202, 45114.0, 45474.772626136655, 46328.536714633774, 47228.52771472241, 33065.0, 33145.00564983054, 36221.81889090538, 36483.0, 37489.625103748614, 34684.47973280352, 36242.63757362393, 36317.27365452623, 37445.0, 37485.81257093697, 36617.90146295616, 36856.63143368532, 37137.0, 37372.43941340937, 37481.2503749918, 27092.0, 28550.714492854804, 29013.27466813262, 32464.67535324711, 32645.34709305753, 23521.88431007916, 23783.881080594485, 24131.517369652858, 24312.635310470003, 24506.897189626154], "value": 18981.003676900804, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.22228375566897, 387.5794762682489, 388.14026930349286, 396.0810918668688, 419.10311932464515, 13925.094868833532, 14120.799732022777, 15445.30496127674, 19631.31290404836, 20419.28532501363, 20201.797982020176, 21020.36938891832, 21046.0, 21157.365279041653, 21305.893470532625, 4469.843555475559, 5645.2096706461125, 5939.495142912836, 5944.940550594482, 5951.613145145573, 3868.0, 4115.135821477494, 4123.546409894909, 5213.635045546814, 5833.445822646863, 4130.958690413087, 6250.874982500347, 6554.705038273265, 6564.113844630985, 6662.100616416795, 6360.077788720633, 6506.512011599149, 6582.572132811352, 6590.967045164792, 6642.402183803457, 4821.180399332087, 5754.539636829086, 5763.567732420085, 5769.394213607553, 5771.971140144315, 24386.87806560956, 24875.875620621962, 25152.87423562889, 25297.747022529722, 25625.359366016, 2693.0978122329043, 2727.9590806137926, 2742.766864816493, 2748.4228312054493, 2761.4891245119716, 14553.636159096108, 15159.545213643609, 15292.694146117015, 15618.453354132664, 16034.75947860783, 1757.5078977886212, 1759.52492826936, 1762.5946032412617, 1766.2846547148426, 1815.391843732338, 113461.86538134595, 119776.0, 120146.97427950073, 145592.99999999895, 147152.2642386792, 11492.195546311845, 11829.763404731857, 11834.704132396759, 11834.940825295818, 11900.702482438008, 15704.450344237897, 15706.685866282838, 15710.214489275719, 15714.528564143095, 15737.842621573867, 7303.261277021117, 7944.245296696805, 8079.555624440713, 8144.75565733029, 8215.260626543639, 7620.364750713938, 7621.695132194761, 7649.311561959339, 7654.923450765421, 7811.648475818602, 3937.350337194388, 4787.935721138474, 4791.616670666338, 4858.368412106403, 4919.87700307488, 1746.087418122517, 1911.543666388684, 1986.3742920979846, 2042.8263597594073, 2043.7445319335084, 397.8508059477696, 422.1556886227545, 434.6088520331727, 435.7908204062068, 1033.5245786938092, 11883.643490695293, 21587.35237942864, 23177.420564486023, 23597.882010589838, 23715.644265336035, 843.0, 924.7364501117149, 977.3695966101825, 982.3320142303303, 1022.6778564752079, 8965.968913574892, 9379.343445958853, 9650.758731031781, 10601.787964240671, 10650.094741946867, 679761.0, 689800.0, 694096.0, 697340.0, 702994.0, 111350.82176990456, 114293.42853285681, 134248.0, 138529.0, 141882.2905885464, 974.7465658928729, 1008.4503945349802, 1014.6702321745462, 1071.8017166824163, 1074.1943542343242, 17917.64164716672, 18910.338138165647, 19095.809041909404, 19276.32532861343, 19380.515487112934, 49529.00941981068, 52999.73500132475, 54713.42515059928, 55261.72369138129, 56076.71961640166, 61381.0, 74843.62578187074, 78748.0, 78873.60563197147, 80864.0, 7039.40165085953, 7367.447441441806, 7648.961755191411, 7906.288434040964, 8617.526036068077, 11034.337939723642, 13072.673183170496, 13284.53504127351, 13535.59393218205, 13785.310734463035, 11476.196666233283, 11510.59712910044, 11631.302121872715, 11673.182877198515, 11696.473658685209, 8194.664520611836, 11332.036776874058, 11472.369019703996, 11534.480948357177, 11707.239029463057, 10002.849957250794, 11449.96950274446, 11493.540258389905, 11910.940445298065, 11938.462769175581, 6552.803415897531, 7758.495697779626, 7759.456838021284, 7880.369570434237, 8015.6392962318005, 10658.89341106579, 11768.906521200099, 11799.0, 11811.409429528318, 11892.940535297268], "value": 9531.857479830744, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1154.2324354304387, 1245.9127861049724, 1267.8922291605213, 1448.058761804826, 1450.717110163518, 8205.83588328233, 8608.139186081396, 8620.310375169987, 8627.87058194126, 8636.524991125494, 8201.0, 8385.161483851613, 8492.447990880588, 8615.698450554231, 8625.654973801056, 2255.9661605075935, 2292.7019487466605, 2294.242899843048, 2295.9655605165854, 2296.8392212545045, 1894.1097684088456, 2248.4716091718515, 2299.1378233162563, 2303.8617682939075, 2307.1348244408346, 2004.0681083296265, 2217.6230040893006, 2293.392251053464, 2293.839431239814, 2306.8615883047073, 2232.497688020199, 2254.661800729887, 2264.354658922199, 2265.4223173090754, 2269.149069099096, 2240.865548067105, 2287.462446325129, 2288.0962020002007, 2293.839431239814, 2294.1855641247284, 8533.104024077444, 8616.0, 8622.482651040895, 8630.352723545697, 8630.741077767678, 4398.900274931268, 5253.86865328366, 5257.185136303879, 5263.973680131613, 5269.841904742825, 3301.96698033019, 3380.340833537461, 3483.878064267764, 3527.6295988921306, 3547.0600290922666, 1198.922070065443, 1214.15009493355, 1218.281214083695, 1230.4831970572382, 1254.9811752823714, 9276.628934842598, 9366.812663746756, 10965.671029869038, 11058.778824423547, 11064.446777661085, 4087.4890638670167, 4347.260965635849, 4399.318105693623, 4496.977515112403, 4499.167653984024, 8861.468311901306, 8870.15733505316, 8870.64517419296, 8871.778205544784, 8876.245519130816, 5048.091343558195, 5049.343585333883, 5052.898942021139, 5054.949450505522, 5060.3168572242275, 4119.876403707894, 4620.768961551909, 4663.0, 4754.334393184957, 4821.590164835958, 3602.9819850900576, 3632.8365223565, 3651.799151046665, 3697.279030589063, 3698.926021479609, 1066.0778426660881, 1142.9714257143637, 1160.8723040465543, 1163.4648061891626, 1165.1669056624385, 679.300320669711, 764.8087978005499, 836.7531578184451, 853.3557164340883, 911.3711539038043, 5499.642523236057, 5513.696746678971, 5658.603897727202, 5774.913376299359, 5826.708664566755, 925.5557332480447, 954.1841725324886, 979.8138353712772, 985.571276494727, 1021.9897801022043, 3593.2813437312643, 3733.4213196954515, 3785.413260944558, 3825.158465137665, 3837.0286753210294, 9919.851202231972, 10242.436665983443, 10243.38539687621, 10268.024537668909, 10270.589176433008, 9608.423494590346, 10025.949870250603, 10029.197664186848, 10183.287169898183, 10197.082262596401, 7737.032870891138, 7927.960360198162, 7943.642536086006, 7945.404094692921, 7962.601869906482, 5665.49010589049, 5666.0, 5686.488216060575, 5925.585209035326, 5998.970005149947, 9230.538473076454, 9299.0, 9392.81214375695, 9404.811903761749, 9433.669821556212, 8907.955460222658, 9236.861447078434, 9255.935567409833, 9376.906230937877, 9398.906010939803, 7125.180604230372, 7277.053982982178, 7277.490575659653, 7279.308465695751, 7284.417246620364, 7537.397008239218, 7783.610819459118, 7798.298153166243, 7817.687292508236, 7882.408819338458, 5711.771529138954, 7161.39128174111, 7199.8560028800175, 7262.527935684164, 7280.490365674564, 7080.150381954199, 7100.928990710234, 7174.784756457315, 7207.783766487014, 7284.198738138694, 6358.364163583605, 6388.872222555616, 6434.613923164625, 6442.549021568445, 6446.903296450463, 4870.707757534559, 4922.630802689741, 4925.753712314298, 5000.399952005782, 5146.125158722951, 4936.136176169228, 4974.005198960294, 5011.498850115106, 5173.430922598436, 5216.295800066942], "value": 4376.105479232973, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.8967804497673, 356.5204799544612, 359.1721082903907, 364.29327105415473, 368.4454895382449, 4719.90560188796, 5038.370203724535, 5129.1280482318, 5153.613478989077, 5170.02723434026, 4260.31834906415, 4484.5515448455135, 4708.6468514861435, 5023.120953833083, 5150.660828184671, 3764.548254209492, 3859.903502412434, 3927.5876033016552, 3945.993771588241, 3973.7814420207023, 3510.999365180924, 3899.3566061599804, 3903.302063602328, 3914.843406263746, 3924.7432726182415, 3892.746971446861, 3920.764754114748, 3928.776565192415, 3929.0, 3943.9211215775667, 3600.3879340512176, 3845.1732877431486, 3949.6050394960416, 3960.0, 3960.2871483133026, 2653.111207745388, 3376.8311584420926, 3392.525046493493, 3401.0953865834963, 3404.2000129969438, 4945.431275403303, 4982.789542836297, 5000.924801167766, 5026.924596131061, 5042.747862606855, 3939.763614183158, 4002.579729128429, 4131.091159944807, 4344.9348259776125, 4454.688171827974, 4513.27787553993, 5038.664753840235, 5143.562797162208, 5182.637215394924, 5415.023516416519, 3763.8870833875026, 3766.5875296763716, 3769.5853456119803, 3771.679407250387, 3774.8867533974026, 5137.460566640524, 5376.768641013975, 5565.050523222699, 5670.17263347239, 5704.80033198836, 3219.9195020124216, 3255.4140254753897, 3266.4283750343593, 3699.7040236780995, 3774.6971400024977, 3822.967798694337, 3834.4631751554803, 3835.463035175078, 3835.884923452301, 3841.635044670752, 4124.8143833527565, 4275.935860962088, 4383.07955329378, 4423.987085875919, 4454.5990860822685, 4878.170710979142, 5396.62223644349, 5422.671445495879, 5432.619716619878, 5446.482584154499, 1217.5129948020863, 1239.324568110382, 1249.3378509390038, 1249.5564074753331, 1260.5840072775961, 786.4534148766552, 827.5737994932615, 843.4644001059371, 846.7375113714645, 854.474498183625, 4178.791060446965, 4758.881662809237, 4765.475797662254, 4772.832950846704, 4775.379200703886, 4811.927821082687, 4826.886658105503, 5261.868453288698, 5278.577713782888, 5335.6531825432, 4322.200392927319, 4523.977380113079, 4562.8820938870185, 4806.158922188603, 4817.0, 2421.1646981791255, 2463.95072098557, 2524.8232623716162, 2776.5835124731047, 2815.211740712604, 4947.851564453073, 5342.672435327398, 5445.237666726662, 5512.724363781875, 5565.443455654402, 4447.643468742, 4639.86080417588, 4644.724085198296, 4807.687500312469, 5460.563154947595, 4692.662591161503, 4744.074905393484, 4820.590249828733, 5455.699936503531, 5507.228987941691, 4441.95614030709, 5078.873028174326, 5290.9735451322495, 5311.946880531145, 5377.892442151213, 4664.463586687439, 4991.825286114969, 5152.763336799217, 5211.1401618734, 5507.0, 4803.133477550606, 4854.888123666216, 4885.95114048855, 5455.509004189642, 5805.168588731417, 3663.4905090013426, 3697.3899306614107, 3701.722370822253, 3805.200907809391, 3811.5426148862316, 5049.343585333883, 5597.880423915151, 5617.550595952396, 5646.35066967304, 5677.51741102011, 4217.451731274915, 4920.778564964514, 5340.572754179734, 5403.081476149143, 5496.422875598005, 4590.977045114753, 5420.783168673209, 5422.322209723785, 5435.456454354533, 5494.313210848644, 5301.416844147064, 5332.413434522277, 5343.038253114321, 5353.170258609922, 5368.973155134356, 4575.450945886515, 5068.313793689351, 5409.810656626999, 5424.294841670716, 5460.945390546202, 4475.7985890634345, 5343.225232341367, 5350.866228344323, 5361.51746342831, 5375.763574377993], "value": 3888.1764422630117, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.222938652789, 717.9389751871093, 754.486948874765, 806.1132753970631, 818.116434251009, 13845.58463246103, 14438.205898675582, 14439.494617688382, 14463.0, 14473.782893256597, 12982.935085324583, 13329.600111996631, 13428.395722192501, 13431.0, 13447.193168409878, 647.9578827376229, 663.5620490476263, 848.088305072047, 858.6522458404356, 865.2256230673574, 861.2162931732125, 865.03548543374, 865.2040123086775, 865.948043117415, 868.3313848336777, 918.3020904112889, 920.9493477858715, 921.5898924978354, 922.7739203895055, 924.5423515359872, 916.2120576304372, 916.5692124701413, 916.734147097341, 917.5274733512247, 917.655879045358, 683.4395795447734, 684.4524380495633, 684.7740245718901, 685.3146853146853, 687.6046273392819, 12149.08881833866, 12417.875821241852, 12595.937020314932, 12660.366981650881, 12778.041646876522, 11599.94200029003, 11737.354445505494, 11759.529618815322, 11910.0, 12283.01735861138, 10501.474926253657, 10695.251332406735, 10776.67669969902, 10946.890531094667, 11050.226484146113, 3469.288795796881, 3592.604813470516, 3659.048647351681, 3671.6144804795645, 3718.2935242304147, 65072.0, 81446.77829832557, 86715.0, 86901.0, 94220.0, 10250.692479225636, 10419.791604167875, 10479.690038745157, 10601.098906592872, 10852.72868178286, 18529.258829646933, 20868.165273389197, 21167.15331386727, 21208.15167393287, 21218.939053047285, 7188.101487314086, 7209.711611535585, 7231.132264128339, 7288.9271107289305, 7484.989526413864, 4057.8376864925663, 4142.502899652061, 4146.150039241948, 4147.108371700038, 4150.27370210212, 3492.4237500812587, 3560.3947328953623, 3579.463080537888, 3584.820758962094, 3589.7666651668083, 660.8909529927604, 661.7551505942832, 663.5189487621436, 679.3681875855403, 679.8266442057343, 540.5351397797891, 543.8422857371397, 615.2954866677629, 638.4381744065192, 639.6961443314482, 9102.284794129251, 9920.62901136213, 10021.830025526708, 10217.489125543692, 10501.527431265458, 562.443181250559, 568.5224411494282, 574.5949105880344, 639.7536948274948, 708.8334241453255, 4593.724376537419, 4926.941070602882, 4959.79812473717, 4961.107000739829, 4982.978489409735, 76669.7328427449, 91331.0, 96133.51933240429, 96604.0, 97857.51071244598, 84113.8034984936, 85547.57226213829, 85732.57133714292, 87946.56026719953, 89166.0, 73423.63288183525, 77268.84096738553, 78131.43737125225, 79385.0, 79415.0, 9194.95402522983, 9490.478023708762, 9565.282603804604, 9572.681935261886, 9630.855537167088, 48182.518174819204, 49324.0, 49373.0, 49432.76418089576, 50290.74854625703, 62310.37689623227, 64679.0, 64998.35001650112, 65353.67323163354, 66359.66820166062, 11301.604443844426, 11477.081833453136, 11504.769904602028, 11697.88302117002, 11942.940285298519, 12362.876371236172, 12517.374131293216, 12556.058295627681, 12924.935375323063, 13024.609261722164, 11712.941435292769, 11823.586174483851, 11902.837240401997, 11910.642680719593, 12408.19346742495, 11769.17615766888, 11871.584494542649, 11889.583864564698, 11938.76122477563, 12039.277643341427, 10469.738256543647, 10483.318584291997, 10483.475826208507, 10562.0, 11031.613893513688, 9775.462349570558, 9926.15627671656, 10312.793744125225, 10398.792024159324, 10420.218483613911, 7885.369170466234, 8294.336453083859, 8896.555172241491, 9251.398659087137, 9363.861595904264], "value": 7007.946056199123, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1469.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 115.0, 114.0, 115.0, 119.0, 161.0, 164.0, 114.0], "value": 124.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 112.0, 171.0, 138.0, 111.0, 109.0, 110.0, 132.0, 109.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 185.0, 183.0, 184.0, 184.0, 184.0, 185.0, 184.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 89.0, 60.0, 59.0, 58.0, 59.0, 85.0, 82.0], "value": 68.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 72.0, 86.0, 71.0, 90.0, 100.0, 119.0, 77.0, 70.0, 83.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 89.0, 88.0, 116.0, 87.0, 91.0, 88.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 94.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 88.0, 86.0, 88.0, 86.0, 88.0, 88.0, 85.0, 86.0], "value": 86.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 58.0, 57.0, 59.0, 59.0, 58.0, 59.0, 58.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 72.0, 75.0, 72.0, 67.0, 68.0, 67.0, 69.0, 69.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 86.0, 85.0, 95.0, 97.0, 86.0, 90.0, 88.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 140.0, 141.0, 139.0, 139.0, 140.0, 139.0, 186.0, 173.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 172.0, 173.0, 174.0, 173.0, 171.0, 173.0, 174.0, 170.0, 181.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 66.0, 71.0, 86.0, 83.0, 63.0, 58.0, 80.0], "value": 70.9, "unit": "score"}]}]} 19:23:01 INFO - Return code: 0 19:23:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:23:02 ERROR - # TBPL SUCCESS # 19:23:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:23:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:23:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:23:02 INFO - 2017-02-05 19:23:02.068 screenresolution[1661:10474] starting screenresolution argv=screenresolution get 19:23:02 INFO - 2017-02-05 19:23:02.071 screenresolution[1661:10474] Display 0: 1600x1200x32@60 19:23:02 INFO - 2017-02-05 19:23:02.077 screenresolution[1662:10477] starting screenresolution argv=screenresolution list 19:23:02 INFO - Available Modes on Display 0 19:23:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:23:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:23:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:23:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:23:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:23:02 INFO - Intel Iris: 19:23:02 INFO - Chipset Model: Intel Iris 19:23:02 INFO - Type: GPU 19:23:02 INFO - Bus: Built-In 19:23:02 INFO - VRAM (Dynamic, Max): 1536 MB 19:23:02 INFO - Vendor: Intel (0x8086) 19:23:02 INFO - Device ID: 0x0a2e 19:23:02 INFO - Revision ID: 0x0009 19:23:02 INFO - Displays: 19:23:02 INFO - Display: 19:23:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:23:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:23:02 INFO - Display Serial Number: 1600x1200 60 19:23:02 INFO - Main Display: Yes 19:23:02 INFO - Mirror: Off 19:23:02 INFO - Online: Yes 19:23:02 INFO - Rotation: Supported 19:23:02 INFO - Return code: 0 19:23:02 INFO - Running post-action listener: _resource_record_post_action 19:23:02 INFO - [mozharness: 2017-02-06 03:23:02.323947Z] Finished run-tests step (success) 19:23:02 INFO - Running post-run listener: _resource_record_post_run 19:23:02 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32558080; Write bytes: 554671616; Read time: 194; Write time: 1655 19:23:02 INFO - TinderboxPrint: CPU usage
53.8% 19:23:02 INFO - TinderboxPrint: I/O read bytes / time
32,558,080 / 194 19:23:02 INFO - TinderboxPrint: I/O write bytes / time
554,671,616 / 1,655 19:23:02 INFO - TinderboxPrint: CPU idle
2,222.6 (46.2%) 19:23:02 INFO - TinderboxPrint: CPU system
961.5 (20.0%) 19:23:02 INFO - TinderboxPrint: CPU user
1,625.6 (33.8%) 19:23:02 INFO - TinderboxPrint: Swap in / out
280,743,936 / 0 19:23:02 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 241984000; Write bytes: 237565952; Read time: 17913; Write time: 497 19:23:02 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30768128; Write bytes: 297780736; Read time: 180; Write time: 1110 19:23:02 INFO - Running post-run listener: _upload_blobber_files 19:23:02 INFO - Blob upload gear active. 19:23:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:23:02 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 19:23:02 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'] 19:23:02 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 19:23:03 INFO - (blobuploader) - INFO - Open directory for files ... 19:23:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:23:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:23:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:04 INFO - (blobuploader) - INFO - Done attempting. 19:23:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:23:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:23:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:05 INFO - (blobuploader) - INFO - Done attempting. 19:23:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:23:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:23:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:06 INFO - (blobuploader) - INFO - Done attempting. 19:23:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:23:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:23:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:08 INFO - (blobuploader) - INFO - Done attempting. 19:23:08 INFO - (blobuploader) - INFO - Iteration through files over. 19:23:08 INFO - Return code: 0 19:23:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:23:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:23:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/379d79e446ecdf78a6c8d28bd0e5344bed4c9f6b530b532ee66401db353a908b57487141dbd405e8ab91a67a72092b1e93c53aa0fab9d873009358bfbeb18a2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac72c2806e0c0ff567479f4c7c79fe97ae64dc755a0684caa62e1b2acebb74df04fbff5992501a2e081b496afb26c381f2731c6f268f9dedaf8e6e81a079c1ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/519aa4eb5e36ae82eefc03c38c0292ea6b38e4f2285995bb30837899bfc77ff40cfd5484cfa06d52927f227be5ca3ef67a51ec2e9d7aa62cf37d1fa59d5a20a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d7f5bd2175cc4c84be9c31901f75f383e7bce320f267a34e4e37c23c96807de6398a44ed3e302145ce6a8f9243dae846b29c5aa9ce559a868ead6dc2e3dbe1d"} 19:23:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:23:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:23:08 INFO - Contents: 19:23:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/379d79e446ecdf78a6c8d28bd0e5344bed4c9f6b530b532ee66401db353a908b57487141dbd405e8ab91a67a72092b1e93c53aa0fab9d873009358bfbeb18a2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac72c2806e0c0ff567479f4c7c79fe97ae64dc755a0684caa62e1b2acebb74df04fbff5992501a2e081b496afb26c381f2731c6f268f9dedaf8e6e81a079c1ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/519aa4eb5e36ae82eefc03c38c0292ea6b38e4f2285995bb30837899bfc77ff40cfd5484cfa06d52927f227be5ca3ef67a51ec2e9d7aa62cf37d1fa59d5a20a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d7f5bd2175cc4c84be9c31901f75f383e7bce320f267a34e4e37c23c96807de6398a44ed3e302145ce6a8f9243dae846b29c5aa9ce559a868ead6dc2e3dbe1d"} 19:23:08 INFO - Running post-run listener: copy_logs_to_upload_dir 19:23:08 INFO - Copying logs to upload dir... 19:23:08 INFO - mkdir: /builds/slave/test/build/upload/logs 19:23:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.924586 ========= master_lag: 0.70 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-02-05 19:23:09.019785) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 19:23:09.024600) ========= 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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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/379d79e446ecdf78a6c8d28bd0e5344bed4c9f6b530b532ee66401db353a908b57487141dbd405e8ab91a67a72092b1e93c53aa0fab9d873009358bfbeb18a2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac72c2806e0c0ff567479f4c7c79fe97ae64dc755a0684caa62e1b2acebb74df04fbff5992501a2e081b496afb26c381f2731c6f268f9dedaf8e6e81a079c1ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/519aa4eb5e36ae82eefc03c38c0292ea6b38e4f2285995bb30837899bfc77ff40cfd5484cfa06d52927f227be5ca3ef67a51ec2e9d7aa62cf37d1fa59d5a20a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d7f5bd2175cc4c84be9c31901f75f383e7bce320f267a34e4e37c23c96807de6398a44ed3e302145ce6a8f9243dae846b29c5aa9ce559a868ead6dc2e3dbe1d"} build_url:https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011232 build_url: 'https://queue.taskcluster.net/v1/task/ITQJl9t9R_6L_rqO_CFbsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/379d79e446ecdf78a6c8d28bd0e5344bed4c9f6b530b532ee66401db353a908b57487141dbd405e8ab91a67a72092b1e93c53aa0fab9d873009358bfbeb18a2b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac72c2806e0c0ff567479f4c7c79fe97ae64dc755a0684caa62e1b2acebb74df04fbff5992501a2e081b496afb26c381f2731c6f268f9dedaf8e6e81a079c1ab", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/519aa4eb5e36ae82eefc03c38c0292ea6b38e4f2285995bb30837899bfc77ff40cfd5484cfa06d52927f227be5ca3ef67a51ec2e9d7aa62cf37d1fa59d5a20a4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7d7f5bd2175cc4c84be9c31901f75f383e7bce320f267a34e4e37c23c96807de6398a44ed3e302145ce6a8f9243dae846b29c5aa9ce559a868ead6dc2e3dbe1d"}' ========= master_lag: 0.09 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-05 19:23:09.128922) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 19:23:09.129266) ========= 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.GYYH5FpOg1/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.Hbkw9qU1aQ/Listeners TMPDIR=/var/folders/kk/d_yy2jqj1p3cq7ckfwf2mf2800000w/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.005121 ========= master_lag: 0.33 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-05 19:23:09.466304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 19:23:09.466642) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-05 19:23:09.473313) ========= ========= Total master_lag: 1.26 =========