builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0120 starttime: 1488276576.87 results: success (0) buildid: 20170228004003 builduid: 196f77ab21954befb352863738fa2e4e revision: 66e9dc9f094f790c94c7f2a5df0757c8f3b81e78 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-28 02:09:36.865754) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-28 02:09:36.866501) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2017-02-28 02:09:36.866960) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2017-02-28 02:09:41.430791) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-28 02:09:41.431108) ========= 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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-28 02:09:41-- 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% 8.03M=0.001s 2017-02-28 02:09:42 (8.03 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.553139 ========= master_lag: 0.95 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-28 02:09:42.937015) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-28 02:09:42.937317) ========= 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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.020062 ========= master_lag: 0.71 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-28 02:09:43.671063) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-02-28 02:09:43.671450) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 66e9dc9f094f790c94c7f2a5df0757c8f3b81e78 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 66e9dc9f094f790c94c7f2a5df0757c8f3b81e78 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-28 02:09:43,757 truncating revision to first 12 chars 2017-02-28 02:09:43,757 Setting DEBUG logging. 2017-02-28 02:09:43,757 attempt 1/10 2017-02-28 02:09:43,757 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/66e9dc9f094f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-28 02:09:47,015 unpacking tar archive at: mozilla-aurora-66e9dc9f094f/testing/mozharness/ program finished with exit code 0 elapsedTime=3.982840 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-02-28 02:09:47.682659) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-28 02:09:47.683229) ========= script_repo_revision: 66e9dc9f094f790c94c7f2a5df0757c8f3b81e78 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-28 02:09:47.683795) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-28 02:09:47.684129) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-28 02:09:47.704575) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 30 mins, 7 secs) (at 2017-02-28 02:09:47.704918) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:09:47 INFO - MultiFileLogger online at 20170228 02:09:47 in /builds/slave/test 02:09:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:09:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:09:47 INFO - {'append_to_log': False, 02:09:47 INFO - 'base_work_dir': '/builds/slave/test', 02:09:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:09:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:09:47 INFO - 'branch': 'Mozilla-Aurora', 02:09:47 INFO - 'buildbot_json_path': 'buildprops.json', 02:09:47 INFO - 'config_files': ('talos/mac_config.py',), 02:09:47 INFO - 'default_actions': ('clobber', 02:09:47 INFO - 'read-buildbot-config', 02:09:47 INFO - 'download-and-extract', 02:09:47 INFO - 'populate-webroot', 02:09:47 INFO - 'create-virtualenv', 02:09:47 INFO - 'install', 02:09:47 INFO - 'run-tests'), 02:09:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:09:47 INFO - 'download_minidump_stackwalk': True, 02:09:47 INFO - 'download_symbols': 'ondemand', 02:09:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:09:47 INFO - 'tooltool.py': '/tools/tooltool.py', 02:09:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:09:47 INFO - '/tools/misc-python/virtualenv.py')}, 02:09:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:09:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:09:47 INFO - 'installer_path': 'installer.exe', 02:09:47 INFO - 'log_level': 'info', 02:09:47 INFO - 'log_name': 'talos', 02:09:47 INFO - 'log_to_console': True, 02:09:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:09:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:09:47 INFO - 'opt_config_files': (), 02:09:47 INFO - 'pip_index': False, 02:09:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:09:47 INFO - 'cmd': ('bash', 02:09:47 INFO - '-c', 02:09:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:09:47 INFO - 'enabled': True, 02:09:47 INFO - 'halt_on_failure': False, 02:09:47 INFO - 'name': 'check_screen_resolution'},), 02:09:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:09:47 INFO - 'cmd': ('bash', 02:09:47 INFO - '-c', 02:09:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:09:47 INFO - 'enabled': True, 02:09:47 INFO - 'halt_on_failure': False, 02:09:47 INFO - 'name': 'check_screen_resolution'},), 02:09:47 INFO - 'run_cmd_checks_enabled': True, 02:09:47 INFO - 'sps_profile': False, 02:09:47 INFO - 'sps_profile_interval': 0, 02:09:47 INFO - 'suite': 'dromaeojs', 02:09:47 INFO - 'system_bits': '32', 02:09:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:09:47 INFO - 'title': 't-yosemite-r7-0120', 02:09:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:09:47 INFO - 'use_talos_json': True, 02:09:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:09:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:09:47 INFO - 'work_dir': 'build'} 02:09:47 INFO - [mozharness: 2017-02-28 10:09:47.918572Z] Running clobber step. 02:09:47 INFO - Running pre-action listener: _resource_record_pre_action 02:09:47 INFO - Running main action method: clobber 02:09:47 INFO - rmtree: /builds/slave/test/build 02:09:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:09:49 INFO - Running post-action listener: _resource_record_post_action 02:09:49 INFO - [mozharness: 2017-02-28 10:09:49.596891Z] Finished clobber step (success) 02:09:49 INFO - [mozharness: 2017-02-28 10:09:49.597020Z] Running read-buildbot-config step. 02:09:49 INFO - Running pre-action listener: _resource_record_pre_action 02:09:49 INFO - Running main action method: read_buildbot_config 02:09:49 INFO - Using buildbot properties: 02:09:49 INFO - { 02:09:49 INFO - "project": "", 02:09:49 INFO - "product": "firefox", 02:09:49 INFO - "script_repo_revision": "production", 02:09:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:09:49 INFO - "repository": "", 02:09:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:09:49 INFO - "buildid": "20170228004003", 02:09:49 INFO - "stage_platform": "macosx64", 02:09:49 INFO - "basedir": "/builds/slave/test", 02:09:49 INFO - "nightly_build": "True", 02:09:49 INFO - "buildnumber": 6, 02:09:49 INFO - "slavename": "t-yosemite-r7-0120", 02:09:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:09:49 INFO - "platform": "yosemite_r7", 02:09:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:09:49 INFO - "branch": "mozilla-aurora", 02:09:49 INFO - "repo_path": "releases/mozilla-aurora", 02:09:49 INFO - "revision": "66e9dc9f094f790c94c7f2a5df0757c8f3b81e78", 02:09:49 INFO - "pgo_build": "False", 02:09:49 INFO - "builduid": "196f77ab21954befb352863738fa2e4e", 02:09:49 INFO - "slavebuilddir": "test" 02:09:49 INFO - } 02:09:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 02:09:49 INFO - Running post-action listener: _resource_record_post_action 02:09:49 INFO - [mozharness: 2017-02-28 10:09:49.600423Z] Finished read-buildbot-config step (success) 02:09:49 INFO - [mozharness: 2017-02-28 10:09:49.600556Z] Running download-and-extract step. 02:09:49 INFO - Running pre-action listener: _resource_record_pre_action 02:09:49 INFO - Running main action method: download_and_extract 02:09:49 INFO - mkdir: /builds/slave/test/build/tests 02:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:09:49 INFO - trying https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 02:09:49 INFO - Downloading https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:09:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 02:09:51 INFO - Downloaded 1199 bytes. 02:09:51 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 02:09:51 INFO - Using the following test package requirements: 02:09:51 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:09:51 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:09:51 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 02:09:51 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:09:51 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 02:09:51 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:09:51 INFO - u'jsshell-mac.zip'], 02:09:51 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:09:51 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 02:09:51 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 02:09:51 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:09:51 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 02:09:51 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:09:51 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 02:09:51 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:09:51 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 02:09:51 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 02:09:51 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 02:09:51 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:09:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:09:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:09:51 INFO - Fetch https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:09:53 INFO - Expected file size: 16357879 02:09:53 INFO - Obtained file size: 16357879 02:10:00 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:10:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 02:10:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:10:00 INFO - Fetch https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:10:32 INFO - retry: attempt #1 caught exception: '' 02:10:32 INFO - retry: Failed, sleeping 60 seconds before retrying 02:11:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #2 02:11:32 INFO - Fetch https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:12:07 INFO - retry: attempt #2 caught exception: '' 02:12:07 INFO - retry: Failed, sleeping 120 seconds before retrying 02:14:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #3 02:14:07 INFO - Fetch https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:14:43 INFO - retry: attempt #3 caught exception: 02:14:43 INFO - retry: Failed, sleeping 240 seconds before retrying 02:18:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #4 02:18:43 INFO - Fetch https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 02:18:45 INFO - Expected file size: 16357879 02:18:45 INFO - Obtained file size: 16357879 02:18:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 02:18:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:18:52 INFO - Fetch https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 02:18:54 INFO - Expected file size: 14403472 02:18:54 INFO - Obtained file size: 14403472 02:18:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:18:54 INFO - trying https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:18:54 INFO - Downloading https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 02:18:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 02:18:58 INFO - Downloaded 74038828 bytes. 02:18:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:18:58 INFO - mkdir: /builds/slave/test/properties 02:18:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:18:58 INFO - Writing to file /builds/slave/test/properties/build_url 02:18:58 INFO - Contents: 02:18:58 INFO - build_url:https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 02:18:59 INFO - Running post-action listener: _resource_record_post_action 02:18:59 INFO - Running post-action listener: set_extra_try_arguments 02:18:59 INFO - [mozharness: 2017-02-28 10:18:59.663504Z] Finished download-and-extract step (success) 02:18:59 INFO - [mozharness: 2017-02-28 10:18:59.663650Z] Running populate-webroot step. 02:18:59 INFO - Running pre-action listener: _resource_record_pre_action 02:18:59 INFO - Running main action method: populate_webroot 02:18:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:18:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:18:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:18:59 INFO - '--tppagecycles', 02:18:59 INFO - '7'], 02:18:59 INFO - 'tests': ['tsvgm']}}, 02:18:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:18:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:18:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:18:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:18:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:18:59 INFO - 'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:18:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:18:59 INFO - 'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['damp', 'tps']}, 02:18:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:18:59 INFO - 'tests': ['damp', 'tps']}, 02:18:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['dromaeo_dom']}, 02:18:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:18:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['basic_compositor_video']}, 02:18:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:18:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['a11yr', 02:18:59 INFO - 'ts_paint', 02:18:59 INFO - 'tpaint', 02:18:59 INFO - 'sessionrestore', 02:18:59 INFO - 'sessionrestore_no_auto_restore', 02:18:59 INFO - 'tabpaint']}, 02:18:59 INFO - 'other-e10s': {'tests': ['a11yr', 02:18:59 INFO - 'ts_paint', 02:18:59 INFO - 'tpaint', 02:18:59 INFO - 'sessionrestore', 02:18:59 INFO - 'sessionrestore_no_auto_restore', 02:18:59 INFO - 'tabpaint']}, 02:18:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:18:59 INFO - 'ts_paint', 02:18:59 INFO - 'tpaint', 02:18:59 INFO - 'sessionrestore', 02:18:59 INFO - 'sessionrestore_no_auto_restore', 02:18:59 INFO - 'tabpaint']}, 02:18:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:18:59 INFO - 'ts_paint', 02:18:59 INFO - 'tpaint', 02:18:59 INFO - 'sessionrestore', 02:18:59 INFO - 'sessionrestore_no_auto_restore', 02:18:59 INFO - 'tabpaint']}, 02:18:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['a11yr', 02:18:59 INFO - 'ts_paint', 02:18:59 INFO - 'tpaint', 02:18:59 INFO - 'sessionrestore', 02:18:59 INFO - 'sessionrestore_no_auto_restore', 02:18:59 INFO - 'tabpaint']}, 02:18:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['a11yr', 02:18:59 INFO - 'ts_paint', 02:18:59 INFO - 'tpaint', 02:18:59 INFO - 'sessionrestore', 02:18:59 INFO - 'sessionrestore_no_auto_restore', 02:18:59 INFO - 'tabpaint']}, 02:18:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['tsvgx', 02:18:59 INFO - 'tsvgr_opacity', 02:18:59 INFO - 'tart', 02:18:59 INFO - 'tscrollx', 02:18:59 INFO - 'cart', 02:18:59 INFO - 'tsvg_static']}, 02:18:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:18:59 INFO - 'tsvgr_opacity', 02:18:59 INFO - 'tart', 02:18:59 INFO - 'tscrollx', 02:18:59 INFO - 'cart', 02:18:59 INFO - 'tsvg_static']}, 02:18:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:18:59 INFO - 'talos_options': ['--disable-e10s'], 02:18:59 INFO - 'tests': ['tp5o']}, 02:18:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:18:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:18:59 INFO - 'talos_options': ['--disable-e10s', 02:18:59 INFO - '--xperf_path', 02:18:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:59 INFO - 'tests': ['tp5n']}, 02:18:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:18:59 INFO - 'talos_options': ['--xperf_path', 02:18:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:59 INFO - 'tests': ['tp5n']}}, 02:18:59 INFO - 'talos.zip': {'path': '', 02:18:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:18:59 INFO - Running post-action listener: _resource_record_post_action 02:18:59 INFO - [mozharness: 2017-02-28 10:18:59.682131Z] Finished populate-webroot step (success) 02:18:59 INFO - [mozharness: 2017-02-28 10:18:59.682242Z] Running create-virtualenv step. 02:18:59 INFO - Running pre-action listener: _resource_record_pre_action 02:18:59 INFO - Running main action method: create_virtualenv 02:18:59 INFO - Creating virtualenv /builds/slave/test/build/venv 02:18:59 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 02:18:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:18:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:18:59 INFO - Using real prefix '/tools/python27' 02:18:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:19:00 INFO - Installing distribute.............................................................................................................................................................................................done. 02:19:03 INFO - Installing pip.................done. 02:19:03 INFO - Return code: 0 02:19:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:19:03 INFO - Reading from file tmpfile_stdout 02:19:03 INFO - Output received: 02:19:03 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) 02:19:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:04 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:04 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 02:19:04 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 02:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:04 INFO - 'HOME': '/Users/cltbld', 02:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:04 INFO - 'LOGNAME': 'cltbld', 02:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:04 INFO - 'MOZ_NO_REMOTE': '1', 02:19:04 INFO - 'NO_EM_RESTART': '1', 02:19:04 INFO - 'PAGER': '/bin/cat', 02:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:04 INFO - 'PWD': '/builds/slave/test', 02:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:04 INFO - 'SHELL': '/bin/bash', 02:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:04 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:04 INFO - 'USER': 'cltbld', 02:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:04 INFO - 'XPC_FLAGS': '0x0', 02:19:04 INFO - 'XPC_SERVICE_NAME': '0', 02:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:04 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 02:19:04 INFO - Cleaning up... 02:19:04 INFO - Return code: 0 02:19:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:04 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:04 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 02:19:04 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 02:19:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:04 INFO - 'HOME': '/Users/cltbld', 02:19:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:04 INFO - 'LOGNAME': 'cltbld', 02:19:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:04 INFO - 'MOZ_NO_REMOTE': '1', 02:19:04 INFO - 'NO_EM_RESTART': '1', 02:19:04 INFO - 'PAGER': '/bin/cat', 02:19:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:04 INFO - 'PWD': '/builds/slave/test', 02:19:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:04 INFO - 'SHELL': '/bin/bash', 02:19:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:04 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:04 INFO - 'USER': 'cltbld', 02:19:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:04 INFO - 'XPC_FLAGS': '0x0', 02:19:04 INFO - 'XPC_SERVICE_NAME': '0', 02:19:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:04 INFO - Downloading/unpacking psutil>=3.1.1 02:19:04 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 02:19:04 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 02:19:04 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 02:19:04 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 02:19:04 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 02:19:04 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 02:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:19:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:19:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:19:13 INFO - Installing collected packages: psutil 02:19:13 INFO - Running setup.py install for psutil 02:19:13 INFO - building 'psutil._psutil_osx' extension 02:19:13 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 02:19:14 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 02:19:14 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 02:19:14 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 02:19:14 INFO - building 'psutil._psutil_posix' extension 02:19:14 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 02:19:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:19:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:19:14 INFO - ^ 02:19:14 INFO - 1 warning generated. 02:19:14 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 02:19:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:19:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:19:14 INFO - Successfully installed psutil 02:19:14 INFO - Cleaning up... 02:19:14 INFO - Return code: 0 02:19:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:14 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:14 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 02:19:14 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 02:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:14 INFO - 'HOME': '/Users/cltbld', 02:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:14 INFO - 'LOGNAME': 'cltbld', 02:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:14 INFO - 'MOZ_NO_REMOTE': '1', 02:19:14 INFO - 'NO_EM_RESTART': '1', 02:19:14 INFO - 'PAGER': '/bin/cat', 02:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:14 INFO - 'PWD': '/builds/slave/test', 02:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:14 INFO - 'SHELL': '/bin/bash', 02:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:14 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:14 INFO - 'USER': 'cltbld', 02:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:14 INFO - 'XPC_FLAGS': '0x0', 02:19:14 INFO - 'XPC_SERVICE_NAME': '0', 02:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:19:14 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 02:19:14 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 02:19:14 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 02:19:14 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 02:19:14 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 02:19:14 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 02:19:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:19:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:19:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:19:19 INFO - Installing collected packages: mozsystemmonitor 02:19:19 INFO - Running setup.py install for mozsystemmonitor 02:19:19 INFO - Successfully installed mozsystemmonitor 02:19:19 INFO - Cleaning up... 02:19:19 INFO - Return code: 0 02:19:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:19:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:19 INFO - 'HOME': '/Users/cltbld', 02:19:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:19 INFO - 'LOGNAME': 'cltbld', 02:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:19 INFO - 'MOZ_NO_REMOTE': '1', 02:19:19 INFO - 'NO_EM_RESTART': '1', 02:19:19 INFO - 'PAGER': '/bin/cat', 02:19:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:19 INFO - 'PWD': '/builds/slave/test', 02:19:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:19 INFO - 'SHELL': '/bin/bash', 02:19:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:19 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:19 INFO - 'USER': 'cltbld', 02:19:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:19 INFO - 'XPC_FLAGS': '0x0', 02:19:19 INFO - 'XPC_SERVICE_NAME': '0', 02:19:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:20 INFO - Downloading/unpacking jsonschema==2.5.1 02:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:19:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:19:25 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 02:19:25 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 02:19:25 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 02:19:25 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 02:19:25 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 02:19:25 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 02:19:25 INFO - Downloading functools32-3.2.3-2.tar.gz 02:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:19:25 INFO - warning: no files found matching '*.txt' 02:19:25 INFO - no previously-included directories found matching 'build' 02:19:25 INFO - no previously-included directories found matching 'dist' 02:19:25 INFO - no previously-included directories found matching '.git*' 02:19:25 INFO - Installing collected packages: jsonschema, functools32 02:19:25 INFO - Running setup.py install for functools32 02:19:25 INFO - warning: no files found matching '*.txt' 02:19:25 INFO - no previously-included directories found matching 'build' 02:19:25 INFO - no previously-included directories found matching 'dist' 02:19:25 INFO - no previously-included directories found matching '.git*' 02:19:25 INFO - Successfully installed jsonschema functools32 02:19:25 INFO - Cleaning up... 02:19:25 INFO - Return code: 0 02:19:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:19:25 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')]} 02:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:25 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')]} 02:19:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:25 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:25 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 02:19:25 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 02:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:25 INFO - 'HOME': '/Users/cltbld', 02:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:25 INFO - 'LOGNAME': 'cltbld', 02:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:25 INFO - 'MOZ_NO_REMOTE': '1', 02:19:25 INFO - 'NO_EM_RESTART': '1', 02:19:25 INFO - 'PAGER': '/bin/cat', 02:19:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:25 INFO - 'PWD': '/builds/slave/test', 02:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:26 INFO - 'SHELL': '/bin/bash', 02:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:26 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:26 INFO - 'USER': 'cltbld', 02:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:26 INFO - 'XPC_FLAGS': '0x0', 02:19:26 INFO - 'XPC_SERVICE_NAME': '0', 02:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:19:26 INFO - Cleaning up... 02:19:26 INFO - Return code: 0 02:19:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:26 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:26 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 02:19:26 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 02:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:26 INFO - 'HOME': '/Users/cltbld', 02:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:26 INFO - 'LOGNAME': 'cltbld', 02:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:26 INFO - 'MOZ_NO_REMOTE': '1', 02:19:26 INFO - 'NO_EM_RESTART': '1', 02:19:26 INFO - 'PAGER': '/bin/cat', 02:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:26 INFO - 'PWD': '/builds/slave/test', 02:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:26 INFO - 'SHELL': '/bin/bash', 02:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:26 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:26 INFO - 'USER': 'cltbld', 02:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:26 INFO - 'XPC_FLAGS': '0x0', 02:19:26 INFO - 'XPC_SERVICE_NAME': '0', 02:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:26 INFO - Downloading/unpacking blobuploader==1.2.4 02:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:19:32 INFO - Downloading blobuploader-1.2.4.tar.gz 02:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:19:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:19:32 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 02:19:32 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 02:19:32 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 02:19:32 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 02:19:32 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 02:19:32 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 02:19:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:19:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:19:35 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 02:19:35 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 02:19:35 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 02:19:35 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 02:19:35 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 02:19:35 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 02:19:36 INFO - Downloading docopt-0.6.1.tar.gz 02:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:19:36 INFO - Installing collected packages: blobuploader, requests, docopt 02:19:36 INFO - Running setup.py install for blobuploader 02:19:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:19:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:19:36 INFO - Running setup.py install for requests 02:19:37 INFO - Running setup.py install for docopt 02:19:37 INFO - Successfully installed blobuploader requests docopt 02:19:37 INFO - Cleaning up... 02:19:37 INFO - Return code: 0 02:19:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:37 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:37 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 02:19:37 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 02:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:37 INFO - 'HOME': '/Users/cltbld', 02:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:37 INFO - 'LOGNAME': 'cltbld', 02:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:37 INFO - 'MOZ_NO_REMOTE': '1', 02:19:37 INFO - 'NO_EM_RESTART': '1', 02:19:37 INFO - 'PAGER': '/bin/cat', 02:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:37 INFO - 'PWD': '/builds/slave/test', 02:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:37 INFO - 'SHELL': '/bin/bash', 02:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:37 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:37 INFO - 'USER': 'cltbld', 02:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:37 INFO - 'XPC_FLAGS': '0x0', 02:19:37 INFO - 'XPC_SERVICE_NAME': '0', 02:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:19:37 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-WVjrKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:19:37 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-f7KHQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:19:37 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-LWh7XB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-cVD4o_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-wN66re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vSEGsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-QA8o1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-kvRZXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ytJf6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ip6JBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-b8mfkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:19:38 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-kVdfVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:19:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-8G6iHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:19:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-5MNVLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-c9b_YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:19:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-2WPjBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:19:39 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-IvYbLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:19:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:19:39 INFO - Running setup.py install for manifestparser 02:19:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:19:39 INFO - Running setup.py install for mozcrash 02:19:39 INFO - Running setup.py install for mozdebug 02:19:39 INFO - Running setup.py install for mozdevice 02:19:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:19:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:19:40 INFO - Running setup.py install for mozfile 02:19:40 INFO - Running setup.py install for mozhttpd 02:19:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:19:40 INFO - Running setup.py install for mozinfo 02:19:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:19:40 INFO - Running setup.py install for mozInstall 02:19:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:19:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:19:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:19:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:19:40 INFO - Running setup.py install for mozleak 02:19:40 INFO - Running setup.py install for mozlog 02:19:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:19:41 INFO - Running setup.py install for moznetwork 02:19:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:19:41 INFO - Running setup.py install for mozprocess 02:19:41 INFO - Running setup.py install for mozprofile 02:19:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:19:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:19:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:19:41 INFO - Running setup.py install for mozrunner 02:19:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:19:41 INFO - Running setup.py install for mozscreenshot 02:19:41 INFO - Running setup.py install for moztest 02:19:41 INFO - Running setup.py install for mozversion 02:19:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:19:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:19:42 INFO - Cleaning up... 02:19:42 INFO - Return code: 0 02:19:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:42 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:42 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 02:19:42 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 02:19:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:42 INFO - 'HOME': '/Users/cltbld', 02:19:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:42 INFO - 'LOGNAME': 'cltbld', 02:19:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:42 INFO - 'MOZ_NO_REMOTE': '1', 02:19:42 INFO - 'NO_EM_RESTART': '1', 02:19:42 INFO - 'PAGER': '/bin/cat', 02:19:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:42 INFO - 'PWD': '/builds/slave/test', 02:19:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:42 INFO - 'SHELL': '/bin/bash', 02:19:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:42 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:42 INFO - 'USER': 'cltbld', 02:19:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:42 INFO - 'XPC_FLAGS': '0x0', 02:19:42 INFO - 'XPC_SERVICE_NAME': '0', 02:19:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:19:42 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-dRVhh3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:19:42 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)) 02:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:19:42 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ubM6GC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:19:42 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)) 02:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:19:42 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-9ricrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:19:42 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)) 02:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:19:42 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-U7yFuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:19:42 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)) 02:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:19:42 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-92vEPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:19:42 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)) 02:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:19:42 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ux8Nm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-DpGjVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-si72NI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-T8X6mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-soFAbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-KtFeGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Km2fPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ifx3AP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-UE4bdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:43 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-F7f_Cy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:19:43 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)) 02:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:19:44 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-gkclNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:19:44 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)) 02:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:19:44 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-dPHE1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:19:44 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)) 02:19:44 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)) 02:19:44 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)) 02:19:44 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)) 02:19:44 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)) 02:19:44 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)) 02:19:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:19: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 02:19: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 02:19: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 02:19: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 02:19: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 02:19: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 02:19:46 INFO - Downloading blessings-1.6.tar.gz 02:19:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:19:46 INFO - Installing collected packages: blessings 02:19:46 INFO - Running setup.py install for blessings 02:19:47 INFO - Successfully installed blessings 02:19:47 INFO - Cleaning up... 02:19:47 INFO - Return code: 0 02:19:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:19:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:19:47 INFO - Reading from file tmpfile_stdout 02:19:47 INFO - Current package versions: 02:19:47 INFO - blessings == 1.6 02:19:47 INFO - blobuploader == 1.2.4 02:19:47 INFO - docopt == 0.6.1 02:19:47 INFO - functools32 == 3.2.3-2 02:19:47 INFO - jsonschema == 2.5.1 02:19:47 INFO - manifestparser == 1.1 02:19:47 INFO - mozInstall == 1.12 02:19:47 INFO - mozcrash == 1.0 02:19:47 INFO - mozdebug == 0.1 02:19:47 INFO - mozdevice == 0.49 02:19:47 INFO - mozfile == 1.2 02:19:47 INFO - mozhttpd == 0.7 02:19:47 INFO - mozinfo == 0.9 02:19:47 INFO - mozleak == 0.1 02:19:47 INFO - mozlog == 3.4 02:19:47 INFO - moznetwork == 0.27 02:19:47 INFO - mozprocess == 0.24 02:19:47 INFO - mozprofile == 0.28 02:19:47 INFO - mozrunner == 6.13 02:19:47 INFO - mozscreenshot == 0.1 02:19:47 INFO - mozsystemmonitor == 0.3 02:19:47 INFO - moztest == 0.8 02:19:47 INFO - mozversion == 1.4 02:19:47 INFO - psutil == 3.1.1 02:19:47 INFO - requests == 1.2.3 02:19:47 INFO - wsgiref == 0.1.2 02:19:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:47 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:47 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 02:19:47 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 02:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:47 INFO - 'HOME': '/Users/cltbld', 02:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:47 INFO - 'LOGNAME': 'cltbld', 02:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:47 INFO - 'MOZ_NO_REMOTE': '1', 02:19:47 INFO - 'NO_EM_RESTART': '1', 02:19:47 INFO - 'PAGER': '/bin/cat', 02:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:47 INFO - 'PWD': '/builds/slave/test', 02:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:47 INFO - 'SHELL': '/bin/bash', 02:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:47 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:47 INFO - 'USER': 'cltbld', 02:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:47 INFO - 'XPC_FLAGS': '0x0', 02:19:47 INFO - 'XPC_SERVICE_NAME': '0', 02:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 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)) 02:19:47 INFO - Cleaning up... 02:19:47 INFO - Return code: 0 02:19:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:19:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:19:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:19:47 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 0x107ef6b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ef9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9e62581b80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, '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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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 02:19:47 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 02:19:47 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 02:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:19:47 INFO - 'HOME': '/Users/cltbld', 02:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:19:47 INFO - 'LOGNAME': 'cltbld', 02:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:47 INFO - 'MOZ_NO_REMOTE': '1', 02:19:47 INFO - 'NO_EM_RESTART': '1', 02:19:47 INFO - 'PAGER': '/bin/cat', 02:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:47 INFO - 'PWD': '/builds/slave/test', 02:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:19:47 INFO - 'SHELL': '/bin/bash', 02:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:19:47 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:19:47 INFO - 'USER': 'cltbld', 02:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:47 INFO - 'XPC_FLAGS': '0x0', 02:19:47 INFO - 'XPC_SERVICE_NAME': '0', 02:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:19:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:19:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:19:48 INFO - Cleaning up... 02:19:48 INFO - Return code: 0 02:19:48 INFO - Running post-action listener: _resource_record_post_action 02:19:48 INFO - Running post-action listener: _start_resource_monitoring 02:19:48 INFO - Starting resource monitoring. 02:19:48 INFO - [mozharness: 2017-02-28 10:19:48.094162Z] Finished create-virtualenv step (success) 02:19:48 INFO - [mozharness: 2017-02-28 10:19:48.095137Z] Running install step. 02:19:48 INFO - Running pre-action listener: _resource_record_pre_action 02:19:48 INFO - Running main action method: install 02:19:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:19:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:19:48 INFO - Reading from file tmpfile_stdout 02:19:48 INFO - Detecting whether we're running mozinstall >=1.0... 02:19:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:19:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:19:48 INFO - Reading from file tmpfile_stdout 02:19:48 INFO - Output received: 02:19:48 INFO - Usage: mozinstall [options] installer 02:19:48 INFO - Options: 02:19:48 INFO - -h, --help show this help message and exit 02:19:48 INFO - -d DEST, --destination=DEST 02:19:48 INFO - Directory to install application into. [default: 02:19:48 INFO - "/builds/slave/test"] 02:19:48 INFO - --app=APP Application being installed. [default: firefox] 02:19:48 INFO - mkdir: /builds/slave/test/build/application 02:19:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:19:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:20:08 INFO - Reading from file tmpfile_stdout 02:20:08 INFO - Output received: 02:20:08 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:20:08 INFO - Running post-action listener: _resource_record_post_action 02:20:08 INFO - [mozharness: 2017-02-28 10:20:08.859259Z] Finished install step (success) 02:20:08 INFO - [mozharness: 2017-02-28 10:20:08.859389Z] Running run-tests step. 02:20:08 INFO - Running pre-action listener: _resource_record_pre_action 02:20:08 INFO - Running main action method: run_tests 02:20:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:20:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:20:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:20:08 INFO - 2017-02-28 02:20:08.873 screenresolution[1928:19879] starting screenresolution argv=screenresolution get 02:20:08 INFO - 2017-02-28 02:20:08.876 screenresolution[1928:19879] Display 0: 1600x1200x32@60 02:20:08 INFO - 2017-02-28 02:20:08.882 screenresolution[1929:19882] starting screenresolution argv=screenresolution list 02:20:08 INFO - Available Modes on Display 0 02:20:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:20:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:20:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:20:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:20:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:20:09 INFO - Intel Iris: 02:20:09 INFO - Chipset Model: Intel Iris 02:20:09 INFO - Type: GPU 02:20:09 INFO - Bus: Built-In 02:20:09 INFO - VRAM (Dynamic, Max): 1536 MB 02:20:09 INFO - Vendor: Intel (0x8086) 02:20:09 INFO - Device ID: 0x0a2e 02:20:09 INFO - Revision ID: 0x0009 02:20:09 INFO - Displays: 02:20:09 INFO - Display: 02:20:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:20:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:20:09 INFO - Display Serial Number: 1600x1200 60 02:20:09 INFO - Main Display: Yes 02:20:09 INFO - Mirror: Off 02:20:09 INFO - Online: Yes 02:20:09 INFO - Rotation: Supported 02:20:09 INFO - Return code: 0 02:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:20:09 INFO - Python 2.7.3 02:20:09 INFO - Return code: 0 02:20:09 INFO - grabbing minidump binary from tooltool 02:20:09 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')]} 02:20:09 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 0x107cd4d30>, '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 0x107bfbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107cc9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:20:09 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 02:20:09 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 02:20:09 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 02:20:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:20:09 INFO - Return code: 0 02:20:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:20:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:20:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:20:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:20:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 02:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0120 --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 02:20:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hq2cxmDOTq/Render', 02:20:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:20:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:20:09 INFO - 'HOME': '/Users/cltbld', 02:20:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:20:09 INFO - 'LOGNAME': 'cltbld', 02:20:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:20:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:20:09 INFO - 'MOZ_NO_REMOTE': '1', 02:20:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:20:09 INFO - 'NO_EM_RESTART': '1', 02:20:09 INFO - 'PAGER': '/bin/cat', 02:20:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:20:09 INFO - 'PWD': '/builds/slave/test', 02:20:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:20:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:20:09 INFO - 'SHELL': '/bin/bash', 02:20:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ufbE5pz6DZ/Listeners', 02:20:09 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 02:20:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:20:09 INFO - 'USER': 'cltbld', 02:20:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:20:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:20:09 INFO - 'XPC_FLAGS': '0x0', 02:20:09 INFO - 'XPC_SERVICE_NAME': '0', 02:20:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:20:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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 02:20:09 INFO - mozversion application_buildid: 20170228004003 02:20:09 INFO - mozversion application_changeset: 66e9dc9f094f790c94c7f2a5df0757c8f3b81e78 02:20:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:20:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:20:09 INFO - mozversion application_name: Firefox 02:20:09 INFO - mozversion application_remotingname: firefox-dev 02:20:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:20:09 INFO - mozversion application_vendor: Mozilla 02:20:09 INFO - mozversion application_version: 53.0a2 02:20:09 INFO - mozversion platform_buildid: 20170228004003 02:20:09 INFO - mozversion platform_changeset: 66e9dc9f094f790c94c7f2a5df0757c8f3b81e78 02:20:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:20:09 INFO - mozversion platform_version: 53.0a2 02:20:09 INFO - using testdate: 1488277209 02:20:09 INFO - actual date: 1488277209 02:20:09 INFO - starting webserver on 'localhost:53252' 02:20:09 INFO - SUITE-START | Running 2 tests 02:20:09 INFO - TEST-START | dromaeo_css 02:20:09 INFO - operating with platform_type : mac_ 02:20:09 INFO - Initialising browser for dromaeo_css test... 02:20:09 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp6OqHR9/profile http://localhost:53252/getInfo.html) 02:20:11 INFO - PROCESS | 1939 | __metrics Screen width/height:1600/1200 02:20:11 INFO - PROCESS | 1939 | colorDepth:24 02:20:11 INFO - PROCESS | 1939 | Browser inner width/height: 1024/689 02:20:11 INFO - PROCESS | 1939 | __metrics 02:20:12 INFO - PROCESS | 1939 | 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" 02:20:12 INFO - PROCESS | 1939 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:20:12 INFO - TEST-INFO | 1939: exit 0 02:20:12 INFO - Browser initialized. 02:20:12 INFO - Running cycle 1/1 for dromaeo_css test... 02:20:12 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp6OqHR9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:20:24 INFO - PROCESS | 1942 | RSS: Main: 212508672 02:20:24 INFO - PROCESS | 1942 | 02:23:28 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53252/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53252/tests/dromaeo/cssquery-ext.html) 02:23:30 INFO - PROCESS | 1942 | RSS: Main: 348954624 02:23:30 INFO - PROCESS | 1942 | 02:25:14 INFO - PROCESS | 1942 | ************************* 02:25:14 INFO - PROCESS | 1942 | A coding exception was thrown and uncaught in a Task. 02:25:14 INFO - PROCESS | 1942 | 02:25:14 ERROR - PROCESS | 1942 | Full message: TypeError: NetworkError when attempting to fetch resource. 02:25:14 INFO - PROCESS | 1942 | Full stack: 02:25:14 INFO - PROCESS | 1942 | ************************* 02:26:32 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53252/tests/dromaeo/cssquery-ext.html (next: http://localhost:53252/tests/dromaeo/cssquery-jquery.html) 02:26:33 INFO - PROCESS | 1942 | RSS: Main: 358088704 02:26:33 INFO - PROCESS | 1942 | 02:29:35 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53252/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53252/tests/dromaeo/cssquery-mootools.html) 02:29:36 INFO - PROCESS | 1942 | RSS: Main: 321249280 02:29:36 INFO - PROCESS | 1942 | 02:32:38 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53252/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53252/tests/dromaeo/cssquery-prototype.html) 02:32:39 INFO - PROCESS | 1942 | RSS: Main: 330207232 02:32:39 INFO - PROCESS | 1942 | 02:35:42 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53252/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53252/tests/dromaeo/cssquery-yui.html) 02:35:43 INFO - PROCESS | 1942 | RSS: Main: 317227008 02:35:43 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | Cycle 1(1): loaded http://localhost:53252/tests/dromaeo/cssquery-yui.html (next: http://localhost:53252/tests/dromaeo/cssquery-dojo.html) 02:38:47 INFO - PROCESS | 1942 | RSS: Main: 361308160 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | __start_tp_report 02:38:47 INFO - PROCESS | 1942 | _x_x_mozilla_page_load 02:38:47 INFO - PROCESS | 1942 | _x_x_mozilla_page_load_details 02:38:47 INFO - PROCESS | 1942 | |i|pagename|runs| 02:38:47 INFO - PROCESS | 1942 | |0;dojo.html;2726.809123361365;3294.621118571364;3300.636929937708;3310.255192581668;3365.4615261558156;4412.4043254160715;5315.468453154687;5621.437856214377;5630.662160270386;5639.68981706006;5314.256004159421;5613.915791263134;5653.8586535336635;5667;5684.37471878093;5146.588272938166;5158.613104017195;5185.87035324118;5249;5308.23030660554;5150.871228219287;5154.32993710817;5193.428722840485;5275.498827611371;5280.577553795687;5194;5199;5205.16717325228;5220;5261.421243663194;5231.189165679326;5249.842504724865;5266.0257852297445;5266.894662106774;5272.261883336336;5124.92312615311;5193.688378697252;5213.973930130363;5237.633365664405;5253.973730131325;5520.033994051025;5550.333959924795;5564.10974244119;5568.610197286192;5611.803586874481;5246.7901283948595;6201.627902325875;6273.278572964076;6274.843128921768;6284.24589049317;6262.937370626281;6324;6342.968285158545;6347.777827776005;6354.968225158891;5578.330600327987;5675.177099320658;5790.942090579125;5791.420857914217;5800.854978625568;6290.308066112724;6333.936660633427;6337.904931426032;6340.968295158587;6368.172137622172;6178.938210617926;6180.969095154585;6182.6599537024995;6187.288461826857;6188.8762224755255;6218.502519798405;6278.717457714414;6287.276963149205;6296.748130074746;6319.715612797435;5863.765449381977;6004.279486461653;6011.338752737195;6014.8195554133445;6021.819345419644;6104.496664630123;6136.733532366159;6164.4143806338325;6213.782517611862;6214.2542894852;4889.951100489021;4896.2655601660035;4907.533784290486;4915.926261106087;4917.754112294371;4411.2941929291155;4423.756693381895;4428.357888106207;4437.977810110993;4475.4181956345465;5623.522000629992;5659.320881494247;5669.943300566942;5763.106718458645;5780.335261444903;6248.375162483806;6250.468710159687;6254.437100660872;6280.874382512416;6319.557630965788;5736.4550367715;5751.539876809845;5760.36635970043;5768.769249229984;5789.2763404574425;5376.758045887945;5386.353637563518;5402.081646120169;5402.648827826179;5412.810551630752;6263.96868015657;6286.308506064329;6289.559730818799;6295.905561416674;6359.236891572856;6323.430891219812;6344.746210151542;6349.301576826638;6355.173827402408;6360.968195158995;6245.313015568193;6248.968755156195;6290.968545157245;6295.937040629535;6308.337624549356;6039.818805435844;6107.931112055284;6126.426068710427;6224.470919971853;6252.468540173954;6197.411245931629;6312.936870631235;6316.3367846377305;6319.9052014218905;6321.715522801393;6055.788047418319;6104.969475152596;6129.417705317987;6132.141500189888;6132.5707200495535;5899.528037757055;5959.940400595939;6080.939190608037;6123.2652081750475;6135.601185922901;5839.854003649943;5844.503217226414;5857.121431785095;5890.145928840208;5930.703464826827;5037.445880953019;6096.908546371719;6112.572119951561;6112.572119951738;6118.326984031842;5729.684867332337;5759.568032397503;5760.596758226884;5821.417858214314;5894.056950887837 02:38:47 INFO - PROCESS | 1942 | |1;ext.html;450.1716841012536;488.4578118288697;494.17719497037433;505.6435213174712;510.8135530531355;27307.453850922993;39809.40285895714;40636.78089657307;40767.79616101919;43158.35262471066;30700.385992280142;42744.786276068575;42994.570054299445;43112.56887431125;43131.3530297045;8206.958965205165;8405.327573794113;8978.640854365834;9200.447973121634;9222.262219022463;8412.327013838878;8498.235158835734;8510.574471276443;10814.243002989793;11021.44892755363;11200.66398008057;11270.605528806493;11282.097432205444;11293.604723834667;11306.208565400384;11108.888911110947;11154.498047587878;11256.437178141061;11273.323600583992;11282;11030.889691103066;11114.166437517219;11114.61098861544;11122.165837562216;11125.332480051224;32962.6703732962;39350;39617.40573891365;39654.60345396538;39789.602103978876;3864.188520410717;3881.4565960765517;3949.9012524686827;3952.960470395288;4040.434339192486;28654.570181447296;28699;29650.851745741347;29864.402711945855;30122.397552048835;3050.4051709916575;3121.391328690906;3143.717065464119;3245.9350812983844;3279.9836000820083;494785;495489.52255238855;498388;498836;500007.99999999633;59393.40606593879;59438.108428373605;59557.702211488664;59629;59697.70151149217;62929.68535157295;63431;63461.36538634555;63528.682356587924;63557.68221158957;15731;15775.684486310209;15897.761533577006;15904.681906361808;15919.602009949613;12697.492100316069;12803.935980320039;12810.551630693068;12810.80783788244;12820.423080961378;13702.45190192401;13901.16593004423;15745.763813542806;15808;16205.513834584981;6161.613636931655;6721.665743360987;6760.932390676128;6795.18457785059;6799.694013769392;854.9529775862263;856.7633054545214;873.057098333798;887.8268737596236;890.3544929925752;24977;29161.979330723323;29309.56035659467;29656.555151672743;29934.25164370908;1041.8385150301565;1053.204830353094;1069.8341757027674;1076.424113099488;1170.163333216737;14637.121772693672;15155.318010689545;15156.621084472756;15456.459023934109;15482.612934676723;614032;615661;617998;618009;618399;59345.70327148337;59349.10976335358;59410.81178376408;59536;59544.70227648921;1269.3843485909326;1387.4450219912117;1442.8357463804084;1447.0594113825887;1447.1389523233659;28280.43439131247;29030.854845725637;29150.562741558468;29901.551476727433;30123.849380752956;48619.02761944671;48909;48929;48994;49026.01947961092;476783;479710;480516;480648;480672;38278;38481;38500.844974650805;38888.80555597204;39168.60831391763;37537.624623754506;44584.554154459336;45514.77242613766;45743.54256457393;46266.30600541062;36260.91217263486;37467.62532374641;37550;37798.055048622904;38936.80531597324;36988.6301136996;37365.626343736214;38098.8095059534;38257.74809141143;38421.231575368896;33849;35441.1139721509;35861.82069089638;36086.45870311895;36851.81574092113;29988.70011299859;32568.348633026733;33729.91108204266;33753.83123084369;34012.31975360528;23765.16821911225;24091.674957877483;24277.393065173514;24440.75559244385;24717.87641061783 02:38:47 INFO - PROCESS | 1942 | |2;jquery.html;341.6668747970728;372.064258390149;387.94956655634763;394.302085309003;422.7041071250124;14706.705865882675;15048.774268385969;17394.304227830886;19915;20211.797882021176;21163.57672846542;21480.24819131327;21564.245251416203;21797.346079617597;21850;4664.97667511662;5745.597808153431;5971.731272092767;6050.42520960508;6061.696915154224;5852.67810270435;5899.823005309848;5978.342382337939;5994.490468310199;6007.098935159717;5584.636998595099;5801.07182850744;5876.382979787125;5933.317668468138;5950.880982380349;5833.620814647034;5884.529237660999;5888.146218798272;5895.587308888379;5914.172015917775;5049.671771334852;5079.1365467870555;5218.425973142989;5225.111731005738;5231.8168864089575;22528;22618.32145035635;25974.22077337683;26013.73986260132;26315.07897223607;2602.531544322021;2692.6634170728657;2694.946101077987;2728.1679087878188;2770.8476033818133;14894.70210595793;15207.695846083127;15407.537773866801;15924.646405055468;16061.518154455385;1760.7975082865378;1774.2814160265114;1781.9643607127784;1791.605846713721;1803.765510483642;121042.39478802637;129111;138383;144438;151258;9523.619055237852;9869.654562090293;11441.37072461006;11936.641900742992;11939.164258501994;15829.97105188159;15854.60363490922;15871.20643967797;15890.205489725467;15905.840941590435;8491.278241349555;8504.44721093127;8821.206091451671;8962.417442866194;8974.641014359484;8594.87107693385;8600.8709869352;8626.39615226928;8782.91217087821;8792.47245165292;4701.341812146302;4784.40194975628;4792.736399497991;4888.877778055577;4896.510348965075;1962.3576027736503;2026.3211824039113;2028.4016215216548;2032.1058734156923;2038.143979528572;971.3880255439028;985.3348989432247;986.6941248212934;1002.3184234720314;1050.4747626186906;20438.89780551088;20672.276470323468;20678.483037924172;20852.582948341253;21091.261805836722;1017.765913839821;1020.8060468511108;1030.1398332392537;1037.5486663301488;1040.2510192661273;9921.950390248003;10771.56913723458;10818.675439736662;10820.89179108199;10914.399708016135;739681;743418;751114;757660;784247;140626;140709.29645351708;145085;149261;150121.24939375234;959.6017652674062;975.4001289207087;996.4121168510613;998.7153661206667;998.8501724741202;14516.274186290855;16659.833401665826;18301.816981830012;18507.90746046261;18607;57605.423945760005;57988.840223196145;58429.415705842395;59504;59895.40104599072;87161.69257461016;87906;88661;90233;91348;7882.251186137537;7906.8930349749935;8219.547924863951;8435.029971553104;8489.915100849159;11232.66302010941;12304.569340073054;12657.240565566095;12657.430415631503;12745;10696.946515267375;10747.677569672922;10828.729281768019;10915.290506117077;11027.629059751871;9838.671779309701;10369.948150259503;10491.370517768959;10747.247692661751;11057;10891.727706807076;10892.564297428014;10935.45322733876;11178.944105279421;11308.378039207593;7051.929480705127;7435.51669141504;7456;8084.191580842104;8355.498670079814;8163.551004694561;9613.903860961302;10610.522526486173;11192.664220073411;11374.886251137383 02:38:47 INFO - PROCESS | 1942 | |3;mootools.html;1317.9472821087156;1426.985730142699;1443.9278036098192;1447.9782803257947;1455.8034665320179;8028.91971080289;8454.661813527468;8463.238308552236;8474.661013559451;8479.576021198938;7396;7669.769906902788;7678.886561448587;7988.7203947861835;8088.59557022148;2286.8056215221686;2326.906923723049;2335.1126571902655;2336.8247381446376;2339.7426283108844;2290.9541809163807;2320.0603755478974;2327.8719670418122;2329.196427232602;2336.3691803213123;2043.1520918818671;2121.66053431451;2234.0728597632046;2292.381057114578;2323.7327707313707;2311.5839148953182;2317.2700599311165;2318.7565305642834;2323.465602911323;2324.0936034946385;2250.3698964290024;2312.086725743322;2313.1672597864754;2319.072371051576;2323.500447403799;8284.46151000183;8307.044689860682;8326.875096873551;8339.332133573309;8376.706815261437;4505.5719706627815;5212.556932660728;5214.348206474191;5216.686998780086;5225.634205605609;3295.6869097435706;3456.94814577779;3478.3738926993133;3541.858325666996;3544.6278140795102;1120.1207052463858;1203.5125774061519;1206.5716670581992;1230.3233221728103;1234.487687609641;10379.481025948748;10831.2959657622;10847.511861966152;10851.457427128613;10852.837207441895;3809;4405.84579539721;4518.932216016762;4550.522195169459;4560.133574620878;8555.700550480704;8564.400491965502;8668.523231222218;8707.738767836974;8714;4415.40392047076;5176;5182.611304152204;5186.818461353834;5190.37715474138;3796.185301237168;4677.766111694402;4819.783109760069;4838.104950584153;4856.587190088811;3646.2342907989087;3700.3524383232825;3772.43413487979;3794.2221844521805;3797.6582107610448;1154.740183458732;1154.8325492803665;1156.2022204678913;1169.859616845984;1171.0690001448697;831.4927893984665;859.6045818923366;868.7567481105174;887.9788243520004;926.7312479381039;5550.417206193372;5573.5819813514145;5614.29821272341;5715.31416230055;5906.852328691817;933.2254228989922;941.9623215071457;955.4267439536361;979.9314048016571;986.5807032011367;3639.745217834726;3674.706023518112;3717.498137751423;3720.776753394805;3758.0980564664283;7877;9614;9633.759156021155;9664.71005869825;9973.601055957826;9557.90442095584;9574.425534467955;9582.233421326417;9582.329236953346;9608;7742.187070357531;7900.486468379537;7914;7919.960400197962;7936.484128531627;4945.727984960861;5024.196128619404;5408.215808707794;5451.400345962021;5453.412122572025;9040.63837446521;9053.728388148365;9165.908340916505;9233.722988310094;9393.24854011691;8962.462252264884;9326.207272381924;9386.06139386056;9474.763130921781;9483;5505.587080969023;6295.496360291074;6515.804525864231;6706.8993965089585;6869.725210991504;7531.811704707207;7567.9243207567215;7599.620018998918;7952.6421311042295;8001.399895007781;6652.068710380648;6738.663066846736;6754.763583274562;6774.93225067743;6852.691628876612;6955.130608673916;7018.684159212746;7019.6490175492045;7056.964715176391;7063.081799366049;5329;6413.967930160319;6425.903611445926;6469.385408386196;6492.772752953624;4864.73243971585;4884.584810291023;4932.679375840559;4984.2025275955675;5008.3238762768015;4887.877803054952;4895.657303988686;4905.754712264444;5027.497250274943;5030.0694371541385 02:38:47 INFO - PROCESS | 1942 | |4;prototype.html;350.37458137225053;351.6465951718524;360.6753921470676;366.96146904575005;370.2391585292225;4646.744429056396;4679.7130789033035;4769.856904292877;5203.375594928609;5308.469153084695;4612.492625811158;4864.416270047591;5028.723420211896;5050.7222102784335;5116.948830511694;3980.7213495055357;4061.3298805697023;4071.6742660587083;4095.488063992001;4096.951524237881;3710.2579484103153;4023.577524359944;4082;4101.425800387948;4102.917941641165;3450.7584469087046;3852.287326844543;4045.9480535060948;4061.2689715851134;4064.268431682296;3360.2190838855445;3453.9309213815836;3694.3165514379925;3947.4276229946495;4025.778582177979;3082.7071428214285;3297.6207736110173;3532.4701294805727;3559.8252576649643;3612.6026137124895;4032.4354590357375;4800.975995120002;5006.774695138727;5049.267856160836;5097.711975775641;4572.359869618256;4589;4671.392718946515;4677.368555245066;4726.645501587394;4949.851504454872;5317.308749862532;5381.282452361533;5383.488568585978;5386.865328366783;3928.958825911136;3976.642102210786;3987;3996.72022958393;3998.520177578709;4973.552380285755;5314.256004159421;5619.719014049281;5651.858703532444;5664.575156863293;3837.328467518173;3971.7021223407783;3994.5206575210577;4000.199860097943;4041.4341992121126;4069.1861627674584;4119.258533464005;4133.710640255214;4152.6055024772595;4178.35235538492;3940.2907476653904;3985.621365970228;4230.936535951963;4504.837677914619;4733.715977041388;4434.7560884151035;5449.673019618756;5485.369182543979;5537.809370985257;5632.915506267327;1143.7826812905687;1183.7336599265268;1191.9106067044834;1192.4276347353318;1243.7885559454733;713.3045280851129;746.0227102495639;808.5270116981515;819.8401311744271;821.4537332673824;4454.643628509712;4753.787784115098;4762.9285560715925;4784.593309568656;4825.30033145192;4811.446683631427;4920.884019871418;5140.971805638813;5268.894622107537;5398.676079435247;4196.874093777192;4781.163296423112;4785.186518291899;4809.615230781529;4827.493113223131;2253.797158255732;2384.9284521464383;2588.8446693198466;2692.572936343741;2751.802965709923;4698.835540756126;5025.070361983045;5385.488378604026;5447.155690867922;5495.790925996274;4861.149298872754;5368.516833484927;5371.838844834661;5762.567807414461;5803.564732645069;5407;5803.9105921868095;5805.94194058054;5855.3851845556455;5993.730282137401;4662.9898287473425;5486.588505861996;5693.5160511357;5705.459525927893;5717;4527.683062185615;4836.540528649773;4902.460729319843;5505.9724701376235;5576.665400075846;5082.847514574569;5151.124308867426;5703.173039909107;5847.707614619337;5851.271038305982;3259.5006297103596;3262.265990152197;3549.846299952474;3661.524001879845;3773.4717139601025;4682.946337074131;5177.922331165111;5316.548093411949;5573.554115670818;5625.240592519957;4525.389072475174;5026.396832380138;5386.057439948072;5407.864803379947;5444.564434845282;4489.910201796011;5437.32033495813;5437.4018857926385;5497.84545245487;5547.33431988164;4367.934480982724;4737.099951009297;5223.347081614798;5331.973340133274;5441.972790136024;5358.169483730028;5491.752871120889;5522.723863806874;5529.695866727207;5598.404454730382;4762.666613337033;5372.811951581676;5399.433059528693;5400.783968641211;5431 02:38:47 INFO - PROCESS | 1942 | |5;yui.html;630.73509126167;652.4193467813647;702.2626242445431;747.7681918605233;782.8512582609305;14717.558473245806;15680.13759243244;15719.60700982476;15740.606484837885;15767.975081619687;14080.436782528686;14137.858621413783;14330.99683022189;14495.347709353078;14542.491012814553;674.076515174212;845.805464743111;863.9308855291562;865.1089377940731;867.2888231650049;693.9826504337407;699.7760716570699;699.8460338725472;699.9825004374906;700.1563116444697;882.1839798186684;905.4929239625798;915.7115508614799;917.2295271971543;922.280621115531;916.7708072981754;917.4953775423558;917.7567944494714;917.7889085510303;918.6555041859269;680.7685386968454;680.7889554238202;681.7647911470535;681.8295426143465;682.6586706646676;11879.643610691694;12369.072319575978;12926.578076411588;14555.053921495175;15030.398784048624;14064.789028164585;14385.712285754227;14426.927865360605;14466.05970611907;14519.92740036293;9659.372140810825;11165.330080195214;11208.21542492026;11396.912151918941;11853.762924741457;3311.536384906115;3686.3733165361955;3753.8310776014873;3874.8643797467234;3879.7090218233743;109460;110228;110471.89528104696;111220;111614;10749.757541093708;11094.611688590861;11170.612131247077;11249.381284029458;11258.493367798468;23368.88315558445;23392.415189620395;23910;23914.163004294765;23915.880420597787;7418.146913105062;7546.962265188639;7596.430267729942;7842.372610191171;7879.684812607432;4039.9520734211255;4127.236461254617;4128.174365126986;4149.33610622299;4175.31107367287;3592.76647017943;3594.964050359515;3680.834362453696;3703.222323312081;3705.333040052816;680.0717021265937;709.4643544124257;709.9112610923635;712.398023669998;726.0017475970541;645.9612423254525;657.4280376072848;673.6631684157921;675.6047712088483;710.9395701365338;9524.949383122344;10103.696889093339;10214.438205898747;10391.948040259751;10486.632967846088;670.389945149921;674.9122614060141;690.520088538461;695.9095317608779;699.909011828459;4386.188555117314;4698.577128058491;4701.318308845198;4708.411448568929;4720.197566413718;70489;78686;95516.04483955071;102340.48829755952;103397.48301258446;87593;89078.66382004276;89217.21565568652;90583.54708226417;94422;76635.61682191554;77946;78376.2162378369;79121.60439197767;79916.80124798133;9074.591643375932;9127.58925848325;9147.63409463614;9197.824643058715;9274;51026.48973510217;52153.7392313036;52487.73756131348;53496.73251633717;53772.46227537675;61636;62348;62538.68730656499;66455;71809;13143.079984401;13221.206727596375;13273.867261327649;13358.599242022754;13924.094933829268;13878.583642490741;13928.651783705489;14535;14758.704825903636;14913.105213687213;12905.935470322589;13527;13675.24786136772;13864.86135138676;14044.648883777987;11328.75383707808;13202.735945281232;13759.17444953306;13771.724565508433;13874.791878122038;12716.491340346653;12749;13381.866181338062;13731.176129432266;13759.243241621807;10539.841902371318;11293.265937714023;11510.424478776195;11570.132240081859;11740.823887641522;10752.83870741955;10823.783524329312;10852.403117828595;10910.23628346008;10991.010809027226 02:38:47 INFO - PROCESS | 1942 | __end_tp_report 02:38:47 INFO - PROCESS | 1942 | __start_cc_report 02:38:47 INFO - PROCESS | 1942 | _x_x_mozilla_cycle_collect,1528 02:38:47 INFO - PROCESS | 1942 | __end_cc_report 02:38:47 INFO - PROCESS | 1942 | __startTimestamp1488278327287__endTimestamp 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | ------- Summary: start ------- 02:38:47 INFO - PROCESS | 1942 | Number of tests: 6 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | [#0] dojo.html Cycles:165 Average:5708.41 Median:5844.50 stddev:670.65 (11.5%) stddev-sans-first:630.60 02:38:47 INFO - PROCESS | 1942 | Values: 2726.8 3294.6 3300.6 3310.3 3365.5 4412.4 5315.5 5621.4 5630.7 5639.7 5314.3 5613.9 5653.9 5667.0 5684.4 5146.6 5158.6 5185.9 5249.0 5308.2 5150.9 5154.3 5193.4 5275.5 5280.6 5194.0 5199.0 5205.2 5220.0 5261.4 5231.2 5249.8 5266.0 5266.9 5272.3 5124.9 5193.7 5214.0 5237.6 5254.0 5520.0 5550.3 5564.1 5568.6 5611.8 5246.8 6201.6 6273.3 6274.8 6284.2 6262.9 6324.0 6343.0 6347.8 6355.0 5578.3 5675.2 5790.9 5791.4 5800.9 6290.3 6333.9 6337.9 6341.0 6368.2 6178.9 6181.0 6182.7 6187.3 6188.9 6218.5 6278.7 6287.3 6296.7 6319.7 5863.8 6004.3 6011.3 6014.8 6021.8 6104.5 6136.7 6164.4 6213.8 6214.3 4890.0 4896.3 4907.5 4915.9 4917.8 4411.3 4423.8 4428.4 4438.0 4475.4 5623.5 5659.3 5669.9 5763.1 5780.3 6248.4 6250.5 6254.4 6280.9 6319.6 5736.5 5751.5 5760.4 5768.8 5789.3 5376.8 5386.4 5402.1 5402.6 5412.8 6264.0 6286.3 6289.6 6295.9 6359.2 6323.4 6344.7 6349.3 6355.2 6361.0 6245.3 6249.0 6291.0 6295.9 6308.3 6039.8 6107.9 6126.4 6224.5 6252.5 6197.4 6312.9 6316.3 6319.9 6321.7 6055.8 6105.0 6129.4 6132.1 6132.6 5899.5 5959.9 6080.9 6123.3 6135.6 5839.9 5844.5 5857.1 5890.1 5930.7 5037.4 6096.9 6112.6 6112.6 6118.3 5729.7 5759.6 5760.6 5821.4 5894.1 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | [#1] ext.html Cycles:180 Average:66909.11 Median:37674.03 stddev:142628.84 (378.6%) stddev-sans-first:142941.67 02:38:47 INFO - PROCESS | 1942 | Values: 450.2 488.5 494.2 505.6 510.8 27307.5 39809.4 40636.8 40767.8 43158.4 30700.4 42744.8 42994.6 43112.6 43131.4 8207.0 8405.3 8978.6 9200.4 9222.3 8412.3 8498.2 8510.6 10814.2 11021.4 11200.7 11270.6 11282.1 11293.6 11306.2 11108.9 11154.5 11256.4 11273.3 11282.0 11030.9 11114.2 11114.6 11122.2 11125.3 32962.7 39350.0 39617.4 39654.6 39789.6 3864.2 3881.5 3949.9 3953.0 4040.4 28654.6 28699.0 29650.9 29864.4 30122.4 3050.4 3121.4 3143.7 3245.9 3280.0 494785.0 495489.5 498388.0 498836.0 500008.0 59393.4 59438.1 59557.7 59629.0 59697.7 62929.7 63431.0 63461.4 63528.7 63557.7 15731.0 15775.7 15897.8 15904.7 15919.6 12697.5 12803.9 12810.6 12810.8 12820.4 13702.5 13901.2 15745.8 15808.0 16205.5 6161.6 6721.7 6760.9 6795.2 6799.7 855.0 856.8 873.1 887.8 890.4 24977.0 29162.0 29309.6 29656.6 29934.3 1041.8 1053.2 1069.8 1076.4 1170.2 14637.1 15155.3 15156.6 15456.5 15482.6 614032.0 615661.0 617998.0 618009.0 618399.0 59345.7 59349.1 59410.8 59536.0 59544.7 1269.4 1387.4 1442.8 1447.1 1447.1 28280.4 29030.9 29150.6 29901.6 30123.8 48619.0 48909.0 48929.0 48994.0 49026.0 476783.0 479710.0 480516.0 480648.0 480672.0 38278.0 38481.0 38500.8 38888.8 39168.6 37537.6 44584.6 45514.8 45743.5 46266.3 36260.9 37467.6 37550.0 37798.1 38936.8 36988.6 37365.6 38098.8 38257.7 38421.2 33849.0 35441.1 35861.8 36086.5 36851.8 29988.7 32568.3 33729.9 33753.8 34012.3 23765.2 24091.7 24277.4 24440.8 24717.9 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | [#2] jquery.html Cycles:180 Average:40864.92 Median:14458.81 stddev:125709.31 (869.4%) stddev-sans-first:126025.13 02:38:47 INFO - PROCESS | 1942 | Values: 341.7 372.1 387.9 394.3 422.7 14706.7 15048.8 17394.3 19915.0 20211.8 21163.6 21480.2 21564.2 21797.3 21850.0 4665.0 5745.6 5971.7 6050.4 6061.7 5852.7 5899.8 5978.3 5994.5 6007.1 5584.6 5801.1 5876.4 5933.3 5950.9 5833.6 5884.5 5888.1 5895.6 5914.2 5049.7 5079.1 5218.4 5225.1 5231.8 22528.0 22618.3 25974.2 26013.7 26315.1 2602.5 2692.7 2694.9 2728.2 2770.8 14894.7 15207.7 15407.5 15924.6 16061.5 1760.8 1774.3 1782.0 1791.6 1803.8 121042.4 129111.0 138383.0 144438.0 151258.0 9523.6 9869.7 11441.4 11936.6 11939.2 15830.0 15854.6 15871.2 15890.2 15905.8 8491.3 8504.4 8821.2 8962.4 8974.6 8594.9 8600.9 8626.4 8782.9 8792.5 4701.3 4784.4 4792.7 4888.9 4896.5 1962.4 2026.3 2028.4 2032.1 2038.1 971.4 985.3 986.7 1002.3 1050.5 20438.9 20672.3 20678.5 20852.6 21091.3 1017.8 1020.8 1030.1 1037.5 1040.3 9922.0 10771.6 10818.7 10820.9 10914.4 739681.0 743418.0 751114.0 757660.0 784247.0 140626.0 140709.3 145085.0 149261.0 150121.2 959.6 975.4 996.4 998.7 998.9 14516.3 16659.8 18301.8 18507.9 18607.0 57605.4 57988.8 58429.4 59504.0 59895.4 87161.7 87906.0 88661.0 90233.0 91348.0 7882.3 7906.9 8219.5 8435.0 8489.9 11232.7 12304.6 12657.2 12657.4 12745.0 10696.9 10747.7 10828.7 10915.3 11027.6 9838.7 10369.9 10491.4 10747.2 11057.0 10891.7 10892.6 10935.5 11178.9 11308.4 7051.9 7435.5 7456.0 8084.2 8355.5 8163.6 9613.9 10610.5 11192.7 11374.9 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | [#3] mootools.html Cycles:180 Average:5310.46 Median:5221.16 stddev:2874.98 (55.1%) stddev-sans-first:2867.38 02:38:47 INFO - PROCESS | 1942 | Values: 1317.9 1427.0 1443.9 1448.0 1455.8 8028.9 8454.7 8463.2 8474.7 8479.6 7396.0 7669.8 7678.9 7988.7 8088.6 2286.8 2326.9 2335.1 2336.8 2339.7 2291.0 2320.1 2327.9 2329.2 2336.4 2043.2 2121.7 2234.1 2292.4 2323.7 2311.6 2317.3 2318.8 2323.5 2324.1 2250.4 2312.1 2313.2 2319.1 2323.5 8284.5 8307.0 8326.9 8339.3 8376.7 4505.6 5212.6 5214.3 5216.7 5225.6 3295.7 3456.9 3478.4 3541.9 3544.6 1120.1 1203.5 1206.6 1230.3 1234.5 10379.5 10831.3 10847.5 10851.5 10852.8 3809.0 4405.8 4518.9 4550.5 4560.1 8555.7 8564.4 8668.5 8707.7 8714.0 4415.4 5176.0 5182.6 5186.8 5190.4 3796.2 4677.8 4819.8 4838.1 4856.6 3646.2 3700.4 3772.4 3794.2 3797.7 1154.7 1154.8 1156.2 1169.9 1171.1 831.5 859.6 868.8 888.0 926.7 5550.4 5573.6 5614.3 5715.3 5906.9 933.2 942.0 955.4 979.9 986.6 3639.7 3674.7 3717.5 3720.8 3758.1 7877.0 9614.0 9633.8 9664.7 9973.6 9557.9 9574.4 9582.2 9582.3 9608.0 7742.2 7900.5 7914.0 7920.0 7936.5 4945.7 5024.2 5408.2 5451.4 5453.4 9040.6 9053.7 9165.9 9233.7 9393.2 8962.5 9326.2 9386.1 9474.8 9483.0 5505.6 6295.5 6515.8 6706.9 6869.7 7531.8 7567.9 7599.6 7952.6 8001.4 6652.1 6738.7 6754.8 6774.9 6852.7 6955.1 7018.7 7019.6 7057.0 7063.1 5329.0 6414.0 6425.9 6469.4 6492.8 4864.7 4884.6 4932.7 4984.2 5008.3 4887.9 4895.7 4905.8 5027.5 5030.1 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | [#4] prototype.html Cycles:180 Average:4378.26 Median:4784.89 stddev:1335.70 (27.9%) stddev-sans-first:1304.79 02:38:47 INFO - PROCESS | 1942 | Values: 350.4 351.6 360.7 367.0 370.2 4646.7 4679.7 4769.9 5203.4 5308.5 4612.5 4864.4 5028.7 5050.7 5116.9 3980.7 4061.3 4071.7 4095.5 4097.0 3710.3 4023.6 4082.0 4101.4 4102.9 3450.8 3852.3 4045.9 4061.3 4064.3 3360.2 3453.9 3694.3 3947.4 4025.8 3082.7 3297.6 3532.5 3559.8 3612.6 4032.4 4801.0 5006.8 5049.3 5097.7 4572.4 4589.0 4671.4 4677.4 4726.6 4949.9 5317.3 5381.3 5383.5 5386.9 3929.0 3976.6 3987.0 3996.7 3998.5 4973.6 5314.3 5619.7 5651.9 5664.6 3837.3 3971.7 3994.5 4000.2 4041.4 4069.2 4119.3 4133.7 4152.6 4178.4 3940.3 3985.6 4230.9 4504.8 4733.7 4434.8 5449.7 5485.4 5537.8 5632.9 1143.8 1183.7 1191.9 1192.4 1243.8 713.3 746.0 808.5 819.8 821.5 4454.6 4753.8 4762.9 4784.6 4825.3 4811.4 4920.9 5141.0 5268.9 5398.7 4196.9 4781.2 4785.2 4809.6 4827.5 2253.8 2384.9 2588.8 2692.6 2751.8 4698.8 5025.1 5385.5 5447.2 5495.8 4861.1 5368.5 5371.8 5762.6 5803.6 5407.0 5803.9 5805.9 5855.4 5993.7 4663.0 5486.6 5693.5 5705.5 5717.0 4527.7 4836.5 4902.5 5506.0 5576.7 5082.8 5151.1 5703.2 5847.7 5851.3 3259.5 3262.3 3549.8 3661.5 3773.5 4682.9 5177.9 5316.5 5573.6 5625.2 4525.4 5026.4 5386.1 5407.9 5444.6 4489.9 5437.3 5437.4 5497.8 5547.3 4367.9 4737.1 5223.3 5332.0 5442.0 5358.2 5491.8 5522.7 5529.7 5598.4 4762.7 5372.8 5399.4 5400.8 5431.0 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - PROCESS | 1942 | [#5] yui.html Cycles:180 Average:20282.43 Median:4083.59 stddev:29008.44 (710.4%) stddev-sans-first:29052.28 02:38:47 INFO - PROCESS | 1942 | Values: 630.7 652.4 702.3 747.8 782.9 14717.6 15680.1 15719.6 15740.6 15768.0 14080.4 14137.9 14331.0 14495.3 14542.5 674.1 845.8 863.9 865.1 867.3 694.0 699.8 699.8 700.0 700.2 882.2 905.5 915.7 917.2 922.3 916.8 917.5 917.8 917.8 918.7 680.8 680.8 681.8 681.8 682.7 11879.6 12369.1 12926.6 14555.1 15030.4 14064.8 14385.7 14426.9 14466.1 14519.9 9659.4 11165.3 11208.2 11396.9 11853.8 3311.5 3686.4 3753.8 3874.9 3879.7 109460.0 110228.0 110471.9 111220.0 111614.0 10749.8 11094.6 11170.6 11249.4 11258.5 23368.9 23392.4 23910.0 23914.2 23915.9 7418.1 7547.0 7596.4 7842.4 7879.7 4040.0 4127.2 4128.2 4149.3 4175.3 3592.8 3595.0 3680.8 3703.2 3705.3 680.1 709.5 709.9 712.4 726.0 646.0 657.4 673.7 675.6 710.9 9524.9 10103.7 10214.4 10391.9 10486.6 670.4 674.9 690.5 695.9 699.9 4386.2 4698.6 4701.3 4708.4 4720.2 70489.0 78686.0 95516.0 102340.5 103397.5 87593.0 89078.7 89217.2 90583.5 94422.0 76635.6 77946.0 78376.2 79121.6 79916.8 9074.6 9127.6 9147.6 9197.8 9274.0 51026.5 52153.7 52487.7 53496.7 53772.5 61636.0 62348.0 62538.7 66455.0 71809.0 13143.1 13221.2 13273.9 13358.6 13924.1 13878.6 13928.7 14535.0 14758.7 14913.1 12905.9 13527.0 13675.2 13864.9 14044.6 11328.8 13202.7 13759.2 13771.7 13874.8 12716.5 12749.0 13381.9 13731.2 13759.2 10539.8 11293.3 11510.4 11570.1 11740.8 10752.8 10823.8 10852.4 10910.2 10991.0 02:38:47 INFO - PROCESS | 1942 | -------- Summary: end -------- 02:38:47 INFO - PROCESS | 1942 | 02:38:47 INFO - TEST-INFO | 1942: exit 0 02:38:47 INFO - TEST-OK | dromaeo_css | took 1118453ms 02:38:47 INFO - TEST-START | kraken 02:38:47 INFO - operating with platform_type : mac_ 02:38:47 INFO - Initialising browser for kraken test... 02:38:47 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpeGxAu5/profile http://localhost:53252/getInfo.html) 02:38:50 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 02:38:50 INFO - PROCESS | 1947 | colorDepth:24 02:38:50 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 02:38:50 INFO - PROCESS | 1947 | __metrics 02:38:50 INFO - PROCESS | 1947 | 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" 02:38:50 INFO - PROCESS | 1947 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:38:50 INFO - TEST-INFO | 1947: exit 0 02:38:50 INFO - Browser initialized. 02:38:50 INFO - Running cycle 1/1 for kraken test... 02:38:50 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpeGxAu5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:39:03 INFO - PROCESS | 1949 | RSS: Main: 212549632 02:39:03 INFO - PROCESS | 1949 | 02:39:05 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53252/tests/kraken/driver.html?testName=audio-beat-detection) 02:39:06 INFO - PROCESS | 1949 | RSS: Main: 266305536 02:39:06 INFO - PROCESS | 1949 | 02:39:09 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53252/tests/kraken/driver.html?testName=audio-dft) 02:39:09 INFO - PROCESS | 1949 | RSS: Main: 275304448 02:39:09 INFO - PROCESS | 1949 | 02:39:13 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53252/tests/kraken/driver.html?testName=audio-fft) 02:39:13 INFO - PROCESS | 1949 | RSS: Main: 372367360 02:39:13 INFO - PROCESS | 1949 | 02:39:15 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53252/tests/kraken/driver.html?testName=audio-oscillator) 02:39:16 INFO - PROCESS | 1949 | RSS: Main: 277020672 02:39:16 INFO - PROCESS | 1949 | 02:39:18 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53252/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:39:18 INFO - PROCESS | 1949 | RSS: Main: 279228416 02:39:18 INFO - PROCESS | 1949 | 02:39:21 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53252/tests/kraken/driver.html?testName=imaging-darkroom) 02:39:22 INFO - PROCESS | 1949 | RSS: Main: 317886464 02:39:22 INFO - PROCESS | 1949 | 02:39:25 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53252/tests/kraken/driver.html?testName=imaging-desaturate) 02:39:26 INFO - PROCESS | 1949 | RSS: Main: 319934464 02:39:26 INFO - PROCESS | 1949 | 02:39:28 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53252/tests/kraken/driver.html?testName=json-parse-financial) 02:39:29 INFO - PROCESS | 1949 | RSS: Main: 318476288 02:39:29 INFO - PROCESS | 1949 | 02:39:31 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53252/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:39:31 INFO - PROCESS | 1949 | RSS: Main: 295313408 02:39:31 INFO - PROCESS | 1949 | 02:39:33 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53252/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:39:34 INFO - PROCESS | 1949 | RSS: Main: 298455040 02:39:34 INFO - PROCESS | 1949 | 02:39:36 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53252/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:39:37 INFO - PROCESS | 1949 | RSS: Main: 306532352 02:39:37 INFO - PROCESS | 1949 | 02:39:40 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:39:41 INFO - PROCESS | 1949 | RSS: Main: 316661760 02:39:41 INFO - PROCESS | 1949 | 02:39:44 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:39:44 INFO - PROCESS | 1949 | RSS: Main: 310927360 02:39:44 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:53252/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53252/tests/kraken/driver.html?testName=ai-astar) 02:39:46 INFO - PROCESS | 1949 | RSS: Main: 354652160 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | __start_tp_report 02:39:46 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 02:39:46 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 02:39:46 INFO - PROCESS | 1949 | |i|pagename|runs| 02:39:46 INFO - PROCESS | 1949 | |0;ai-astar;115;115;114;117;115;115;116;114;115;116 02:39:46 INFO - PROCESS | 1949 | |1;audio-beat-detection;140;110;110;155;142;108;109;110;129;109 02:39:46 INFO - PROCESS | 1949 | |2;audio-dft;181;186;187;188;185;185;184;185;185;184 02:39:46 INFO - PROCESS | 1949 | |3;audio-fft;84;59;60;88;61;61;78;59;65;74 02:39:46 INFO - PROCESS | 1949 | |4;audio-oscillator;83;70;86;71;68;68;83;66;69;82 02:39:46 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;90;87;90;91;89;87;93;90;89;85 02:39:46 INFO - PROCESS | 1949 | |6;imaging-darkroom;96;96;97;97;96;96;97;108;152;150 02:39:46 INFO - PROCESS | 1949 | |7;imaging-desaturate;85;100;138;113;88;86;86;85;87;85 02:39:46 INFO - PROCESS | 1949 | |8;json-parse-financial;57;54;53;54;55;56;58;59;58;57 02:39:46 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;64;68;75;76;72;68;70;70;70;73 02:39:46 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;85;86;98;94;84;88;85;87;89;83 02:39:46 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;140;140;153;153;146;143;141;150;181;159 02:39:46 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;167;226;162;211;154;215;210;168;167;170 02:39:46 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;67;67;56;73;83;84;83;82;84;69 02:39:46 INFO - PROCESS | 1949 | __end_tp_report 02:39:46 INFO - PROCESS | 1949 | __start_cc_report 02:39:46 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,2184 02:39:46 INFO - PROCESS | 1949 | __end_cc_report 02:39:46 INFO - PROCESS | 1949 | __startTimestamp1488278386946__endTimestamp 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | ------- Summary: start ------- 02:39:46 INFO - PROCESS | 1949 | Number of tests: 14 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:115.20 Median:115.00 stddev:0.92 (0.8%) stddev-sans-first:0.97 02:39:46 INFO - PROCESS | 1949 | Values: 115.0 115.0 114.0 117.0 115.0 115.0 116.0 114.0 115.0 116.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:122.20 Median:119.50 stddev:17.73 (14.8%) stddev-sans-first:17.59 02:39:46 INFO - PROCESS | 1949 | Values: 140.0 110.0 110.0 155.0 142.0 108.0 109.0 110.0 129.0 109.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:1.89 (1.0%) stddev-sans-first:1.33 02:39:46 INFO - PROCESS | 1949 | Values: 181.0 186.0 187.0 188.0 185.0 185.0 184.0 185.0 185.0 184.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:68.90 Median:69.50 stddev:11.14 (16.0%) stddev-sans-first:10.39 02:39:46 INFO - PROCESS | 1949 | Values: 84.0 59.0 60.0 88.0 61.0 61.0 78.0 59.0 65.0 74.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:74.60 Median:76.50 stddev:7.83 (10.2%) stddev-sans-first:7.70 02:39:46 INFO - PROCESS | 1949 | Values: 83.0 70.0 86.0 71.0 68.0 68.0 83.0 66.0 69.0 82.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:89.10 Median:90.00 stddev:2.28 (2.5%) stddev-sans-first:2.40 02:39:46 INFO - PROCESS | 1949 | Values: 90.0 87.0 90.0 91.0 89.0 87.0 93.0 90.0 89.0 85.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:96.00 stddev:22.70 (23.6%) stddev-sans-first:23.62 02:39:46 INFO - PROCESS | 1949 | Values: 96.0 96.0 97.0 97.0 96.0 96.0 97.0 108.0 152.0 150.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:85.50 stddev:17.55 (20.5%) stddev-sans-first:18.21 02:39:46 INFO - PROCESS | 1949 | Values: 85.0 100.0 138.0 113.0 88.0 86.0 86.0 85.0 87.0 85.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:2.02 (3.6%) stddev-sans-first:2.12 02:39:46 INFO - PROCESS | 1949 | Values: 57.0 54.0 53.0 54.0 55.0 56.0 58.0 59.0 58.0 57.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:3.57 (5.0%) stddev-sans-first:2.87 02:39:46 INFO - PROCESS | 1949 | Values: 64.0 68.0 75.0 76.0 72.0 68.0 70.0 70.0 70.0 73.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:87.50 stddev:4.72 (5.4%) stddev-sans-first:4.89 02:39:46 INFO - PROCESS | 1949 | Values: 85.0 86.0 98.0 94.0 84.0 88.0 85.0 87.0 89.0 83.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:150.60 Median:151.50 stddev:12.48 (8.2%) stddev-sans-first:12.64 02:39:46 INFO - PROCESS | 1949 | Values: 140.0 140.0 153.0 153.0 146.0 143.0 141.0 150.0 181.0 159.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.00 Median:190.00 stddev:26.94 (14.2%) stddev-sans-first:27.78 02:39:46 INFO - PROCESS | 1949 | Values: 167.0 226.0 162.0 211.0 154.0 215.0 210.0 168.0 167.0 170.0 02:39:46 INFO - PROCESS | 1949 | 02:39:46 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.80 Median:82.50 stddev:9.82 (11.9%) stddev-sans-first:10.00 02:39:46 INFO - PROCESS | 1949 | Values: 67.0 67.0 56.0 73.0 83.0 84.0 83.0 82.0 84.0 69.0 02:39:46 INFO - PROCESS | 1949 | -------- Summary: end -------- 02:39:46 INFO - PROCESS | 1949 | 02:39:47 INFO - TEST-INFO | 1949: exit 0 02:39:47 INFO - TEST-OK | kraken | took 59958ms 02:39:47 INFO - SUITE-END | took 1178s 02:39:48 INFO - Completed test suite (00:19:38) 02:39:48 INFO - javascript benchmark 02:39:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2726.809123361365, 3294.621118571364, 3300.636929937708, 3310.255192581668, 3365.4615261558156, 4412.4043254160715, 5315.468453154687, 5621.437856214377, 5630.662160270386, 5639.68981706006, 5314.256004159421, 5613.915791263134, 5653.8586535336635, 5667.0, 5684.37471878093, 5146.588272938166, 5158.613104017195, 5185.87035324118, 5249.0, 5308.23030660554, 5150.871228219287, 5154.32993710817, 5193.428722840485, 5275.498827611371, 5280.577553795687, 5194.0, 5199.0, 5205.16717325228, 5220.0, 5261.421243663194, 5231.189165679326, 5249.842504724865, 5266.0257852297445, 5266.894662106774, 5272.261883336336, 5124.92312615311, 5193.688378697252, 5213.973930130363, 5237.633365664405, 5253.973730131325, 5520.033994051025, 5550.333959924795, 5564.10974244119, 5568.610197286192, 5611.803586874481, 5246.7901283948595, 6201.627902325875, 6273.278572964076, 6274.843128921768, 6284.24589049317, 6262.937370626281, 6324.0, 6342.968285158545, 6347.777827776005, 6354.968225158891, 5578.330600327987, 5675.177099320658, 5790.942090579125, 5791.420857914217, 5800.854978625568, 6290.308066112724, 6333.936660633427, 6337.904931426032, 6340.968295158587, 6368.172137622172, 6178.938210617926, 6180.969095154585, 6182.6599537024995, 6187.288461826857, 6188.8762224755255, 6218.502519798405, 6278.717457714414, 6287.276963149205, 6296.748130074746, 6319.715612797435, 5863.765449381977, 6004.279486461653, 6011.338752737195, 6014.8195554133445, 6021.819345419644, 6104.496664630123, 6136.733532366159, 6164.4143806338325, 6213.782517611862, 6214.2542894852, 4889.951100489021, 4896.2655601660035, 4907.533784290486, 4915.926261106087, 4917.754112294371, 4411.2941929291155, 4423.756693381895, 4428.357888106207, 4437.977810110993, 4475.4181956345465, 5623.522000629992, 5659.320881494247, 5669.943300566942, 5763.106718458645, 5780.335261444903, 6248.375162483806, 6250.468710159687, 6254.437100660872, 6280.874382512416, 6319.557630965788, 5736.4550367715, 5751.539876809845, 5760.36635970043, 5768.769249229984, 5789.2763404574425, 5376.758045887945, 5386.353637563518, 5402.081646120169, 5402.648827826179, 5412.810551630752, 6263.96868015657, 6286.308506064329, 6289.559730818799, 6295.905561416674, 6359.236891572856, 6323.430891219812, 6344.746210151542, 6349.301576826638, 6355.173827402408, 6360.968195158995, 6245.313015568193, 6248.968755156195, 6290.968545157245, 6295.937040629535, 6308.337624549356, 6039.818805435844, 6107.931112055284, 6126.426068710427, 6224.470919971853, 6252.468540173954, 6197.411245931629, 6312.936870631235, 6316.3367846377305, 6319.9052014218905, 6321.715522801393, 6055.788047418319, 6104.969475152596, 6129.417705317987, 6132.141500189888, 6132.5707200495535, 5899.528037757055, 5959.940400595939, 6080.939190608037, 6123.2652081750475, 6135.601185922901, 5839.854003649943, 5844.503217226414, 5857.121431785095, 5890.145928840208, 5930.703464826827, 5037.445880953019, 6096.908546371719, 6112.572119951561, 6112.572119951738, 6118.326984031842, 5729.684867332337, 5759.568032397503, 5760.596758226884, 5821.417858214314, 5894.056950887837], "value": 5663.351615085964, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.1716841012536, 488.4578118288697, 494.17719497037433, 505.6435213174712, 510.8135530531355, 27307.453850922993, 39809.40285895714, 40636.78089657307, 40767.79616101919, 43158.35262471066, 30700.385992280142, 42744.786276068575, 42994.570054299445, 43112.56887431125, 43131.3530297045, 8206.958965205165, 8405.327573794113, 8978.640854365834, 9200.447973121634, 9222.262219022463, 8412.327013838878, 8498.235158835734, 8510.574471276443, 10814.243002989793, 11021.44892755363, 11200.66398008057, 11270.605528806493, 11282.097432205444, 11293.604723834667, 11306.208565400384, 11108.888911110947, 11154.498047587878, 11256.437178141061, 11273.323600583992, 11282.0, 11030.889691103066, 11114.166437517219, 11114.61098861544, 11122.165837562216, 11125.332480051224, 32962.6703732962, 39350.0, 39617.40573891365, 39654.60345396538, 39789.602103978876, 3864.188520410717, 3881.4565960765517, 3949.9012524686827, 3952.960470395288, 4040.434339192486, 28654.570181447296, 28699.0, 29650.851745741347, 29864.402711945855, 30122.397552048835, 3050.4051709916575, 3121.391328690906, 3143.717065464119, 3245.9350812983844, 3279.9836000820083, 494785.0, 495489.52255238855, 498388.0, 498836.0, 500007.99999999633, 59393.40606593879, 59438.108428373605, 59557.702211488664, 59629.0, 59697.70151149217, 62929.68535157295, 63431.0, 63461.36538634555, 63528.682356587924, 63557.68221158957, 15731.0, 15775.684486310209, 15897.761533577006, 15904.681906361808, 15919.602009949613, 12697.492100316069, 12803.935980320039, 12810.551630693068, 12810.80783788244, 12820.423080961378, 13702.45190192401, 13901.16593004423, 15745.763813542806, 15808.0, 16205.513834584981, 6161.613636931655, 6721.665743360987, 6760.932390676128, 6795.18457785059, 6799.694013769392, 854.9529775862263, 856.7633054545214, 873.057098333798, 887.8268737596236, 890.3544929925752, 24977.0, 29161.979330723323, 29309.56035659467, 29656.555151672743, 29934.25164370908, 1041.8385150301565, 1053.204830353094, 1069.8341757027674, 1076.424113099488, 1170.163333216737, 14637.121772693672, 15155.318010689545, 15156.621084472756, 15456.459023934109, 15482.612934676723, 614032.0, 615661.0, 617998.0, 618009.0, 618399.0, 59345.70327148337, 59349.10976335358, 59410.81178376408, 59536.0, 59544.70227648921, 1269.3843485909326, 1387.4450219912117, 1442.8357463804084, 1447.0594113825887, 1447.1389523233659, 28280.43439131247, 29030.854845725637, 29150.562741558468, 29901.551476727433, 30123.849380752956, 48619.02761944671, 48909.0, 48929.0, 48994.0, 49026.01947961092, 476783.0, 479710.0, 480516.0, 480648.0, 480672.0, 38278.0, 38481.0, 38500.844974650805, 38888.80555597204, 39168.60831391763, 37537.624623754506, 44584.554154459336, 45514.77242613766, 45743.54256457393, 46266.30600541062, 36260.91217263486, 37467.62532374641, 37550.0, 37798.055048622904, 38936.80531597324, 36988.6301136996, 37365.626343736214, 38098.8095059534, 38257.74809141143, 38421.231575368896, 33849.0, 35441.1139721509, 35861.82069089638, 36086.45870311895, 36851.81574092113, 29988.70011299859, 32568.348633026733, 33729.91108204266, 33753.83123084369, 34012.31975360528, 23765.16821911225, 24091.674957877483, 24277.393065173514, 24440.75559244385, 24717.87641061783], "value": 19793.580803228968, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.6668747970728, 372.064258390149, 387.94956655634763, 394.302085309003, 422.7041071250124, 14706.705865882675, 15048.774268385969, 17394.304227830886, 19915.0, 20211.797882021176, 21163.57672846542, 21480.24819131327, 21564.245251416203, 21797.346079617597, 21850.0, 4664.97667511662, 5745.597808153431, 5971.731272092767, 6050.42520960508, 6061.696915154224, 5852.67810270435, 5899.823005309848, 5978.342382337939, 5994.490468310199, 6007.098935159717, 5584.636998595099, 5801.07182850744, 5876.382979787125, 5933.317668468138, 5950.880982380349, 5833.620814647034, 5884.529237660999, 5888.146218798272, 5895.587308888379, 5914.172015917775, 5049.671771334852, 5079.1365467870555, 5218.425973142989, 5225.111731005738, 5231.8168864089575, 22528.0, 22618.32145035635, 25974.22077337683, 26013.73986260132, 26315.07897223607, 2602.531544322021, 2692.6634170728657, 2694.946101077987, 2728.1679087878188, 2770.8476033818133, 14894.70210595793, 15207.695846083127, 15407.537773866801, 15924.646405055468, 16061.518154455385, 1760.7975082865378, 1774.2814160265114, 1781.9643607127784, 1791.605846713721, 1803.765510483642, 121042.39478802637, 129111.0, 138383.0, 144438.0, 151258.0, 9523.619055237852, 9869.654562090293, 11441.37072461006, 11936.641900742992, 11939.164258501994, 15829.97105188159, 15854.60363490922, 15871.20643967797, 15890.205489725467, 15905.840941590435, 8491.278241349555, 8504.44721093127, 8821.206091451671, 8962.417442866194, 8974.641014359484, 8594.87107693385, 8600.8709869352, 8626.39615226928, 8782.91217087821, 8792.47245165292, 4701.341812146302, 4784.40194975628, 4792.736399497991, 4888.877778055577, 4896.510348965075, 1962.3576027736503, 2026.3211824039113, 2028.4016215216548, 2032.1058734156923, 2038.143979528572, 971.3880255439028, 985.3348989432247, 986.6941248212934, 1002.3184234720314, 1050.4747626186906, 20438.89780551088, 20672.276470323468, 20678.483037924172, 20852.582948341253, 21091.261805836722, 1017.765913839821, 1020.8060468511108, 1030.1398332392537, 1037.5486663301488, 1040.2510192661273, 9921.950390248003, 10771.56913723458, 10818.675439736662, 10820.89179108199, 10914.399708016135, 739681.0, 743418.0, 751114.0, 757660.0, 784247.0, 140626.0, 140709.29645351708, 145085.0, 149261.0, 150121.24939375234, 959.6017652674062, 975.4001289207087, 996.4121168510613, 998.7153661206667, 998.8501724741202, 14516.274186290855, 16659.833401665826, 18301.816981830012, 18507.90746046261, 18607.0, 57605.423945760005, 57988.840223196145, 58429.415705842395, 59504.0, 59895.40104599072, 87161.69257461016, 87906.0, 88661.0, 90233.0, 91348.0, 7882.251186137537, 7906.8930349749935, 8219.547924863951, 8435.029971553104, 8489.915100849159, 11232.66302010941, 12304.569340073054, 12657.240565566095, 12657.430415631503, 12745.0, 10696.946515267375, 10747.677569672922, 10828.729281768019, 10915.290506117077, 11027.629059751871, 9838.671779309701, 10369.948150259503, 10491.370517768959, 10747.247692661751, 11057.0, 10891.727706807076, 10892.564297428014, 10935.45322733876, 11178.944105279421, 11308.378039207593, 7051.929480705127, 7435.51669141504, 7456.0, 8084.191580842104, 8355.498670079814, 8163.551004694561, 9613.903860961302, 10610.522526486173, 11192.664220073411, 11374.886251137383], "value": 9868.096620523584, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1317.9472821087156, 1426.985730142699, 1443.9278036098192, 1447.9782803257947, 1455.8034665320179, 8028.91971080289, 8454.661813527468, 8463.238308552236, 8474.661013559451, 8479.576021198938, 7396.0, 7669.769906902788, 7678.886561448587, 7988.7203947861835, 8088.59557022148, 2286.8056215221686, 2326.906923723049, 2335.1126571902655, 2336.8247381446376, 2339.7426283108844, 2290.9541809163807, 2320.0603755478974, 2327.8719670418122, 2329.196427232602, 2336.3691803213123, 2043.1520918818671, 2121.66053431451, 2234.0728597632046, 2292.381057114578, 2323.7327707313707, 2311.5839148953182, 2317.2700599311165, 2318.7565305642834, 2323.465602911323, 2324.0936034946385, 2250.3698964290024, 2312.086725743322, 2313.1672597864754, 2319.072371051576, 2323.500447403799, 8284.46151000183, 8307.044689860682, 8326.875096873551, 8339.332133573309, 8376.706815261437, 4505.5719706627815, 5212.556932660728, 5214.348206474191, 5216.686998780086, 5225.634205605609, 3295.6869097435706, 3456.94814577779, 3478.3738926993133, 3541.858325666996, 3544.6278140795102, 1120.1207052463858, 1203.5125774061519, 1206.5716670581992, 1230.3233221728103, 1234.487687609641, 10379.481025948748, 10831.2959657622, 10847.511861966152, 10851.457427128613, 10852.837207441895, 3809.0, 4405.84579539721, 4518.932216016762, 4550.522195169459, 4560.133574620878, 8555.700550480704, 8564.400491965502, 8668.523231222218, 8707.738767836974, 8714.0, 4415.40392047076, 5176.0, 5182.611304152204, 5186.818461353834, 5190.37715474138, 3796.185301237168, 4677.766111694402, 4819.783109760069, 4838.104950584153, 4856.587190088811, 3646.2342907989087, 3700.3524383232825, 3772.43413487979, 3794.2221844521805, 3797.6582107610448, 1154.740183458732, 1154.8325492803665, 1156.2022204678913, 1169.859616845984, 1171.0690001448697, 831.4927893984665, 859.6045818923366, 868.7567481105174, 887.9788243520004, 926.7312479381039, 5550.417206193372, 5573.5819813514145, 5614.29821272341, 5715.31416230055, 5906.852328691817, 933.2254228989922, 941.9623215071457, 955.4267439536361, 979.9314048016571, 986.5807032011367, 3639.745217834726, 3674.706023518112, 3717.498137751423, 3720.776753394805, 3758.0980564664283, 7877.0, 9614.0, 9633.759156021155, 9664.71005869825, 9973.601055957826, 9557.90442095584, 9574.425534467955, 9582.233421326417, 9582.329236953346, 9608.0, 7742.187070357531, 7900.486468379537, 7914.0, 7919.960400197962, 7936.484128531627, 4945.727984960861, 5024.196128619404, 5408.215808707794, 5451.400345962021, 5453.412122572025, 9040.63837446521, 9053.728388148365, 9165.908340916505, 9233.722988310094, 9393.24854011691, 8962.462252264884, 9326.207272381924, 9386.06139386056, 9474.763130921781, 9483.0, 5505.587080969023, 6295.496360291074, 6515.804525864231, 6706.8993965089585, 6869.725210991504, 7531.811704707207, 7567.9243207567215, 7599.620018998918, 7952.6421311042295, 8001.399895007781, 6652.068710380648, 6738.663066846736, 6754.763583274562, 6774.93225067743, 6852.691628876612, 6955.130608673916, 7018.684159212746, 7019.6490175492045, 7056.964715176391, 7063.081799366049, 5329.0, 6413.967930160319, 6425.903611445926, 6469.385408386196, 6492.772752953624, 4864.73243971585, 4884.584810291023, 4932.679375840559, 4984.2025275955675, 5008.3238762768015, 4887.877803054952, 4895.657303988686, 4905.754712264444, 5027.497250274943, 5030.0694371541385], "value": 4332.789102597593, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.37458137225053, 351.6465951718524, 360.6753921470676, 366.96146904575005, 370.2391585292225, 4646.744429056396, 4679.7130789033035, 4769.856904292877, 5203.375594928609, 5308.469153084695, 4612.492625811158, 4864.416270047591, 5028.723420211896, 5050.7222102784335, 5116.948830511694, 3980.7213495055357, 4061.3298805697023, 4071.6742660587083, 4095.488063992001, 4096.951524237881, 3710.2579484103153, 4023.577524359944, 4082.0, 4101.425800387948, 4102.917941641165, 3450.7584469087046, 3852.287326844543, 4045.9480535060948, 4061.2689715851134, 4064.268431682296, 3360.2190838855445, 3453.9309213815836, 3694.3165514379925, 3947.4276229946495, 4025.778582177979, 3082.7071428214285, 3297.6207736110173, 3532.4701294805727, 3559.8252576649643, 3612.6026137124895, 4032.4354590357375, 4800.975995120002, 5006.774695138727, 5049.267856160836, 5097.711975775641, 4572.359869618256, 4589.0, 4671.392718946515, 4677.368555245066, 4726.645501587394, 4949.851504454872, 5317.308749862532, 5381.282452361533, 5383.488568585978, 5386.865328366783, 3928.958825911136, 3976.642102210786, 3987.0, 3996.72022958393, 3998.520177578709, 4973.552380285755, 5314.256004159421, 5619.719014049281, 5651.858703532444, 5664.575156863293, 3837.328467518173, 3971.7021223407783, 3994.5206575210577, 4000.199860097943, 4041.4341992121126, 4069.1861627674584, 4119.258533464005, 4133.710640255214, 4152.6055024772595, 4178.35235538492, 3940.2907476653904, 3985.621365970228, 4230.936535951963, 4504.837677914619, 4733.715977041388, 4434.7560884151035, 5449.673019618756, 5485.369182543979, 5537.809370985257, 5632.915506267327, 1143.7826812905687, 1183.7336599265268, 1191.9106067044834, 1192.4276347353318, 1243.7885559454733, 713.3045280851129, 746.0227102495639, 808.5270116981515, 819.8401311744271, 821.4537332673824, 4454.643628509712, 4753.787784115098, 4762.9285560715925, 4784.593309568656, 4825.30033145192, 4811.446683631427, 4920.884019871418, 5140.971805638813, 5268.894622107537, 5398.676079435247, 4196.874093777192, 4781.163296423112, 4785.186518291899, 4809.615230781529, 4827.493113223131, 2253.797158255732, 2384.9284521464383, 2588.8446693198466, 2692.572936343741, 2751.802965709923, 4698.835540756126, 5025.070361983045, 5385.488378604026, 5447.155690867922, 5495.790925996274, 4861.149298872754, 5368.516833484927, 5371.838844834661, 5762.567807414461, 5803.564732645069, 5407.0, 5803.9105921868095, 5805.94194058054, 5855.3851845556455, 5993.730282137401, 4662.9898287473425, 5486.588505861996, 5693.5160511357, 5705.459525927893, 5717.0, 4527.683062185615, 4836.540528649773, 4902.460729319843, 5505.9724701376235, 5576.665400075846, 5082.847514574569, 5151.124308867426, 5703.173039909107, 5847.707614619337, 5851.271038305982, 3259.5006297103596, 3262.265990152197, 3549.846299952474, 3661.524001879845, 3773.4717139601025, 4682.946337074131, 5177.922331165111, 5316.548093411949, 5573.554115670818, 5625.240592519957, 4525.389072475174, 5026.396832380138, 5386.057439948072, 5407.864803379947, 5444.564434845282, 4489.910201796011, 5437.32033495813, 5437.4018857926385, 5497.84545245487, 5547.33431988164, 4367.934480982724, 4737.099951009297, 5223.347081614798, 5331.973340133274, 5441.972790136024, 5358.169483730028, 5491.752871120889, 5522.723863806874, 5529.695866727207, 5598.404454730382, 4762.666613337033, 5372.811951581676, 5399.433059528693, 5400.783968641211, 5431.0], "value": 3958.5566718818372, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [630.73509126167, 652.4193467813647, 702.2626242445431, 747.7681918605233, 782.8512582609305, 14717.558473245806, 15680.13759243244, 15719.60700982476, 15740.606484837885, 15767.975081619687, 14080.436782528686, 14137.858621413783, 14330.99683022189, 14495.347709353078, 14542.491012814553, 674.076515174212, 845.805464743111, 863.9308855291562, 865.1089377940731, 867.2888231650049, 693.9826504337407, 699.7760716570699, 699.8460338725472, 699.9825004374906, 700.1563116444697, 882.1839798186684, 905.4929239625798, 915.7115508614799, 917.2295271971543, 922.280621115531, 916.7708072981754, 917.4953775423558, 917.7567944494714, 917.7889085510303, 918.6555041859269, 680.7685386968454, 680.7889554238202, 681.7647911470535, 681.8295426143465, 682.6586706646676, 11879.643610691694, 12369.072319575978, 12926.578076411588, 14555.053921495175, 15030.398784048624, 14064.789028164585, 14385.712285754227, 14426.927865360605, 14466.05970611907, 14519.92740036293, 9659.372140810825, 11165.330080195214, 11208.21542492026, 11396.912151918941, 11853.762924741457, 3311.536384906115, 3686.3733165361955, 3753.8310776014873, 3874.8643797467234, 3879.7090218233743, 109460.0, 110228.0, 110471.89528104696, 111220.0, 111614.0, 10749.757541093708, 11094.611688590861, 11170.612131247077, 11249.381284029458, 11258.493367798468, 23368.88315558445, 23392.415189620395, 23910.0, 23914.163004294765, 23915.880420597787, 7418.146913105062, 7546.962265188639, 7596.430267729942, 7842.372610191171, 7879.684812607432, 4039.9520734211255, 4127.236461254617, 4128.174365126986, 4149.33610622299, 4175.31107367287, 3592.76647017943, 3594.964050359515, 3680.834362453696, 3703.222323312081, 3705.333040052816, 680.0717021265937, 709.4643544124257, 709.9112610923635, 712.398023669998, 726.0017475970541, 645.9612423254525, 657.4280376072848, 673.6631684157921, 675.6047712088483, 710.9395701365338, 9524.949383122344, 10103.696889093339, 10214.438205898747, 10391.948040259751, 10486.632967846088, 670.389945149921, 674.9122614060141, 690.520088538461, 695.9095317608779, 699.909011828459, 4386.188555117314, 4698.577128058491, 4701.318308845198, 4708.411448568929, 4720.197566413718, 70489.0, 78686.0, 95516.04483955071, 102340.48829755952, 103397.48301258446, 87593.0, 89078.66382004276, 89217.21565568652, 90583.54708226417, 94422.0, 76635.61682191554, 77946.0, 78376.2162378369, 79121.60439197767, 79916.80124798133, 9074.591643375932, 9127.58925848325, 9147.63409463614, 9197.824643058715, 9274.0, 51026.48973510217, 52153.7392313036, 52487.73756131348, 53496.73251633717, 53772.46227537675, 61636.0, 62348.0, 62538.68730656499, 66455.0, 71809.0, 13143.079984401, 13221.206727596375, 13273.867261327649, 13358.599242022754, 13924.094933829268, 13878.583642490741, 13928.651783705489, 14535.0, 14758.704825903636, 14913.105213687213, 12905.935470322589, 13527.0, 13675.24786136772, 13864.86135138676, 14044.648883777987, 11328.75383707808, 13202.735945281232, 13759.17444953306, 13771.724565508433, 13874.791878122038, 12716.491340346653, 12749.0, 13381.866181338062, 13731.176129432266, 13759.243241621807, 10539.841902371318, 11293.265937714023, 11510.424478776195, 11570.132240081859, 11740.823887641522, 10752.83870741955, 10823.783524329312, 10852.403117828595, 10910.23628346008, 10991.010809027226], "value": 7438.178167038188, "unit": "score"}], "name": "dromaeo_css", "value": 7215.648207852089, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 117.0, 115.0, 115.0, 116.0, 114.0, 115.0, 116.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 110.0, 155.0, 142.0, 108.0, 109.0, 110.0, 129.0, 109.0], "value": 122.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 186.0, 187.0, 188.0, 185.0, 185.0, 184.0, 185.0, 185.0, 184.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 60.0, 88.0, 61.0, 61.0, 78.0, 59.0, 65.0, 74.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 86.0, 71.0, 68.0, 68.0, 83.0, 66.0, 69.0, 82.0], "value": 74.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 91.0, 89.0, 87.0, 93.0, 90.0, 89.0, 85.0], "value": 89.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 97.0, 96.0, 96.0, 97.0, 108.0, 152.0, 150.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 100.0, 138.0, 113.0, 88.0, 86.0, 86.0, 85.0, 87.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 54.0, 53.0, 54.0, 55.0, 56.0, 58.0, 59.0, 58.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 75.0, 76.0, 72.0, 68.0, 70.0, 70.0, 70.0, 73.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 98.0, 94.0, 84.0, 88.0, 85.0, 87.0, 89.0, 83.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 153.0, 153.0, 146.0, 143.0, 141.0, 150.0, 181.0, 159.0], "value": 150.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 226.0, 162.0, 211.0, 154.0, 215.0, 210.0, 168.0, 167.0, 170.0], "value": 185.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 56.0, 73.0, 83.0, 84.0, 83.0, 82.0, 84.0, 69.0], "value": 74.8, "unit": "score"}], "name": "kraken", "value": 1483.8, "alertThreshold": 2.0}]} 02:39:48 INFO - Return code: 0 02:39:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:39:48 ERROR - # TBPL SUCCESS # 02:39:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:39:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:39:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:39:48 INFO - 2017-02-28 02:39:48.272 screenresolution[1953:22022] starting screenresolution argv=screenresolution get 02:39:48 INFO - 2017-02-28 02:39:48.275 screenresolution[1953:22022] Display 0: 1600x1200x32@60 02:39:48 INFO - 2017-02-28 02:39:48.281 screenresolution[1954:22025] starting screenresolution argv=screenresolution list 02:39:48 INFO - Available Modes on Display 0 02:39:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:39:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:39:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:39:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:39:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:39:48 INFO - Intel Iris: 02:39:48 INFO - Chipset Model: Intel Iris 02:39:48 INFO - Type: GPU 02:39:48 INFO - Bus: Built-In 02:39:48 INFO - VRAM (Dynamic, Max): 1536 MB 02:39:48 INFO - Vendor: Intel (0x8086) 02:39:48 INFO - Device ID: 0x0a2e 02:39:48 INFO - Revision ID: 0x0009 02:39:48 INFO - Displays: 02:39:48 INFO - Display: 02:39:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:39:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:39:48 INFO - Display Serial Number: 1600x1200 60 02:39:48 INFO - Main Display: Yes 02:39:48 INFO - Mirror: Off 02:39:48 INFO - Online: Yes 02:39:48 INFO - Rotation: Supported 02:39:48 INFO - Return code: 0 02:39:48 INFO - Running post-action listener: _resource_record_post_action 02:39:48 INFO - [mozharness: 2017-02-28 10:39:48.517499Z] Finished run-tests step (success) 02:39:48 INFO - Running post-run listener: _resource_record_post_run 02:39:48 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 655360; Write bytes: 507283456; Read time: 7; Write time: 1902 02:39:48 INFO - TinderboxPrint: CPU usage
53.6% 02:39:48 INFO - TinderboxPrint: I/O read bytes / time
655,360 / 7 02:39:48 INFO - TinderboxPrint: I/O write bytes / time
507,283,456 / 1,902 02:39:48 INFO - TinderboxPrint: CPU idle
2,227.4 (46.4%) 02:39:48 INFO - TinderboxPrint: CPU system
958.9 (20.0%) 02:39:48 INFO - TinderboxPrint: CPU user
1,611.4 (33.6%) 02:39:48 INFO - TinderboxPrint: Swap in / out
207,998,976 / 0 02:39:48 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200094208; Write bytes: 201197568; Read time: 15496; Write time: 528 02:39:48 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 655360; Write bytes: 296259584; Read time: 7; Write time: 1341 02:39:48 INFO - Running post-run listener: _upload_blobber_files 02:39:48 INFO - Blob upload gear active. 02:39:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:39:48 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 02:39:48 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:39:48 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:39:49 INFO - (blobuploader) - INFO - Open directory for files ... 02:39:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:39:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:39:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:50 INFO - (blobuploader) - INFO - Done attempting. 02:39:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:39:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:39:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:51 INFO - (blobuploader) - INFO - Done attempting. 02:39:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:39:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:39:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:52 INFO - (blobuploader) - INFO - Done attempting. 02:39:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:39:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:39:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:39:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:55 INFO - (blobuploader) - INFO - Done attempting. 02:39:55 INFO - (blobuploader) - INFO - Iteration through files over. 02:39:55 INFO - Return code: 0 02:39:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:39:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:39:55 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50faaa0e245748eeb3670f9cac28542c0cbe19062aca3542694dfbffa383aed0480cfb741599f45ac59594924e2f3e7acbdc09a2d1cd6c18ae5a3a0f4131d897", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ccdf750672297c617f58795267c6106d5a56c0a4e47382b03a468370887ba7edee97ae82697b9f71883f5069f7499bfb6b52a1aa944c4d284e9597f74ec9cda8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8d69637d25f7a2f06e95715b6d59e63ee1f32ceaf11528747f86de378e1d3750c627f66fff4f5d3e0f0189fba0b9b7516c5147c396ff0813779b6a5d1660d91", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a962138b61e2be5f279181042b137c9c2480771a9ea8a2570ad9fb9358817f3a769e955510574e95e01084d75bc3e4e3bce52ac4c55670954ff07aadb3563f0c"} 02:39:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:39:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:39:55 INFO - Contents: 02:39:55 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50faaa0e245748eeb3670f9cac28542c0cbe19062aca3542694dfbffa383aed0480cfb741599f45ac59594924e2f3e7acbdc09a2d1cd6c18ae5a3a0f4131d897", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ccdf750672297c617f58795267c6106d5a56c0a4e47382b03a468370887ba7edee97ae82697b9f71883f5069f7499bfb6b52a1aa944c4d284e9597f74ec9cda8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8d69637d25f7a2f06e95715b6d59e63ee1f32ceaf11528747f86de378e1d3750c627f66fff4f5d3e0f0189fba0b9b7516c5147c396ff0813779b6a5d1660d91", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a962138b61e2be5f279181042b137c9c2480771a9ea8a2570ad9fb9358817f3a769e955510574e95e01084d75bc3e4e3bce52ac4c55670954ff07aadb3563f0c"} 02:39:55 INFO - Running post-run listener: copy_logs_to_upload_dir 02:39:55 INFO - Copying logs to upload dir... 02:39:55 INFO - mkdir: /builds/slave/test/build/upload/logs 02:39:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1807.334792 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 30 mins, 7 secs) (at 2017-02-28 02:39:55.078634) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-28 02:39:55.081651) ========= 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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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/Mozilla-Aurora/sha512/50faaa0e245748eeb3670f9cac28542c0cbe19062aca3542694dfbffa383aed0480cfb741599f45ac59594924e2f3e7acbdc09a2d1cd6c18ae5a3a0f4131d897", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ccdf750672297c617f58795267c6106d5a56c0a4e47382b03a468370887ba7edee97ae82697b9f71883f5069f7499bfb6b52a1aa944c4d284e9597f74ec9cda8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8d69637d25f7a2f06e95715b6d59e63ee1f32ceaf11528747f86de378e1d3750c627f66fff4f5d3e0f0189fba0b9b7516c5147c396ff0813779b6a5d1660d91", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a962138b61e2be5f279181042b137c9c2480771a9ea8a2570ad9fb9358817f3a769e955510574e95e01084d75bc3e4e3bce52ac4c55670954ff07aadb3563f0c"} build_url:https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011578 build_url: 'https://queue.taskcluster.net/v1/task/AXfaVNsrQO-jNue4u8A4tg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/50faaa0e245748eeb3670f9cac28542c0cbe19062aca3542694dfbffa383aed0480cfb741599f45ac59594924e2f3e7acbdc09a2d1cd6c18ae5a3a0f4131d897", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ccdf750672297c617f58795267c6106d5a56c0a4e47382b03a468370887ba7edee97ae82697b9f71883f5069f7499bfb6b52a1aa944c4d284e9597f74ec9cda8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c8d69637d25f7a2f06e95715b6d59e63ee1f32ceaf11528747f86de378e1d3750c627f66fff4f5d3e0f0189fba0b9b7516c5147c396ff0813779b6a5d1660d91", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a962138b61e2be5f279181042b137c9c2480771a9ea8a2570ad9fb9358817f3a769e955510574e95e01084d75bc3e4e3bce52ac4c55670954ff07aadb3563f0c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-28 02:39:55.115411) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-28 02:39:55.115696) ========= 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.hq2cxmDOTq/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.ufbE5pz6DZ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.005476 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-28 02:39:55.163805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-28 02:39:55.164090) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-28 02:39:55.164412) ========= ========= Total master_lag: 1.80 =========