builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0296 starttime: 1486384184.23 results: success (0) buildid: 20170206030211 builduid: cc07653b2c2b4c4f917f0cb42279d413 revision: 20a8536b0bfac74389d3a57bd8dd957d98779ce1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:44.228851) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:44.229726) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:44.230071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:44.257700) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:44.258143) ========= 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.yVMLUlO5IV/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.AaTS429W7Q/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-06 04:29:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.3M=0.001s 2017-02-06 04:29:44 (16.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.610445 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:44.894475) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:44.894830) ========= 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.yVMLUlO5IV/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.AaTS429W7Q/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.025284 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:44.944076) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 04:29:44.944477) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 20a8536b0bfac74389d3a57bd8dd957d98779ce1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 20a8536b0bfac74389d3a57bd8dd957d98779ce1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yVMLUlO5IV/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.AaTS429W7Q/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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-06 04:29:45,054 truncating revision to first 12 chars 2017-02-06 04:29:45,054 Setting DEBUG logging. 2017-02-06 04:29:45,054 attempt 1/10 2017-02-06 04:29:45,054 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/20a8536b0bfa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 04:29:46,426 unpacking tar archive at: mozilla-central-20a8536b0bfa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.647034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-06 04:29:46.615927) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:46.616292) ========= script_repo_revision: 20a8536b0bfac74389d3a57bd8dd957d98779ce1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:46.616728) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:46.617046) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-06 04:29:46.637407) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-02-06 04:29:46.637846) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yVMLUlO5IV/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.AaTS429W7Q/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:29:46 INFO - MultiFileLogger online at 20170206 04:29:46 in /builds/slave/test 04:29:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:29:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:29:46 INFO - {'append_to_log': False, 04:29:46 INFO - 'base_work_dir': '/builds/slave/test', 04:29:46 INFO - 'blob_upload_branch': 'Firefox', 04:29:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:29:46 INFO - 'branch': 'Firefox', 04:29:46 INFO - 'buildbot_json_path': 'buildprops.json', 04:29:46 INFO - 'config_files': ('talos/mac_config.py',), 04:29:46 INFO - 'default_actions': ('clobber', 04:29:46 INFO - 'read-buildbot-config', 04:29:46 INFO - 'download-and-extract', 04:29:46 INFO - 'populate-webroot', 04:29:46 INFO - 'create-virtualenv', 04:29:46 INFO - 'install', 04:29:46 INFO - 'run-tests'), 04:29:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:29:46 INFO - 'download_minidump_stackwalk': True, 04:29:46 INFO - 'download_symbols': 'ondemand', 04:29:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:29:46 INFO - 'tooltool.py': '/tools/tooltool.py', 04:29:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:29:46 INFO - '/tools/misc-python/virtualenv.py')}, 04:29:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:29:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:29:46 INFO - 'installer_path': 'installer.exe', 04:29:46 INFO - 'log_level': 'info', 04:29:46 INFO - 'log_name': 'talos', 04:29:46 INFO - 'log_to_console': True, 04:29:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:29:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:29:46 INFO - 'opt_config_files': (), 04:29:46 INFO - 'pip_index': False, 04:29:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:46 INFO - 'cmd': ('bash', 04:29:46 INFO - '-c', 04:29:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:46 INFO - 'enabled': True, 04:29:46 INFO - 'halt_on_failure': False, 04:29:46 INFO - 'name': 'check_screen_resolution'},), 04:29:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:29:46 INFO - 'cmd': ('bash', 04:29:46 INFO - '-c', 04:29:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:29:46 INFO - 'enabled': True, 04:29:46 INFO - 'halt_on_failure': False, 04:29:46 INFO - 'name': 'check_screen_resolution'},), 04:29:46 INFO - 'run_cmd_checks_enabled': True, 04:29:46 INFO - 'sps_profile': False, 04:29:46 INFO - 'sps_profile_interval': 0, 04:29:46 INFO - 'suite': 'dromaeojs', 04:29:46 INFO - 'system_bits': '32', 04:29:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:29:46 INFO - 'title': 't-yosemite-r7-0296', 04:29:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:29:46 INFO - 'use_talos_json': True, 04:29:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:29:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:29:46 INFO - 'work_dir': 'build'} 04:29:46 INFO - [mozharness: 2017-02-06 12:29:46.855075Z] Running clobber step. 04:29:46 INFO - Running pre-action listener: _resource_record_pre_action 04:29:46 INFO - Running main action method: clobber 04:29:46 INFO - rmtree: /builds/slave/test/build 04:29:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:29:49 INFO - Running post-action listener: _resource_record_post_action 04:29:49 INFO - [mozharness: 2017-02-06 12:29:49.305793Z] Finished clobber step (success) 04:29:49 INFO - [mozharness: 2017-02-06 12:29:49.305946Z] Running read-buildbot-config step. 04:29:49 INFO - Running pre-action listener: _resource_record_pre_action 04:29:49 INFO - Running main action method: read_buildbot_config 04:29:49 INFO - Using buildbot properties: 04:29:49 INFO - { 04:29:49 INFO - "project": "", 04:29:49 INFO - "product": "firefox", 04:29:49 INFO - "script_repo_revision": "production", 04:29:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:29:49 INFO - "repository": "", 04:29:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:29:49 INFO - "buildid": "20170206030211", 04:29:49 INFO - "stage_platform": "macosx64", 04:29:49 INFO - "basedir": "/builds/slave/test", 04:29:49 INFO - "nightly_build": "True", 04:29:49 INFO - "buildnumber": 80, 04:29:49 INFO - "slavename": "t-yosemite-r7-0296", 04:29:49 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:29:49 INFO - "platform": "yosemite_r7", 04:29:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:29:49 INFO - "branch": "mozilla-central", 04:29:49 INFO - "repo_path": "mozilla-central", 04:29:49 INFO - "revision": "20a8536b0bfac74389d3a57bd8dd957d98779ce1", 04:29:49 INFO - "pgo_build": "False", 04:29:49 INFO - "builduid": "cc07653b2c2b4c4f917f0cb42279d413", 04:29:49 INFO - "slavebuilddir": "test" 04:29:49 INFO - } 04:29:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 04:29:49 INFO - Running post-action listener: _resource_record_post_action 04:29:49 INFO - [mozharness: 2017-02-06 12:29:49.309456Z] Finished read-buildbot-config step (success) 04:29:49 INFO - [mozharness: 2017-02-06 12:29:49.309604Z] Running download-and-extract step. 04:29:49 INFO - Running pre-action listener: _resource_record_pre_action 04:29:49 INFO - Running main action method: download_and_extract 04:29:49 INFO - mkdir: /builds/slave/test/build/tests 04:29: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')]} 04:29:49 INFO - trying https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 04:29:49 INFO - Downloading https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:29:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 04:29:50 INFO - Downloaded 1199 bytes. 04:29:50 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 04:29:50 INFO - Using the following test package requirements: 04:29:50 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:29:50 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:50 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 04:29:50 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:50 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 04:29:50 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:50 INFO - u'jsshell-mac.zip'], 04:29:50 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:50 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 04:29:50 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 04:29:50 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:50 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 04:29:50 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:50 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 04:29:50 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:50 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 04:29:50 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 04:29:50 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 04:29:50 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:29:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:29:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:29:50 INFO - Fetch https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:29:52 INFO - Expected file size: 34851177 04:29:52 INFO - Obtained file size: 34851177 04:29:59 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:29:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 04:29:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:29:59 INFO - Fetch https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 04:30:01 INFO - Expected file size: 34851177 04:30:01 INFO - Obtained file size: 34851177 04:30:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 04:30:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:30:08 INFO - Fetch https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 04:30:10 INFO - Expected file size: 14403454 04:30:10 INFO - Obtained file size: 14403454 04:30:11 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')]} 04:30:11 INFO - trying https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:30:11 INFO - Downloading https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 04:30:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 04:30:13 INFO - Downloaded 70362832 bytes. 04:30:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:30:13 INFO - mkdir: /builds/slave/test/properties 04:30:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:30:13 INFO - Writing to file /builds/slave/test/properties/build_url 04:30:13 INFO - Contents: 04:30:13 INFO - build_url:https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 04:30:14 INFO - Running post-action listener: _resource_record_post_action 04:30:14 INFO - Running post-action listener: set_extra_try_arguments 04:30:14 INFO - [mozharness: 2017-02-06 12:30:14.509109Z] Finished download-and-extract step (success) 04:30:14 INFO - [mozharness: 2017-02-06 12:30:14.509244Z] Running populate-webroot step. 04:30:14 INFO - Running pre-action listener: _resource_record_pre_action 04:30:14 INFO - Running main action method: populate_webroot 04:30:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:30:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:30:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:30:14 INFO - '--tppagecycles', 04:30:14 INFO - '7'], 04:30:14 INFO - 'tests': ['tsvgm']}}, 04:30:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:30:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:30:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:30:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:30:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:30:14 INFO - 'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:30:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:30:14 INFO - 'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['damp', 'tps']}, 04:30:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:30:14 INFO - 'tests': ['damp', 'tps']}, 04:30:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['dromaeo_dom']}, 04:30:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:30:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['basic_compositor_video']}, 04:30:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:30:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['a11yr', 04:30:14 INFO - 'ts_paint', 04:30:14 INFO - 'tpaint', 04:30:14 INFO - 'sessionrestore', 04:30:14 INFO - 'sessionrestore_no_auto_restore', 04:30:14 INFO - 'tabpaint']}, 04:30:14 INFO - 'other-e10s': {'tests': ['a11yr', 04:30:14 INFO - 'ts_paint', 04:30:14 INFO - 'tpaint', 04:30:14 INFO - 'sessionrestore', 04:30:14 INFO - 'sessionrestore_no_auto_restore', 04:30:14 INFO - 'tabpaint']}, 04:30:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:30:14 INFO - 'ts_paint', 04:30:14 INFO - 'tpaint', 04:30:14 INFO - 'sessionrestore', 04:30:14 INFO - 'sessionrestore_no_auto_restore', 04:30:14 INFO - 'tabpaint']}, 04:30:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:30:14 INFO - 'ts_paint', 04:30:14 INFO - 'tpaint', 04:30:14 INFO - 'sessionrestore', 04:30:14 INFO - 'sessionrestore_no_auto_restore', 04:30:14 INFO - 'tabpaint']}, 04:30:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['a11yr', 04:30:14 INFO - 'ts_paint', 04:30:14 INFO - 'tpaint', 04:30:14 INFO - 'sessionrestore', 04:30:14 INFO - 'sessionrestore_no_auto_restore', 04:30:14 INFO - 'tabpaint']}, 04:30:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['a11yr', 04:30:14 INFO - 'ts_paint', 04:30:14 INFO - 'tpaint', 04:30:14 INFO - 'sessionrestore', 04:30:14 INFO - 'sessionrestore_no_auto_restore', 04:30:14 INFO - 'tabpaint']}, 04:30:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['tsvgx', 04:30:14 INFO - 'tsvgr_opacity', 04:30:14 INFO - 'tart', 04:30:14 INFO - 'tscrollx', 04:30:14 INFO - 'cart', 04:30:14 INFO - 'tsvg_static']}, 04:30:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:30:14 INFO - 'tsvgr_opacity', 04:30:14 INFO - 'tart', 04:30:14 INFO - 'tscrollx', 04:30:14 INFO - 'cart', 04:30:14 INFO - 'tsvg_static']}, 04:30:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:30:14 INFO - 'talos_options': ['--disable-e10s'], 04:30:14 INFO - 'tests': ['tp5o']}, 04:30:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:30:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:30:14 INFO - 'talos_options': ['--disable-e10s', 04:30:14 INFO - '--xperf_path', 04:30:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:14 INFO - 'tests': ['tp5n']}, 04:30:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:30:14 INFO - 'talos_options': ['--xperf_path', 04:30:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:14 INFO - 'tests': ['tp5n']}}, 04:30:14 INFO - 'talos.zip': {'path': '', 04:30:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:30:14 INFO - Running post-action listener: _resource_record_post_action 04:30:14 INFO - [mozharness: 2017-02-06 12:30:14.527779Z] Finished populate-webroot step (success) 04:30:14 INFO - [mozharness: 2017-02-06 12:30:14.527917Z] Running create-virtualenv step. 04:30:14 INFO - Running pre-action listener: _resource_record_pre_action 04:30:14 INFO - Running main action method: create_virtualenv 04:30:14 INFO - Creating virtualenv /builds/slave/test/build/venv 04:30:14 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 04:30:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:30:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:30:14 INFO - Using real prefix '/tools/python27' 04:30:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:30:15 INFO - Installing distribute.............................................................................................................................................................................................done. 04:30:18 INFO - Installing pip.................done. 04:30:18 INFO - Return code: 0 04:30:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:30:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:30:18 INFO - Reading from file tmpfile_stdout 04:30:18 INFO - Output received: 04:30:18 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) 04:30:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30: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', 'pip>=1.5'] in /builds/slave/test/build 04:30: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 pip>=1.5 04:30:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:19 INFO - 'HOME': '/Users/cltbld', 04:30:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:19 INFO - 'LOGNAME': 'cltbld', 04:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:19 INFO - 'MOZ_NO_REMOTE': '1', 04:30:19 INFO - 'NO_EM_RESTART': '1', 04:30:19 INFO - 'PAGER': '/bin/cat', 04:30:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:19 INFO - 'PWD': '/builds/slave/test', 04:30:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:19 INFO - 'SHELL': '/bin/bash', 04:30:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:19 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:19 INFO - 'USER': 'cltbld', 04:30:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:19 INFO - 'XPC_FLAGS': '0x0', 04:30:19 INFO - 'XPC_SERVICE_NAME': '0', 04:30:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:19 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 04:30:19 INFO - Cleaning up... 04:30:19 INFO - Return code: 0 04:30:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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', '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 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30: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', 'psutil>=3.1.1'] in /builds/slave/test/build 04:30: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 psutil>=3.1.1 04:30:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:19 INFO - 'HOME': '/Users/cltbld', 04:30:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:19 INFO - 'LOGNAME': 'cltbld', 04:30:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:19 INFO - 'MOZ_NO_REMOTE': '1', 04:30:19 INFO - 'NO_EM_RESTART': '1', 04:30:19 INFO - 'PAGER': '/bin/cat', 04:30:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:19 INFO - 'PWD': '/builds/slave/test', 04:30:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:19 INFO - 'SHELL': '/bin/bash', 04:30:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:19 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:19 INFO - 'USER': 'cltbld', 04:30:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:19 INFO - 'XPC_FLAGS': '0x0', 04:30:19 INFO - 'XPC_SERVICE_NAME': '0', 04:30:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:19 INFO - Downloading/unpacking psutil>=3.1.1 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:19 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 04:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:30:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:25 INFO - Installing collected packages: psutil 04:30:25 INFO - Running setup.py install for psutil 04:30:25 INFO - building 'psutil._psutil_osx' extension 04:30:25 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 04:30:25 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 04:30:25 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 04:30:25 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 04:30:26 INFO - building 'psutil._psutil_posix' extension 04:30:26 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 04:30:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:30:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:30:26 INFO - ^ 04:30:26 INFO - 1 warning generated. 04:30:26 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 04:30:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:26 INFO - Successfully installed psutil 04:30:26 INFO - Cleaning up... 04:30:26 INFO - Return code: 0 04:30:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:30: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 mozsystemmonitor==0.3 04:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:26 INFO - 'HOME': '/Users/cltbld', 04:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:26 INFO - 'LOGNAME': 'cltbld', 04:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:26 INFO - 'MOZ_NO_REMOTE': '1', 04:30:26 INFO - 'NO_EM_RESTART': '1', 04:30:26 INFO - 'PAGER': '/bin/cat', 04:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:26 INFO - 'PWD': '/builds/slave/test', 04:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:26 INFO - 'SHELL': '/bin/bash', 04:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:26 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:26 INFO - 'USER': 'cltbld', 04:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:26 INFO - 'XPC_FLAGS': '0x0', 04:30:26 INFO - 'XPC_SERVICE_NAME': '0', 04:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:30:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:30:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:30:29 INFO - Installing collected packages: mozsystemmonitor 04:30:29 INFO - Running setup.py install for mozsystemmonitor 04:30:29 INFO - Successfully installed mozsystemmonitor 04:30:29 INFO - Cleaning up... 04:30:29 INFO - Return code: 0 04:30:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:30:29 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')]} 04:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:29 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')]} 04:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:29 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 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30:29 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 04:30:29 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 04:30:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:29 INFO - 'HOME': '/Users/cltbld', 04:30:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:29 INFO - 'LOGNAME': 'cltbld', 04:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:29 INFO - 'MOZ_NO_REMOTE': '1', 04:30:29 INFO - 'NO_EM_RESTART': '1', 04:30:29 INFO - 'PAGER': '/bin/cat', 04:30:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:29 INFO - 'PWD': '/builds/slave/test', 04:30:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:29 INFO - 'SHELL': '/bin/bash', 04:30:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:29 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:29 INFO - 'USER': 'cltbld', 04:30:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:29 INFO - 'XPC_FLAGS': '0x0', 04:30:29 INFO - 'XPC_SERVICE_NAME': '0', 04:30:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:29 INFO - Downloading/unpacking jsonschema==2.5.1 04:30:29 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 04:30:29 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 04:30:29 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 04:30:29 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 04:30:29 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 04:30:29 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 04:30:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:34 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:34 INFO - warning: no files found matching '*.txt' 04:30:34 INFO - no previously-included directories found matching 'build' 04:30:34 INFO - no previously-included directories found matching 'dist' 04:30:34 INFO - no previously-included directories found matching '.git*' 04:30:34 INFO - Installing collected packages: jsonschema, functools32 04:30:34 INFO - Running setup.py install for functools32 04:30:34 INFO - warning: no files found matching '*.txt' 04:30:34 INFO - no previously-included directories found matching 'build' 04:30:34 INFO - no previously-included directories found matching 'dist' 04:30:34 INFO - no previously-included directories found matching '.git*' 04:30:34 INFO - Successfully installed jsonschema functools32 04:30:34 INFO - Cleaning up... 04:30:34 INFO - Return code: 0 04:30:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:30:34 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')]} 04:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:34 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')]} 04:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:34 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 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30:34 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 04:30:34 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 04:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:34 INFO - 'HOME': '/Users/cltbld', 04:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:34 INFO - 'LOGNAME': 'cltbld', 04:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:34 INFO - 'MOZ_NO_REMOTE': '1', 04:30:34 INFO - 'NO_EM_RESTART': '1', 04:30:34 INFO - 'PAGER': '/bin/cat', 04:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:34 INFO - 'PWD': '/builds/slave/test', 04:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:34 INFO - 'SHELL': '/bin/bash', 04:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:34 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:34 INFO - 'USER': 'cltbld', 04:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:34 INFO - 'XPC_FLAGS': '0x0', 04:30:34 INFO - 'XPC_SERVICE_NAME': '0', 04:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:30:35 INFO - Cleaning up... 04:30:35 INFO - Return code: 0 04:30:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:30:35 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')]} 04:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:35 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')]} 04:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:35 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 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30:35 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 04:30:35 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 04:30:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:35 INFO - 'HOME': '/Users/cltbld', 04:30:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:35 INFO - 'LOGNAME': 'cltbld', 04:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:35 INFO - 'MOZ_NO_REMOTE': '1', 04:30:35 INFO - 'NO_EM_RESTART': '1', 04:30:35 INFO - 'PAGER': '/bin/cat', 04:30:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:35 INFO - 'PWD': '/builds/slave/test', 04:30:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:35 INFO - 'SHELL': '/bin/bash', 04:30:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:35 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:35 INFO - 'USER': 'cltbld', 04:30:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:35 INFO - 'XPC_FLAGS': '0x0', 04:30:35 INFO - 'XPC_SERVICE_NAME': '0', 04:30:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:35 INFO - Downloading/unpacking blobuploader==1.2.4 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30: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 04:30:39 INFO - Downloading blobuploader-1.2.4.tar.gz 04:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:30:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:40 INFO - Downloading docopt-0.6.1.tar.gz 04:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:40 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:40 INFO - Running setup.py install for blobuploader 04:30:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:40 INFO - Running setup.py install for requests 04:30:41 INFO - Running setup.py install for docopt 04:30:41 INFO - Successfully installed blobuploader requests docopt 04:30:41 INFO - Cleaning up... 04:30:41 INFO - Return code: 0 04:30:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:41 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 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30:41 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 04:30:41 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 04:30:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:41 INFO - 'HOME': '/Users/cltbld', 04:30:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:41 INFO - 'LOGNAME': 'cltbld', 04:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:41 INFO - 'MOZ_NO_REMOTE': '1', 04:30:41 INFO - 'NO_EM_RESTART': '1', 04:30:41 INFO - 'PAGER': '/bin/cat', 04:30:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:41 INFO - 'PWD': '/builds/slave/test', 04:30:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:41 INFO - 'SHELL': '/bin/bash', 04:30:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:41 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:41 INFO - 'USER': 'cltbld', 04:30:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:41 INFO - 'XPC_FLAGS': '0x0', 04:30:41 INFO - 'XPC_SERVICE_NAME': '0', 04:30:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:41 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-305ezi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:41 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-D2kWtI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:41 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Gh6cUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:41 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8bukCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-pXcnGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-NMPjNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-FoowhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-00gn3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-k2JPI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-DgoOAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-LPI4uE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-tUSjoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ouiedN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:42 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-AomDtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:43 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-B1mSNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:43 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-5YoNgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:43 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KMm5hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:30:43 INFO - Running setup.py install for manifestparser 04:30:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:30:43 INFO - Running setup.py install for mozcrash 04:30:43 INFO - Running setup.py install for mozdebug 04:30:43 INFO - Running setup.py install for mozdevice 04:30:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:30:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:30:43 INFO - Running setup.py install for mozfile 04:30:44 INFO - Running setup.py install for mozhttpd 04:30:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:30:44 INFO - Running setup.py install for mozinfo 04:30:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:30:44 INFO - Running setup.py install for mozInstall 04:30:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:30:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:30:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:30:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:30:44 INFO - Running setup.py install for mozleak 04:30:44 INFO - Running setup.py install for mozlog 04:30:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:30:44 INFO - Running setup.py install for moznetwork 04:30:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:30:45 INFO - Running setup.py install for mozprocess 04:30:45 INFO - Running setup.py install for mozprofile 04:30:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:30:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:30:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:30:45 INFO - Running setup.py install for mozrunner 04:30:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:30:45 INFO - Running setup.py install for mozscreenshot 04:30:45 INFO - Running setup.py install for moztest 04:30:45 INFO - Running setup.py install for mozversion 04:30:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:30:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:30:45 INFO - Cleaning up... 04:30:45 INFO - Return code: 0 04:30:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:45 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')]} 04:30:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:45 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')]} 04:30:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:45 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 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30:45 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 04:30:45 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 04:30:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:45 INFO - 'HOME': '/Users/cltbld', 04:30:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:45 INFO - 'LOGNAME': 'cltbld', 04:30:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:45 INFO - 'MOZ_NO_REMOTE': '1', 04:30:45 INFO - 'NO_EM_RESTART': '1', 04:30:45 INFO - 'PAGER': '/bin/cat', 04:30:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:45 INFO - 'PWD': '/builds/slave/test', 04:30:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:45 INFO - 'SHELL': '/bin/bash', 04:30:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:45 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:45 INFO - 'USER': 'cltbld', 04:30:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:45 INFO - 'XPC_FLAGS': '0x0', 04:30:45 INFO - 'XPC_SERVICE_NAME': '0', 04:30:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:46 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ihknx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:46 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)) 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:46 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-2VJPFz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:46 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)) 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:46 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-d_Xe7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:46 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)) 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:46 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-moNtuo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:46 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)) 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:46 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-eo9zQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:46 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)) 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:46 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8Ik0MR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:46 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)) 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:46 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-9XzXPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:46 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)) 04:30:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:46 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-bk6woF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-JtXgIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ImpZqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-GIV_Lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-z_mB3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-8KvZDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-HADu4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-FxmEKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KX1pHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:47 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)) 04:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:47 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KwqDDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:48 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)) 04:30:48 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)) 04:30:48 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)) 04:30:48 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)) 04:30:48 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)) 04:30:48 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)) 04:30:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:30:48 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 04:30:48 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 04:30:48 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 04:30:48 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 04:30:48 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 04:30:48 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 04:30:50 INFO - Downloading blessings-1.6.tar.gz 04:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:30:50 INFO - Installing collected packages: blessings 04:30:50 INFO - Running setup.py install for blessings 04:30:50 INFO - Successfully installed blessings 04:30:50 INFO - Cleaning up... 04:30:51 INFO - Return code: 0 04:30:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:51 INFO - Reading from file tmpfile_stdout 04:30:51 INFO - Current package versions: 04:30:51 INFO - blessings == 1.6 04:30:51 INFO - blobuploader == 1.2.4 04:30:51 INFO - docopt == 0.6.1 04:30:51 INFO - functools32 == 3.2.3-2 04:30:51 INFO - jsonschema == 2.5.1 04:30:51 INFO - manifestparser == 1.1 04:30:51 INFO - mozInstall == 1.12 04:30:51 INFO - mozcrash == 1.0 04:30:51 INFO - mozdebug == 0.1 04:30:51 INFO - mozdevice == 0.49 04:30:51 INFO - mozfile == 1.2 04:30:51 INFO - mozhttpd == 0.7 04:30:51 INFO - mozinfo == 0.9 04:30:51 INFO - mozleak == 0.1 04:30:51 INFO - mozlog == 3.4 04:30:51 INFO - moznetwork == 0.27 04:30:51 INFO - mozprocess == 0.24 04:30:51 INFO - mozprofile == 0.28 04:30:51 INFO - mozrunner == 6.13 04:30:51 INFO - mozscreenshot == 0.1 04:30:51 INFO - mozsystemmonitor == 0.3 04:30:51 INFO - moztest == 0.8 04:30:51 INFO - mozversion == 1.4 04:30:51 INFO - psutil == 3.1.1 04:30:51 INFO - requests == 1.2.3 04:30:51 INFO - wsgiref == 0.1.2 04:30:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:51 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 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30:51 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 04:30:51 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 04:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:51 INFO - 'HOME': '/Users/cltbld', 04:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:51 INFO - 'LOGNAME': 'cltbld', 04:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:51 INFO - 'MOZ_NO_REMOTE': '1', 04:30:51 INFO - 'NO_EM_RESTART': '1', 04:30:51 INFO - 'PAGER': '/bin/cat', 04:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:51 INFO - 'PWD': '/builds/slave/test', 04:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:51 INFO - 'SHELL': '/bin/bash', 04:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:51 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:51 INFO - 'USER': 'cltbld', 04:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:51 INFO - 'XPC_FLAGS': '0x0', 04:30:51 INFO - 'XPC_SERVICE_NAME': '0', 04:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 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)) 04:30:51 INFO - Cleaning up... 04:30:51 INFO - Return code: 0 04:30:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:51 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 0x10cc8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8eb04bd010>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, '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.yVMLUlO5IV/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.AaTS429W7Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 04:30:51 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 04:30:51 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 04:30:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:30:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:30:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:30:51 INFO - 'HOME': '/Users/cltbld', 04:30:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:30:51 INFO - 'LOGNAME': 'cltbld', 04:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:51 INFO - 'MOZ_NO_REMOTE': '1', 04:30:51 INFO - 'NO_EM_RESTART': '1', 04:30:51 INFO - 'PAGER': '/bin/cat', 04:30:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:51 INFO - 'PWD': '/builds/slave/test', 04:30:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:30:51 INFO - 'SHELL': '/bin/bash', 04:30:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:30:51 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:30:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:30:51 INFO - 'USER': 'cltbld', 04:30:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:30:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:51 INFO - 'XPC_FLAGS': '0x0', 04:30:51 INFO - 'XPC_SERVICE_NAME': '0', 04:30:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:30:51 INFO - Cleaning up... 04:30:51 INFO - Return code: 0 04:30:51 INFO - Running post-action listener: _resource_record_post_action 04:30:51 INFO - Running post-action listener: _start_resource_monitoring 04:30:51 INFO - Starting resource monitoring. 04:30:51 INFO - [mozharness: 2017-02-06 12:30:51.874457Z] Finished create-virtualenv step (success) 04:30:51 INFO - [mozharness: 2017-02-06 12:30:51.875437Z] Running install step. 04:30:51 INFO - Running pre-action listener: _resource_record_pre_action 04:30:51 INFO - Running main action method: install 04:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:30:52 INFO - Reading from file tmpfile_stdout 04:30:52 INFO - Detecting whether we're running mozinstall >=1.0... 04:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:30:52 INFO - Reading from file tmpfile_stdout 04:30:52 INFO - Output received: 04:30:52 INFO - Usage: mozinstall [options] installer 04:30:52 INFO - Options: 04:30:52 INFO - -h, --help show this help message and exit 04:30:52 INFO - -d DEST, --destination=DEST 04:30:52 INFO - Directory to install application into. [default: 04:30:52 INFO - "/builds/slave/test"] 04:30:52 INFO - --app=APP Application being installed. [default: firefox] 04:30:52 INFO - mkdir: /builds/slave/test/build/application 04:30:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:31:14 INFO - Reading from file tmpfile_stdout 04:31:14 INFO - Output received: 04:31:14 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:31:14 INFO - Running post-action listener: _resource_record_post_action 04:31:14 INFO - [mozharness: 2017-02-06 12:31:14.363960Z] Finished install step (success) 04:31:14 INFO - [mozharness: 2017-02-06 12:31:14.364120Z] Running run-tests step. 04:31:14 INFO - Running pre-action listener: _resource_record_pre_action 04:31:14 INFO - Running main action method: run_tests 04:31:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:31:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:31:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:31:14 INFO - 2017-02-06 04:31:14.378 screenresolution[1633:8173] starting screenresolution argv=screenresolution get 04:31:14 INFO - 2017-02-06 04:31:14.381 screenresolution[1633:8173] Display 0: 1600x1200x32@60 04:31:14 INFO - 2017-02-06 04:31:14.386 screenresolution[1634:8176] starting screenresolution argv=screenresolution list 04:31:14 INFO - Available Modes on Display 0 04:31:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:31:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:31:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:31:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:31:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:31:14 INFO - Intel Iris: 04:31:14 INFO - Chipset Model: Intel Iris 04:31:14 INFO - Type: GPU 04:31:14 INFO - Bus: Built-In 04:31:14 INFO - VRAM (Dynamic, Max): 1536 MB 04:31:14 INFO - Vendor: Intel (0x8086) 04:31:14 INFO - Device ID: 0x0a2e 04:31:14 INFO - Revision ID: 0x0009 04:31:14 INFO - Displays: 04:31:14 INFO - Display: 04:31:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:31:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:31:14 INFO - Display Serial Number: 1600x1200 60 04:31:14 INFO - Main Display: Yes 04:31:14 INFO - Mirror: Off 04:31:14 INFO - Online: Yes 04:31:14 INFO - Rotation: Supported 04:31:14 INFO - Return code: 0 04:31:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:31:14 INFO - Python 2.7.3 04:31:14 INFO - Return code: 0 04:31:14 INFO - grabbing minidump binary from tooltool 04:31: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')]} 04:31:14 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 0x10ca6cd30>, '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 0x10c993db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca61be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:31:14 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 04:31:14 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 04:31:14 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 04:31:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:31:14 INFO - Return code: 0 04:31:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:31:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:31:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:31:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:31:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:31:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 04:31:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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 04:31:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yVMLUlO5IV/Render', 04:31:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:31:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:31:14 INFO - 'HOME': '/Users/cltbld', 04:31:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:31:14 INFO - 'LOGNAME': 'cltbld', 04:31:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:31:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:31:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:14 INFO - 'MOZ_NO_REMOTE': '1', 04:31:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:31:14 INFO - 'NO_EM_RESTART': '1', 04:31:14 INFO - 'PAGER': '/bin/cat', 04:31:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:31:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:14 INFO - 'PWD': '/builds/slave/test', 04:31:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:31:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:31:14 INFO - 'SHELL': '/bin/bash', 04:31:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AaTS429W7Q/Listeners', 04:31:14 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 04:31:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:31:14 INFO - 'USER': 'cltbld', 04:31:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:31:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:31:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:14 INFO - 'XPC_FLAGS': '0x0', 04:31:14 INFO - 'XPC_SERVICE_NAME': '0', 04:31:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:31:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 04:31:14 INFO - mozversion application_buildid: 20170206030211 04:31:14 INFO - mozversion application_changeset: 20a8536b0bfac74389d3a57bd8dd957d98779ce1 04:31:14 INFO - mozversion application_display_name: Nightly 04:31:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:31:14 INFO - mozversion application_name: Firefox 04:31:14 INFO - mozversion application_remotingname: firefox 04:31:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:31:14 INFO - mozversion application_vendor: Mozilla 04:31:14 INFO - mozversion application_version: 54.0a1 04:31:14 INFO - mozversion platform_buildid: 20170206030211 04:31:14 INFO - mozversion platform_changeset: 20a8536b0bfac74389d3a57bd8dd957d98779ce1 04:31:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:31:14 INFO - mozversion platform_version: 54.0a1 04:31:14 INFO - using testdate: 1486384274 04:31:14 INFO - actual date: 1486384274 04:31:14 INFO - starting webserver on 'localhost:49244' 04:31:14 INFO - SUITE-START | Running 2 tests 04:31:14 INFO - TEST-START | dromaeo_css 04:31:14 INFO - operating with platform_type : mac_ 04:31:14 INFO - Initialising browser for dromaeo_css test... 04:31:15 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpT3f_fX/profile http://localhost:49244/getInfo.html) 04:31:17 INFO - PROCESS | 1644 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:31:17 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 04:31:17 INFO - PROCESS | 1644 | colorDepth:24 04:31:17 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 04:31:17 INFO - PROCESS | 1644 | __metrics 04:31:17 INFO - PROCESS | 1644 | 1486384277562 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:31:17 INFO - PROCESS | 1644 | 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" 04:31:17 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:31:18 INFO - TEST-INFO | 1644: exit 0 04:31:18 INFO - Browser initialized. 04:31:18 INFO - Running cycle 1/1 for dromaeo_css test... 04:31:18 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpT3f_fX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:30 INFO - PROCESS | 1648 | RSS: Main: 237076480 04:31:30 INFO - PROCESS | 1648 | 04:34:33 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 04:34:35 INFO - PROCESS | 1648 | RSS: Main: 416010240 04:34:35 INFO - PROCESS | 1648 | 04:36:20 INFO - PROCESS | 1648 | ************************* 04:36:20 INFO - PROCESS | 1648 | A coding exception was thrown and uncaught in a Task. 04:36:20 INFO - PROCESS | 1648 | 04:36:20 ERROR - PROCESS | 1648 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:20 INFO - PROCESS | 1648 | Full stack: 04:36:20 INFO - PROCESS | 1648 | ************************* 04:37:39 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 04:37:39 INFO - PROCESS | 1648 | RSS: Main: 386551808 04:37:39 INFO - PROCESS | 1648 | 04:40:41 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 04:40:42 INFO - PROCESS | 1648 | RSS: Main: 339005440 04:40:42 INFO - PROCESS | 1648 | 04:43:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 04:43:45 INFO - PROCESS | 1648 | RSS: Main: 367001600 04:43:45 INFO - PROCESS | 1648 | 04:46:49 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 04:46:49 INFO - PROCESS | 1648 | RSS: Main: 332849152 04:46:49 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 04:49:53 INFO - PROCESS | 1648 | RSS: Main: 553189376 04:49:53 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | __start_tp_report 04:49:53 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 04:49:53 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 04:49:53 INFO - PROCESS | 1648 | |i|pagename|runs| 04:49:53 INFO - PROCESS | 1648 | |0;dojo.html;3131.6242050953883;3226.2740883301253;3228.5802845630064;3235.579374681294;3312.320974200289;5253;5352.652077614952;5385.111456609659;5472.644278121924;5495;5373.570114390849;5399.62202645814;5413.02565538203;5438.211459338394;5449.264349312842;3588.6770190682832;3990.2219067281962;4868.099401610713;4950.529699678525;5118.59051275897;5065.189569668853;5069;5081.517255860687;5100.540951314381;5109.974450127745;4849.806007759667;4890.462049174588;4914.385701787277;4915.45929947709;5021.17150670139;4012.538558065831;4941.061198372297;4968.403791545002;4969.552740253394;5070.138076526962;4938.950610493921;4940.061388336205;4950.381202349707;4965.875853103666;5016.423111342206;5443.646162999393;5450.945490545084;5463.590230732711;5470.91793623096;5495.230667706524;5729.140628905656;5787.50806181475;5789.8263052108505;5937.139114828369;6011.789587364465;6095.4818840398175;6110.725017374184;6129.950970367737;6132.172156758825;6138.493197210971;5534.169874518854;5594.776208951637;5608.747606357723;5615.606907516516;5621.8594535136945;5058.822941197041;5946.197263369476;5999.640021598718;6154.692265386713;6257.405546473078;5720.68536230503;5734.971325143348;5821.15593238978;5965.3139888912465;5978.192943952511;6111.449969502677;6116.449519543263;6118.862843290023;6125.081237814275;6128.697664213171;5971.940280597225;5972.283326000907;6006.459418652343;6013.639181649115;6017.669028203403;5741.311042674905;5813.912791308134;5819.709014549256;5890.49930755882;6044.214252147281;4738.194506933761;4740.336352910595;4774.259989701602;4778.832740854054;4789.664723469324;4287.121140166259;4295.806688699016;4296.119295544406;4308.91382172361;4342.804573794187;5410.965029961498;5466.262054622575;5467.835964921059;5490.670559766427;5526.806561770319;5830.096335068071;5877.147813567009;5881;6048.60684055535;6101.328853826164;5453.590980676465;5472.863178420492;5546.500814926676;5554.111342185231;5564.27664403633;5188.714620695898;5199.376074871039;5201.3238279024245;5266.447023062523;5280.683159010472;6231.189945307081;6252.406021428048;6260.718267678057;6270.529710271839;6294.8111556653375;5837.328707198726;5877.559183061202;5885.852853678693;6044.335123136363;6076.179715738319;6255.46828519581;6277.435030847246;6283.37166283368;6296.087067375113;6297.118403423616;5934.169216309634;5965.8806823862415;5965.970170149121;5971.701414929149;5990.34106248321;5800.854978625399;5821.679807610622;5830.912536311874;6108.5723999319625;6112.32764395925;4976.601871850171;5392.299001129828;5728.828135155952;5730.942690573041;5734.770609175586;5739.713014349182;5780.843831233686;5784.21913041734;5846.766129354778;5861.443162899518;5652.802151924663;5680.488756011979;5688.175214593944;5693.601447898607;5718.828435146952;5836.416358364299;5837.299524056971;5845.09401042839;5853.2976042875125;5856.795012174553;5517.420670829505;5573.359063707563;5573.7770489179975;5667.2349232854685;5686.687232202268 04:49:53 INFO - PROCESS | 1648 | |1;ext.html;492.27143827135814;496.223410362782;498.4417452453253;513.93102347118;516;28308.15075547734;29837;31496.527552086707;41016.38475422871;41093.79453102738;41187;41510.37734433986;41663.16673666525;41742.58257417425;41787;7754.1470438251745;8954.731358059269;9164.495952722598;9166.908330916673;9169.312301577374;7728.188540203282;10759;11093.33439993599;11104.888951110506;11208.887911120866;10747.892521074806;10881.619143329985;10929.508172132273;10936.726581835477;10940.781184376308;7788.0654321481215;8018.198180181994;11022;11086.778264434666;11098.334099954029;10679.626213082505;10965.725856853642;10990.340579565252;11106.944465277622;11123.55505779768;40073.39889901654;40326.59673403287;40357.192856143;40547.78356649305;40668;3509.385857474957;3927.0575061985205;3948.2498325318097;3977.7414468059774;3989.5412027616903;28964.8551757242;29172.124836254945;29909.70090299091;30364.240893977607;30559;3084.8766049358;3109.176068341891;3154.0537838648315;3162.5256211568217;3218.742500599946;435090.087387766;451785;452014.7399263015;452553;453130;48906.75546622244;50145.24782128271;57280;57878.8424231513;57927.42072579305;52512.212316815276;61195.776084478064;62633.373666262756;62667.74664506684;65241;14821.77767333491;14868;15046.924765376321;15052.774208386883;15063.246837658293;11177.932082564752;11208.27146235492;11223.66329010131;11236.887631123584;11238;12748.940254338891;12937.159084659466;15656.530304090895;15833;15899.682006359808;6026.427489388501;6409.647469389229;6475;6504.414602685781;6551.427657362199;840.4621042532784;845.8350621628846;851.3317046118891;857.4940784936917;868.1708967935606;28625.998090066747;29640.88507527912;29694.554581681292;29794.40411191764;29928.850355748084;1017.7557386227252;1018.6689325969089;1043.5929987304958;1053.3890343600692;1133.4899295316975;12789.93605031969;15154.924225379023;15352.547761149142;15402.46091386796;15534;546768;548773;550511;551477;552229;57369.71315143398;57513.712431437576;57593.42406575881;57625.42374576285;57667.42332576704;1351.6283022168982;1379.4482207117233;1397.965959528883;1403.410567561606;1403.7403080430154;27759;28558;28914.855425722737;29387.971421000162;29477.705222947498;48152;48632;48648.75675621599;48655.54033379004;48676;468053;470695;471608;472717;472953.8807649044;29511;31888.5216721754;32652.183695407806;32832.343353133285;37953.62046379611;31734.88927887513;34124.82937585296;43219.135617288106;46123;46790.532094678616;35681;36691.44962825613;37156.25687486289;38167.427488587135;38419.42370864495;36291.45562816507;36403.27193456169;37109.09045861125;37591.81204093962;38105.618943811314;35661.93014209578;36597.268054638225;37191.442128367555;37249.06877328089;37285.4407183887;30882;33170.00489985304;33313.666863331055;33336;33390.83304583462;23830;24126.51746965086;24187;24789;25430.49139017246 04:49:53 INFO - PROCESS | 1648 | |2;jquery.html;335.1203091883805;363.3260302139533;390.13779547200687;400.2335527464905;423.11779938827544;14337.996340256186;14753.77869331961;16291.674166516663;20128.49678758032;20958.37124886252;12420;13264.270465124415;21324.680129798064;21618.783812161873;21806.89096554519;5201.271821944931;5416.268803711488;5418.29562156919;5428.864278393052;5434.402215756264;5007.599392048645;5291.708956007418;6597.043428702836;6664.866702665944;6714.261431242559;6065.0902364645435;6656.800295991128;6687.83280417991;6690.096836927001;6692.330766923293;5955.583109182359;5994.250718660168;6014.188084608566;6019.277686677583;6029.577929544934;5576.553875689935;5849.970750146264;5862;5864.296284445851;5937;13710.24593647349;13870.098443601235;14611.78082328766;25489.362765930815;25696;2456.4718585504024;2496.364881002946;2520.861352625605;2532.822702410832;2540.606206038067;9774.90225097747;10439.37363758177;10570.260081794277;12224.816627750592;15220.771688424682;1587.618971446856;1726.2835923091902;1764.97352539712;1781.1806568978286;1819.0631824610336;134064;134930;135608;138070.30964845212;141936;11640.592579259684;11803.049237690577;11885;11923.940380298043;12093.972012379047;16011.439599613957;16038.599035023984;16047.277872495766;16056.27746751399;16099.275532601061;8219.712310069119;8397.244248017707;8560.272376847914;8679.566021698889;8699.565021749015;7882.369410447151;8062.355011599058;8158.877616835753;8165.510069395856;8312.21034001769;4814.662973591815;4826.710397376169;4828.227483602606;4838.709677419366;4891.437484689235;1822.851603489793;1890.933817316415;1951.3950675290705;2013.5167559785546;2022.2012305139388;393.190028541206;419.0466688284117;429.29809761040923;431.5123909981701;1021.9437930913722;11978.221415607957;12051.698707532381;12252.754944901051;12311.815322770166;12361.381930903419;444.46885971264027;449.81107934667517;455.5672111494094;462.53977292594084;476.7282648890169;5386.5152136307925;5753.51095156908;6093.238345206849;6248.343923888017;6295.905561416583;414928;618744;674506;682006.589967047;701892;114597.70804583862;142206.15587688188;143264.28367858095;151800;152770;356.53472218754774;455.71062375391205;456.1174128062144;456.22441848857073;456.66891503660247;11258.887411125785;11282.717932051764;11354.637443506832;11411.60059397917;11579.478923448296;42682.35976460294;50032;50143.498565013884;54936;55449.68254761818;81555;82255.35489290301;83574;85554.57222713847;94649.73690916896;5864.501517371071;6167.876642467215;7403.444741644506;8209.671613135408;8644.789729437758;9651.469169195761;12878.356082195665;13130.606081817561;13437.395317210554;13711.177329360271;11075.280106793032;11601.303921764722;11635.53457861676;11769.058475322125;11969.581064662694;10779.97590228927;11145.721356965816;11212.943935280271;11511.366874821964;11767.588134415597;10900.836487452852;11566.537338506365;11652.94173529127;11681.182317237712;11780.936956391572;7382.926170738224;7670.7315243966195;8367.790805229917;8741;8884.244839188741;10874.565017399216;11486.138539609396;11549.538018479167;11710.473028713557;11757.294562326288 04:49:53 INFO - PROCESS | 1648 | |3;mootools.html;1244.6390546741445;1409.1967578480271;1423.7294913966346;1437.496876093367;1439.5897169306743;7379.225181355954;8102.230288122626;8351.665933362658;8354.997400311955;8462.280706139985;8219.671213151467;8310.75067747967;8311.459755115928;8312.168783121684;8320.542370169638;1945.3288615427725;2231.6094683430415;2263.4567703751063;2266.8186545076437;2269.5347453771938;1865.7481240032625;2014.8992550372423;2168.815650669695;2243.080337061805;2264.275431861805;2203.6584329428965;2225.810806081485;2227.7103976483118;2230.7434645015787;2237.910483580663;2224.8665080095166;2247.5504899020098;2249.6850440938283;2255.988720056406;2258.311215079408;2207.006846918877;2238.71274017439;2262.242148880127;2271.0802125139344;2273.4089136824377;8283.668653253862;8384.03583587889;8387.454815436977;8393.6222869971;8406.033306169807;4990.8752281193265;5125.2312153176945;5127.256547800585;5137.537621614036;5139.049275883974;3275.7870738401925;3358.345122701074;3419.247765491588;3427.241070937894;3517.2262102337445;1159.8782127876536;1197.5449329254914;1199.7720433117768;1243.1298091336012;1262.8800263974908;10789.784204315869;10881.619143329945;10923.399213043358;10932.398718070583;10949.178811589083;4424.579664931826;4597.287420449836;4605.0559635274685;4673.95326046742;4693.577578017995;9377.062293770568;9543.570539325747;9565.234781217487;9568.665096721581;9600;5222.529972302511;5224.425313215544;5225.111731005738;5229.424763276036;5232.502912223333;3962.663173630216;4670.276107203389;4752.857414277577;4753.073150735642;4773.451053128934;3760.811959402019;3790.2040571480297;3822.522184726909;3881.670058045098;3899.493065901415;1136.2784631758727;1206.6184219069116;1256.5099611151475;1261.2495565138727;1271.0784681106124;829.995850020746;865.0873328638347;891.4695756025154;898.1736802142045;933.3280038372361;5398.676079435247;5487.5884308677005;5601.439856014366;5660.405657405913;5769.1634712967225;700.7967689370128;964.5469220191696;990.4206039466849;1002.8144793213279;1014.8224060789332;3595.640435956436;3718.1448266898765;3805.5052843130215;3847.576766555677;3847.9807600961817;9827.557759900821;10155.238357123246;10169.644062457779;10183.388996660224;10185.898141018495;9290.256779457763;9763.902360976299;10024.448655324028;10143.442110683984;10146.44194569291;7003.5797852129035;7359.963200183965;7863.960680196562;7890.250426209615;7896.368290536743;5548.500634942875;5553.16702494629;5566.22072909801;5793.768249270151;5912.940870591239;7634.236576342321;8513.872291915503;8555.529445880537;9345.859812102688;9403.247740180906;8768.298536116969;9222.123898229658;9366.297527685314;9447.858282125912;9573.856392154263;6560.540762146604;6688.598684078971;6807;7196.856062878608;7267.672954717156;7852.84294314122;7927.286544211049;8004.83990320202;8006.119326873923;8014.479058861155;7144.571325720473;7188.5327453715345;7231.5299505531975;7250.818729531804;7265.709371625076;7031.261717519791;7081.645917704197;7116.750913718202;7141.785746427616;7143.2499587544635;6286.271275399302;6432.2281326241155;6444.45222156122;6448.484121270381;6459;4265.232258193555;4812.975935120302;4940.431850337113;4991.9500804991485;5148.330717006766;4242.257604919188;4919.877003074952;5015.949840501548;5017.874553136055;5218.478152184751 04:49:53 INFO - PROCESS | 1648 | |4;prototype.html;296.47967816482077;345.20784388934175;364.50973440722237;366.65717554086945;368.77873276034364;4108.157827645333;4981.982297169648;5218.112920803458;5250.4487028861995;5291.126964050927;4297.634701050414;4557.1569259686985;4723.787824279489;4904.438871849179;5140.742962851852;3423.760336776427;3810.5617853946737;3879.9612003880025;3974.642282194602;3984.0637450199324;3297.7856439331485;3957.3866050762035;4006.4559005031724;4013.1740058273417;4023.839046438139;3574.160072382988;3639.6178401267885;3676.871309504168;3940.9605903941024;3946.625070618287;3944.3294639910996;3979.6020397959974;3981;3994.400839874042;4001.9599804002023;2684.8657567121763;3029.9698102645207;3348.916277093068;3480.860765569374;3495.06024156593;4138.337865941435;4820.408795447414;5041.764767631954;5046.167382381903;5056.544910958031;3801.9239615207816;4507.2788353863225;4519.864404067883;4528.8451444881675;4548.5906268436;5191.714455704935;5383.327084114486;5448.591355648303;5556.222128901957;5567.832965011056;3775.4903088083306;3781.1681430085337;3785.791781452019;3792.9518060247433;3800.5629352624524;4347.69566130371;4598.586127248564;5460.399356070829;5537.750801213955;5578.470045345685;3643.5627724672677;3808.1622043150464;3834.2331533693373;3837.501124853751;3852.4799152114106;3889.6110388961442;3902.2780785554764;3902.804859757001;3922.2547715933883;3931.567527571965;3975.582563830756;4265.677639931631;4512.909741805145;4560.657950653715;4573.438874230442;4204.409333452171;5060.949390506048;5321.600879934021;5510.724463776795;5558.8604336111;1075.7041813501194;1152.389233706137;1158.8006413362298;1236.344737289238;1244.775940330749;778.6301506784346;796.6972550430916;842.9789255268668;847.174005344784;867.778716427307;4453.101588252961;4555.544445555418;4791.6406269529925;4821.79455136216;4872.707637541759;4702.788374523155;5202.2803695358;5392.325959255093;5397.271368365299;5423.484768946943;4166.812493437742;4315.611594956469;4389.868303950887;4412.3160910059;4816.349792778;2333.9183128590416;2587.9353016174746;2706.594010898342;2758.793090518179;2808.4693841856238;4529.071540334224;4840.346553215341;4956.975215123901;5032.446430892599;5412.485813847757;5236.371635403776;5284.199374331611;5508.9724551377785;5523.972380138073;5563.360213575491;4934.3338649282605;5217.191335343028;5503.972480137654;5550.530014143748;5607.299087614048;4924.261360795909;4957.4639205126905;5258.158694608845;5339.864054378279;5398.811041613525;4140.4617399737845;4171.416001759817;4510.548945105464;5233.633645644921;5268.683878967274;4545.204589196944;4862.9756851216935;5684.403137670485;5713.143028545752;5729.369769325454;3215.5658986036588;3772.358699021228;3800.3919372900205;3810.2760475509563;3839.0074322704077;5670.943290567042;5688.947544704243;5709.25779648643;5710.200571919851;5716.28546431696;4892.2661600760175;5171.250168725439;5277.973610131925;5452.809151679672;5493.423190564933;4435.423394958635;4464.955350446454;5466.699331536804;5487.4238204987905;5532.068003957908;5166.269299784614;5358;5384.83056265989;5404.108801791023;5434.2935418395355;4644.58198762113;4796.592289655418;5457.754401052041;5518.392976772471;5539.479536810822;4621.584057434847;4979.377577802775;5112.258722485144;5246.029484545371;5353.812616558402 04:49:53 INFO - PROCESS | 1648 | |5;yui.html;700.1877821726791;715.7673756029288;755.7317152410894;809.6558962440963;821.9301359384452;14253.928730356347;14459.78310325346;14774.409023639068;14831;14831.184284864328;11980.580679676214;13154.408051637676;13349.399277032533;13374.531891383804;13380.866191338084;855.7732200966749;863.5897948474455;881.2685471059006;882.7307671160326;885.3890815337429;856.2251162697737;869.0744357259498;872.16708043818;872.3152325424543;873.3668090634303;817.4523069543391;902.9074819468451;926.2821313482038;927.5130556457846;927.9884925431279;916.7799728065249;917.3532659475054;917.4357769971487;917.857732051533;919.2278486071698;669.1034014420651;683.5932620091038;684.3601232847303;684.849333146707;684.9657517124174;12418.81371779424;12658.177218480769;12712.110152289344;12814.590395056537;12861.164024338388;11583.18917675763;12185.903268705859;12520.937395312965;12596.937015314956;12811.487540498369;11017.724556886142;12540.557835848904;12579.937100314532;12627.93686031573;12953.870461295455;4239.258129827268;4423.469183697945;4433.933490997638;4465.799039043251;4523.932141017887;91165;92402.07597924002;93524.06475935222;93944;94141.5292923531;10342.689719308433;10424.947875260727;10524.105451036594;10674;10687.572497100184;19959.60080798376;20187.39437816868;20222.393328200178;20226.98865056741;20266;7189.784306470814;7271;7303.89044164338;7397.26027397256;7515.962420187864;4075;4125.216208920296;4136.689748268892;4143.233501802176;4153.91692166155;3408.7613867029568;3517.3492903812876;3523.1896663767475;3523.7004854587135;3545.8936231913085;535.376286626084;646.8479907221799;658.8263351667147;663.7378235596913;679.4700133895569;492.5173330136486;518.8625014371195;530.4059453412203;532.5180711456138;533.8772082421065;8235.752927412188;8571.22858942698;9041.933460132783;9471.526423678926;9577.329586928987;593.2702775586054;596.8000719758843;657.0964923230558;658.2101478226052;661.6394065234459;4456.376107344974;4526.524714904954;4546.636269098464;4650.348951146842;4732.526747325309;93549;97137.05725885443;98916.80870951888;99639;101752.49123754333;75777;78385;78535.21464785279;78733;80562.59718701369;61531.15406537901;66524;74534;74627.25372746312;75532;8753.737387878375;8759.824803504021;8774.947006359022;8885.555722213734;9064.728058158265;39980.80009599934;49558.504414956835;50055.74972125116;50387.74030649116;50469;64650;65841.35396615123;66600;67305;67546.66226668835;10241.590336386462;10322.2258330624;10489.475526223505;10610.469476525988;11170.385628790169;11686.298822070703;11707.88292117102;12021.218620789621;12171.573994910135;12298.385080746106;10019.599216031276;10268.97310268997;11323.037541809039;11516.963473287444;11522.251053681484;8694.347923905605;10872.61945831892;11036.834447483454;11210.551577937118;11259.268147570381;10529.10502607287;11170.944145279222;11189.608363707232;11213.887861121284;11224.326540407601;9104.576103836685;9441.811163776823;9499.857502137611;9502.619895204114;9689.515524223902;7897.723579674684;7962.601869906597;8888.466691998501;8957.372983891066;9241 04:49:53 INFO - PROCESS | 1648 | __end_tp_report 04:49:53 INFO - PROCESS | 1648 | __start_cc_report 04:49:53 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1980 04:49:53 INFO - PROCESS | 1648 | __end_cc_report 04:49:53 INFO - PROCESS | 1648 | __startTimestamp1486385393389__endTimestamp 04:49:53 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | ------- Summary: start ------- 04:49:53 INFO - PROCESS | 1648 | Number of tests: 6 04:49:53 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5488.16 Median:5667.23 stddev:658.22 (11.6%) stddev-sans-first:633.75 04:49:53 INFO - PROCESS | 1648 | Values: 3131.6 3226.3 3228.6 3235.6 3312.3 5253.0 5352.7 5385.1 5472.6 5495.0 5373.6 5399.6 5413.0 5438.2 5449.3 3588.7 3990.2 4868.1 4950.5 5118.6 5065.2 5069.0 5081.5 5100.5 5110.0 4849.8 4890.5 4914.4 4915.5 5021.2 4012.5 4941.1 4968.4 4969.6 5070.1 4939.0 4940.1 4950.4 4965.9 5016.4 5443.6 5450.9 5463.6 5470.9 5495.2 5729.1 5787.5 5789.8 5937.1 6011.8 6095.5 6110.7 6130.0 6132.2 6138.5 5534.2 5594.8 5608.7 5615.6 5621.9 5058.8 5946.2 5999.6 6154.7 6257.4 5720.7 5735.0 5821.2 5965.3 5978.2 6111.4 6116.4 6118.9 6125.1 6128.7 5971.9 5972.3 6006.5 6013.6 6017.7 5741.3 5813.9 5819.7 5890.5 6044.2 4738.2 4740.3 4774.3 4778.8 4789.7 4287.1 4295.8 4296.1 4308.9 4342.8 5411.0 5466.3 5467.8 5490.7 5526.8 5830.1 5877.1 5881.0 6048.6 6101.3 5453.6 5472.9 5546.5 5554.1 5564.3 5188.7 5199.4 5201.3 5266.4 5280.7 6231.2 6252.4 6260.7 6270.5 6294.8 5837.3 5877.6 5885.9 6044.3 6076.2 6255.5 6277.4 6283.4 6296.1 6297.1 5934.2 5965.9 5966.0 5971.7 5990.3 5800.9 5821.7 5830.9 6108.6 6112.3 4976.6 5392.3 5728.8 5730.9 5734.8 5739.7 5780.8 5784.2 5846.8 5861.4 5652.8 5680.5 5688.2 5693.6 5718.8 5836.4 5837.3 5845.1 5853.3 5856.8 5517.4 5573.4 5573.8 5667.2 5686.7 04:49:53 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:62817.96 Median:36500.27 stddev:130905.51 (358.6%) stddev-sans-first:131189.09 04:49:53 INFO - PROCESS | 1648 | Values: 492.3 496.2 498.4 513.9 516.0 28308.2 29837.0 31496.5 41016.4 41093.8 41187.0 41510.4 41663.2 41742.6 41787.0 7754.1 8954.7 9164.5 9166.9 9169.3 7728.2 10759.0 11093.3 11104.9 11208.9 10747.9 10881.6 10929.5 10936.7 10940.8 7788.1 8018.2 11022.0 11086.8 11098.3 10679.6 10965.7 10990.3 11106.9 11123.6 40073.4 40326.6 40357.2 40547.8 40668.0 3509.4 3927.1 3948.2 3977.7 3989.5 28964.9 29172.1 29909.7 30364.2 30559.0 3084.9 3109.2 3154.1 3162.5 3218.7 435090.1 451785.0 452014.7 452553.0 453130.0 48906.8 50145.2 57280.0 57878.8 57927.4 52512.2 61195.8 62633.4 62667.7 65241.0 14821.8 14868.0 15046.9 15052.8 15063.2 11177.9 11208.3 11223.7 11236.9 11238.0 12748.9 12937.2 15656.5 15833.0 15899.7 6026.4 6409.6 6475.0 6504.4 6551.4 840.5 845.8 851.3 857.5 868.2 28626.0 29640.9 29694.6 29794.4 29928.9 1017.8 1018.7 1043.6 1053.4 1133.5 12789.9 15154.9 15352.5 15402.5 15534.0 546768.0 548773.0 550511.0 551477.0 552229.0 57369.7 57513.7 57593.4 57625.4 57667.4 1351.6 1379.4 1398.0 1403.4 1403.7 27759.0 28558.0 28914.9 29388.0 29477.7 48152.0 48632.0 48648.8 48655.5 48676.0 468053.0 470695.0 471608.0 472717.0 472953.9 29511.0 31888.5 32652.2 32832.3 37953.6 31734.9 34124.8 43219.1 46123.0 46790.5 35681.0 36691.4 37156.3 38167.4 38419.4 36291.5 36403.3 37109.1 37591.8 38105.6 35661.9 36597.3 37191.4 37249.1 37285.4 30882.0 33170.0 33313.7 33336.0 33390.8 23830.0 24126.5 24187.0 24789.0 25430.5 04:49:53 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:35799.30 Median:359.93 stddev:105596.44 (29338.0%) stddev-sans-first:105859.09 04:49:53 INFO - PROCESS | 1648 | Values: 335.1 363.3 390.1 400.2 423.1 14338.0 14753.8 16291.7 20128.5 20958.4 12420.0 13264.3 21324.7 21618.8 21806.9 5201.3 5416.3 5418.3 5428.9 5434.4 5007.6 5291.7 6597.0 6664.9 6714.3 6065.1 6656.8 6687.8 6690.1 6692.3 5955.6 5994.3 6014.2 6019.3 6029.6 5576.6 5850.0 5862.0 5864.3 5937.0 13710.2 13870.1 14611.8 25489.4 25696.0 2456.5 2496.4 2520.9 2532.8 2540.6 9774.9 10439.4 10570.3 12224.8 15220.8 1587.6 1726.3 1765.0 1781.2 1819.1 134064.0 134930.0 135608.0 138070.3 141936.0 11640.6 11803.0 11885.0 11923.9 12094.0 16011.4 16038.6 16047.3 16056.3 16099.3 8219.7 8397.2 8560.3 8679.6 8699.6 7882.4 8062.4 8158.9 8165.5 8312.2 4814.7 4826.7 4828.2 4838.7 4891.4 1822.9 1890.9 1951.4 2013.5 2022.2 393.2 419.0 429.3 431.5 1021.9 11978.2 12051.7 12252.8 12311.8 12361.4 444.5 449.8 455.6 462.5 476.7 5386.5 5753.5 6093.2 6248.3 6295.9 414928.0 618744.0 674506.0 682006.6 701892.0 114597.7 142206.2 143264.3 151800.0 152770.0 356.5 455.7 456.1 456.2 456.7 11258.9 11282.7 11354.6 11411.6 11579.5 42682.4 50032.0 50143.5 54936.0 55449.7 81555.0 82255.4 83574.0 85554.6 94649.7 5864.5 6167.9 7403.4 8209.7 8644.8 9651.5 12878.4 13130.6 13437.4 13711.2 11075.3 11601.3 11635.5 11769.1 11969.6 10780.0 11145.7 11212.9 11511.4 11767.6 10900.8 11566.5 11652.9 11681.2 11780.9 7382.9 7670.7 8367.8 8741.0 8884.2 10874.6 11486.1 11549.5 11710.5 11757.3 04:49:53 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5403.45 Median:5138.29 stddev:2970.62 (57.8%) stddev-sans-first:2962.51 04:49:53 INFO - PROCESS | 1648 | Values: 1244.6 1409.2 1423.7 1437.5 1439.6 7379.2 8102.2 8351.7 8355.0 8462.3 8219.7 8310.8 8311.5 8312.2 8320.5 1945.3 2231.6 2263.5 2266.8 2269.5 1865.7 2014.9 2168.8 2243.1 2264.3 2203.7 2225.8 2227.7 2230.7 2237.9 2224.9 2247.6 2249.7 2256.0 2258.3 2207.0 2238.7 2262.2 2271.1 2273.4 8283.7 8384.0 8387.5 8393.6 8406.0 4990.9 5125.2 5127.3 5137.5 5139.0 3275.8 3358.3 3419.2 3427.2 3517.2 1159.9 1197.5 1199.8 1243.1 1262.9 10789.8 10881.6 10923.4 10932.4 10949.2 4424.6 4597.3 4605.1 4674.0 4693.6 9377.1 9543.6 9565.2 9568.7 9600.0 5222.5 5224.4 5225.1 5229.4 5232.5 3962.7 4670.3 4752.9 4753.1 4773.5 3760.8 3790.2 3822.5 3881.7 3899.5 1136.3 1206.6 1256.5 1261.2 1271.1 830.0 865.1 891.5 898.2 933.3 5398.7 5487.6 5601.4 5660.4 5769.2 700.8 964.5 990.4 1002.8 1014.8 3595.6 3718.1 3805.5 3847.6 3848.0 9827.6 10155.2 10169.6 10183.4 10185.9 9290.3 9763.9 10024.4 10143.4 10146.4 7003.6 7360.0 7864.0 7890.3 7896.4 5548.5 5553.2 5566.2 5793.8 5912.9 7634.2 8513.9 8555.5 9345.9 9403.2 8768.3 9222.1 9366.3 9447.9 9573.9 6560.5 6688.6 6807.0 7196.9 7267.7 7852.8 7927.3 8004.8 8006.1 8014.5 7144.6 7188.5 7231.5 7250.8 7265.7 7031.3 7081.6 7116.8 7141.8 7143.2 6286.3 6432.2 6444.5 6448.5 6459.0 4265.2 4813.0 4940.4 4992.0 5148.3 4242.3 4919.9 5015.9 5017.9 5218.5 04:49:53 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4294.26 Median:4633.08 stddev:1308.80 (28.2%) stddev-sans-first:1277.61 04:49:53 INFO - PROCESS | 1648 | Values: 296.5 345.2 364.5 366.7 368.8 4108.2 4982.0 5218.1 5250.4 5291.1 4297.6 4557.2 4723.8 4904.4 5140.7 3423.8 3810.6 3880.0 3974.6 3984.1 3297.8 3957.4 4006.5 4013.2 4023.8 3574.2 3639.6 3676.9 3941.0 3946.6 3944.3 3979.6 3981.0 3994.4 4002.0 2684.9 3030.0 3348.9 3480.9 3495.1 4138.3 4820.4 5041.8 5046.2 5056.5 3801.9 4507.3 4519.9 4528.8 4548.6 5191.7 5383.3 5448.6 5556.2 5567.8 3775.5 3781.2 3785.8 3793.0 3800.6 4347.7 4598.6 5460.4 5537.8 5578.5 3643.6 3808.2 3834.2 3837.5 3852.5 3889.6 3902.3 3902.8 3922.3 3931.6 3975.6 4265.7 4512.9 4560.7 4573.4 4204.4 5060.9 5321.6 5510.7 5558.9 1075.7 1152.4 1158.8 1236.3 1244.8 778.6 796.7 843.0 847.2 867.8 4453.1 4555.5 4791.6 4821.8 4872.7 4702.8 5202.3 5392.3 5397.3 5423.5 4166.8 4315.6 4389.9 4412.3 4816.3 2333.9 2587.9 2706.6 2758.8 2808.5 4529.1 4840.3 4957.0 5032.4 5412.5 5236.4 5284.2 5509.0 5524.0 5563.4 4934.3 5217.2 5504.0 5550.5 5607.3 4924.3 4957.5 5258.2 5339.9 5398.8 4140.5 4171.4 4510.5 5233.6 5268.7 4545.2 4863.0 5684.4 5713.1 5729.4 3215.6 3772.4 3800.4 3810.3 3839.0 5670.9 5688.9 5709.3 5710.2 5716.3 4892.3 5171.3 5278.0 5452.8 5493.4 4435.4 4465.0 5466.7 5487.4 5532.1 5166.3 5358.0 5384.8 5404.1 5434.3 4644.6 4796.6 5457.8 5518.4 5539.5 4621.6 4979.4 5112.3 5246.0 5353.8 04:49:53 INFO - PROCESS | 1648 | 04:49:53 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:18672.18 Median:564.32 stddev:26991.85 (4783.0%) stddev-sans-first:27033.84 04:49:53 INFO - PROCESS | 1648 | Values: 700.2 715.8 755.7 809.7 821.9 14253.9 14459.8 14774.4 14831.0 14831.2 11980.6 13154.4 13349.4 13374.5 13380.9 855.8 863.6 881.3 882.7 885.4 856.2 869.1 872.2 872.3 873.4 817.5 902.9 926.3 927.5 928.0 916.8 917.4 917.4 917.9 919.2 669.1 683.6 684.4 684.8 685.0 12418.8 12658.2 12712.1 12814.6 12861.2 11583.2 12185.9 12520.9 12596.9 12811.5 11017.7 12540.6 12579.9 12627.9 12953.9 4239.3 4423.5 4433.9 4465.8 4523.9 91165.0 92402.1 93524.1 93944.0 94141.5 10342.7 10424.9 10524.1 10674.0 10687.6 19959.6 20187.4 20222.4 20227.0 20266.0 7189.8 7271.0 7303.9 7397.3 7516.0 4075.0 4125.2 4136.7 4143.2 4153.9 3408.8 3517.3 3523.2 3523.7 3545.9 535.4 646.8 658.8 663.7 679.5 492.5 518.9 530.4 532.5 533.9 8235.8 8571.2 9041.9 9471.5 9577.3 593.3 596.8 657.1 658.2 661.6 4456.4 4526.5 4546.6 4650.3 4732.5 93549.0 97137.1 98916.8 99639.0 101752.5 75777.0 78385.0 78535.2 78733.0 80562.6 61531.2 66524.0 74534.0 74627.3 75532.0 8753.7 8759.8 8774.9 8885.6 9064.7 39980.8 49558.5 50055.7 50387.7 50469.0 64650.0 65841.4 66600.0 67305.0 67546.7 10241.6 10322.2 10489.5 10610.5 11170.4 11686.3 11707.9 12021.2 12171.6 12298.4 10019.6 10269.0 11323.0 11517.0 11522.3 8694.3 10872.6 11036.8 11210.6 11259.3 10529.1 11170.9 11189.6 11213.9 11224.3 9104.6 9441.8 9499.9 9502.6 9689.5 7897.7 7962.6 8888.5 8957.4 9241.0 04:49:53 INFO - PROCESS | 1648 | -------- Summary: end -------- 04:49:53 INFO - PROCESS | 1648 | 04:49:54 INFO - TEST-INFO | 1648: exit 0 04:49:54 INFO - TEST-OK | dromaeo_css | took 1119056ms 04:49:54 INFO - TEST-START | kraken 04:49:54 INFO - operating with platform_type : mac_ 04:49:54 INFO - Initialising browser for kraken test... 04:49:54 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmppsPagH/profile http://localhost:49244/getInfo.html) 04:49:56 INFO - PROCESS | 1657 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 04:49:56 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 04:49:56 INFO - PROCESS | 1657 | colorDepth:24 04:49:56 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 04:49:56 INFO - PROCESS | 1657 | __metrics 04:49:56 INFO - PROCESS | 1657 | 1486385396466 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:49:56 INFO - PROCESS | 1657 | 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" 04:49:56 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:49:57 INFO - TEST-INFO | 1657: exit 0 04:49:57 INFO - Browser initialized. 04:49:57 INFO - Running cycle 1/1 for kraken test... 04:49:57 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmppsPagH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:09 INFO - PROCESS | 1659 | RSS: Main: 237064192 04:50:09 INFO - PROCESS | 1659 | 04:50:12 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 04:50:13 INFO - PROCESS | 1659 | RSS: Main: 290566144 04:50:13 INFO - PROCESS | 1659 | 04:50:15 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 04:50:16 INFO - PROCESS | 1659 | RSS: Main: 292233216 04:50:16 INFO - PROCESS | 1659 | 04:50:19 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 04:50:20 INFO - PROCESS | 1659 | RSS: Main: 381870080 04:50:20 INFO - PROCESS | 1659 | 04:50:22 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 04:50:23 INFO - PROCESS | 1659 | RSS: Main: 284385280 04:50:23 INFO - PROCESS | 1659 | 04:50:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:50:25 INFO - PROCESS | 1659 | RSS: Main: 287907840 04:50:25 INFO - PROCESS | 1659 | 04:50:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:29 INFO - PROCESS | 1659 | RSS: Main: 328253440 04:50:29 INFO - PROCESS | 1659 | 04:50:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:32 INFO - PROCESS | 1659 | RSS: Main: 330874880 04:50:32 INFO - PROCESS | 1659 | 04:50:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 04:50:36 INFO - PROCESS | 1659 | RSS: Main: 329490432 04:50:36 INFO - PROCESS | 1659 | 04:50:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:38 INFO - PROCESS | 1659 | RSS: Main: 305025024 04:50:38 INFO - PROCESS | 1659 | 04:50:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:40 INFO - PROCESS | 1659 | RSS: Main: 308224000 04:50:40 INFO - PROCESS | 1659 | 04:50:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:43 INFO - PROCESS | 1659 | RSS: Main: 309948416 04:50:43 INFO - PROCESS | 1659 | 04:50:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:50:47 INFO - PROCESS | 1659 | RSS: Main: 330231808 04:50:47 INFO - PROCESS | 1659 | 04:50:50 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:50:51 INFO - PROCESS | 1659 | RSS: Main: 324255744 04:50:51 INFO - PROCESS | 1659 | 04:50:52 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 04:50:53 INFO - PROCESS | 1659 | RSS: Main: 369086464 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | __start_tp_report 04:50:53 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 04:50:53 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 04:50:53 INFO - PROCESS | 1659 | |i|pagename|runs| 04:50:53 INFO - PROCESS | 1659 | |0;ai-astar;116;154;113;112;112;113;120;166;156;113 04:50:53 INFO - PROCESS | 1659 | |1;audio-beat-detection;143;108;103;144;112;109;111;110;129;108 04:50:53 INFO - PROCESS | 1659 | |2;audio-dft;185;184;184;185;181;183;181;182;186;185 04:50:53 INFO - PROCESS | 1659 | |3;audio-fft;79;59;58;87;60;59;58;57;74;59 04:50:53 INFO - PROCESS | 1659 | |4;audio-oscillator;95;70;86;70;68;70;86;58;58;89 04:50:53 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;90;87;91;88;90;87;91;87;90;87 04:50:53 INFO - PROCESS | 1659 | |6;imaging-darkroom;99;100;99;101;100;100;99;99;106;99 04:50:53 INFO - PROCESS | 1659 | |7;imaging-desaturate;87;85;85;86;88;85;85;85;86;85 04:50:53 INFO - PROCESS | 1659 | |8;json-parse-financial;61;59;58;57;59;59;58;58;59;58 04:50:53 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;65;71;70;75;71;68;69;70;69;70 04:50:53 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;87;88;87;88;89;84;90;87;86;88 04:50:53 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;140;142;140;136;148;151;151;151;181;156 04:50:53 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;174;173;172;180;172;172;174;173;227;160 04:50:53 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;67;66;68;67;65;65;65;65;65 04:50:53 INFO - PROCESS | 1659 | __end_tp_report 04:50:53 INFO - PROCESS | 1659 | __start_cc_report 04:50:53 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2228 04:50:53 INFO - PROCESS | 1659 | __end_cc_report 04:50:53 INFO - PROCESS | 1659 | __startTimestamp1486385453111__endTimestamp 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | ------- Summary: start ------- 04:50:53 INFO - PROCESS | 1659 | Number of tests: 14 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:127.50 Median:118.00 stddev:21.85 (18.5%) stddev-sans-first:22.77 04:50:53 INFO - PROCESS | 1659 | Values: 116.0 154.0 113.0 112.0 112.0 113.0 120.0 166.0 156.0 113.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:117.70 Median:111.50 stddev:15.19 (13.6%) stddev-sans-first:13.06 04:50:53 INFO - PROCESS | 1659 | Values: 143.0 108.0 103.0 144.0 112.0 109.0 111.0 110.0 129.0 108.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.50 stddev:1.78 (1.0%) stddev-sans-first:1.81 04:50:53 INFO - PROCESS | 1659 | Values: 185.0 184.0 184.0 185.0 181.0 183.0 181.0 182.0 186.0 185.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:65.00 Median:59.50 stddev:10.83 (18.2%) stddev-sans-first:10.24 04:50:53 INFO - PROCESS | 1659 | Values: 79.0 59.0 58.0 87.0 60.0 59.0 58.0 57.0 74.0 59.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:78.00 stddev:13.08 (16.8%) stddev-sans-first:11.70 04:50:53 INFO - PROCESS | 1659 | Values: 95.0 70.0 86.0 70.0 68.0 70.0 86.0 58.0 58.0 89.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:90.00 stddev:1.75 (1.9%) stddev-sans-first:1.80 04:50:53 INFO - PROCESS | 1659 | Values: 90.0 87.0 91.0 88.0 90.0 87.0 91.0 87.0 90.0 87.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:99.00 stddev:2.15 (2.2%) stddev-sans-first:2.24 04:50:53 INFO - PROCESS | 1659 | Values: 99.0 100.0 99.0 101.0 100.0 100.0 99.0 99.0 106.0 99.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:85.50 stddev:1.06 (1.2%) stddev-sans-first:1.01 04:50:53 INFO - PROCESS | 1659 | Values: 87.0 85.0 85.0 86.0 88.0 85.0 85.0 85.0 86.0 85.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.00 stddev:1.07 (1.8%) stddev-sans-first:0.71 04:50:53 INFO - PROCESS | 1659 | Values: 61.0 59.0 58.0 57.0 59.0 59.0 58.0 58.0 59.0 58.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:70.00 stddev:2.53 (3.6%) stddev-sans-first:2.00 04:50:53 INFO - PROCESS | 1659 | Values: 65.0 71.0 70.0 75.0 71.0 68.0 69.0 70.0 69.0 70.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:88.00 stddev:1.65 (1.9%) stddev-sans-first:1.74 04:50:53 INFO - PROCESS | 1659 | Values: 87.0 88.0 87.0 88.0 89.0 84.0 90.0 87.0 86.0 88.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:151.00 stddev:12.75 (8.4%) stddev-sans-first:13.04 04:50:53 INFO - PROCESS | 1659 | Values: 140.0 142.0 140.0 136.0 148.0 151.0 151.0 151.0 181.0 156.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:173.50 stddev:18.01 (10.4%) stddev-sans-first:19.05 04:50:53 INFO - PROCESS | 1659 | Values: 174.0 173.0 172.0 180.0 172.0 172.0 174.0 173.0 227.0 160.0 04:50:53 INFO - PROCESS | 1659 | 04:50:53 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.15 (1.7%) stddev-sans-first:1.17 04:50:53 INFO - PROCESS | 1659 | Values: 67.0 67.0 66.0 68.0 67.0 65.0 65.0 65.0 65.0 65.0 04:50:53 INFO - PROCESS | 1659 | -------- Summary: end -------- 04:50:53 INFO - PROCESS | 1659 | 04:50:54 INFO - TEST-INFO | 1659: exit 0 04:50:54 INFO - TEST-OK | kraken | took 60029ms 04:50:54 INFO - SUITE-END | took 1179s 04:50:54 INFO - Completed test suite (00:19:39) 04:50:54 INFO - javascript benchmark 04:50:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6894.775391764652, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3131.6242050953883, 3226.2740883301253, 3228.5802845630064, 3235.579374681294, 3312.320974200289, 5253.0, 5352.652077614952, 5385.111456609659, 5472.644278121924, 5495.0, 5373.570114390849, 5399.62202645814, 5413.02565538203, 5438.211459338394, 5449.264349312842, 3588.6770190682832, 3990.2219067281962, 4868.099401610713, 4950.529699678525, 5118.59051275897, 5065.189569668853, 5069.0, 5081.517255860687, 5100.540951314381, 5109.974450127745, 4849.806007759667, 4890.462049174588, 4914.385701787277, 4915.45929947709, 5021.17150670139, 4012.538558065831, 4941.061198372297, 4968.403791545002, 4969.552740253394, 5070.138076526962, 4938.950610493921, 4940.061388336205, 4950.381202349707, 4965.875853103666, 5016.423111342206, 5443.646162999393, 5450.945490545084, 5463.590230732711, 5470.91793623096, 5495.230667706524, 5729.140628905656, 5787.50806181475, 5789.8263052108505, 5937.139114828369, 6011.789587364465, 6095.4818840398175, 6110.725017374184, 6129.950970367737, 6132.172156758825, 6138.493197210971, 5534.169874518854, 5594.776208951637, 5608.747606357723, 5615.606907516516, 5621.8594535136945, 5058.822941197041, 5946.197263369476, 5999.640021598718, 6154.692265386713, 6257.405546473078, 5720.68536230503, 5734.971325143348, 5821.15593238978, 5965.3139888912465, 5978.192943952511, 6111.449969502677, 6116.449519543263, 6118.862843290023, 6125.081237814275, 6128.697664213171, 5971.940280597225, 5972.283326000907, 6006.459418652343, 6013.639181649115, 6017.669028203403, 5741.311042674905, 5813.912791308134, 5819.709014549256, 5890.49930755882, 6044.214252147281, 4738.194506933761, 4740.336352910595, 4774.259989701602, 4778.832740854054, 4789.664723469324, 4287.121140166259, 4295.806688699016, 4296.119295544406, 4308.91382172361, 4342.804573794187, 5410.965029961498, 5466.262054622575, 5467.835964921059, 5490.670559766427, 5526.806561770319, 5830.096335068071, 5877.147813567009, 5881.0, 6048.60684055535, 6101.328853826164, 5453.590980676465, 5472.863178420492, 5546.500814926676, 5554.111342185231, 5564.27664403633, 5188.714620695898, 5199.376074871039, 5201.3238279024245, 5266.447023062523, 5280.683159010472, 6231.189945307081, 6252.406021428048, 6260.718267678057, 6270.529710271839, 6294.8111556653375, 5837.328707198726, 5877.559183061202, 5885.852853678693, 6044.335123136363, 6076.179715738319, 6255.46828519581, 6277.435030847246, 6283.37166283368, 6296.087067375113, 6297.118403423616, 5934.169216309634, 5965.8806823862415, 5965.970170149121, 5971.701414929149, 5990.34106248321, 5800.854978625399, 5821.679807610622, 5830.912536311874, 6108.5723999319625, 6112.32764395925, 4976.601871850171, 5392.299001129828, 5728.828135155952, 5730.942690573041, 5734.770609175586, 5739.713014349182, 5780.843831233686, 5784.21913041734, 5846.766129354778, 5861.443162899518, 5652.802151924663, 5680.488756011979, 5688.175214593944, 5693.601447898607, 5718.828435146952, 5836.416358364299, 5837.299524056971, 5845.09401042839, 5853.2976042875125, 5856.795012174553, 5517.420670829505, 5573.359063707563, 5573.7770489179975, 5667.2349232854685, 5686.687232202268], "value": 5445.145802825723, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.27143827135814, 496.223410362782, 498.4417452453253, 513.93102347118, 516.0, 28308.15075547734, 29837.0, 31496.527552086707, 41016.38475422871, 41093.79453102738, 41187.0, 41510.37734433986, 41663.16673666525, 41742.58257417425, 41787.0, 7754.1470438251745, 8954.731358059269, 9164.495952722598, 9166.908330916673, 9169.312301577374, 7728.188540203282, 10759.0, 11093.33439993599, 11104.888951110506, 11208.887911120866, 10747.892521074806, 10881.619143329985, 10929.508172132273, 10936.726581835477, 10940.781184376308, 7788.0654321481215, 8018.198180181994, 11022.0, 11086.778264434666, 11098.334099954029, 10679.626213082505, 10965.725856853642, 10990.340579565252, 11106.944465277622, 11123.55505779768, 40073.39889901654, 40326.59673403287, 40357.192856143, 40547.78356649305, 40668.0, 3509.385857474957, 3927.0575061985205, 3948.2498325318097, 3977.7414468059774, 3989.5412027616903, 28964.8551757242, 29172.124836254945, 29909.70090299091, 30364.240893977607, 30559.0, 3084.8766049358, 3109.176068341891, 3154.0537838648315, 3162.5256211568217, 3218.742500599946, 435090.087387766, 451785.0, 452014.7399263015, 452553.0, 453130.0, 48906.75546622244, 50145.24782128271, 57280.0, 57878.8424231513, 57927.42072579305, 52512.212316815276, 61195.776084478064, 62633.373666262756, 62667.74664506684, 65241.0, 14821.77767333491, 14868.0, 15046.924765376321, 15052.774208386883, 15063.246837658293, 11177.932082564752, 11208.27146235492, 11223.66329010131, 11236.887631123584, 11238.0, 12748.940254338891, 12937.159084659466, 15656.530304090895, 15833.0, 15899.682006359808, 6026.427489388501, 6409.647469389229, 6475.0, 6504.414602685781, 6551.427657362199, 840.4621042532784, 845.8350621628846, 851.3317046118891, 857.4940784936917, 868.1708967935606, 28625.998090066747, 29640.88507527912, 29694.554581681292, 29794.40411191764, 29928.850355748084, 1017.7557386227252, 1018.6689325969089, 1043.5929987304958, 1053.3890343600692, 1133.4899295316975, 12789.93605031969, 15154.924225379023, 15352.547761149142, 15402.46091386796, 15534.0, 546768.0, 548773.0, 550511.0, 551477.0, 552229.0, 57369.71315143398, 57513.712431437576, 57593.42406575881, 57625.42374576285, 57667.42332576704, 1351.6283022168982, 1379.4482207117233, 1397.965959528883, 1403.410567561606, 1403.7403080430154, 27759.0, 28558.0, 28914.855425722737, 29387.971421000162, 29477.705222947498, 48152.0, 48632.0, 48648.75675621599, 48655.54033379004, 48676.0, 468053.0, 470695.0, 471608.0, 472717.0, 472953.8807649044, 29511.0, 31888.5216721754, 32652.183695407806, 32832.343353133285, 37953.62046379611, 31734.88927887513, 34124.82937585296, 43219.135617288106, 46123.0, 46790.532094678616, 35681.0, 36691.44962825613, 37156.25687486289, 38167.427488587135, 38419.42370864495, 36291.45562816507, 36403.27193456169, 37109.09045861125, 37591.81204093962, 38105.618943811314, 35661.93014209578, 36597.268054638225, 37191.442128367555, 37249.06877328089, 37285.4407183887, 30882.0, 33170.00489985304, 33313.666863331055, 33336.0, 33390.83304583462, 23830.0, 24126.51746965086, 24187.0, 24789.0, 25430.49139017246], "value": 19221.369079903183, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.1203091883805, 363.3260302139533, 390.13779547200687, 400.2335527464905, 423.11779938827544, 14337.996340256186, 14753.77869331961, 16291.674166516663, 20128.49678758032, 20958.37124886252, 12420.0, 13264.270465124415, 21324.680129798064, 21618.783812161873, 21806.89096554519, 5201.271821944931, 5416.268803711488, 5418.29562156919, 5428.864278393052, 5434.402215756264, 5007.599392048645, 5291.708956007418, 6597.043428702836, 6664.866702665944, 6714.261431242559, 6065.0902364645435, 6656.800295991128, 6687.83280417991, 6690.096836927001, 6692.330766923293, 5955.583109182359, 5994.250718660168, 6014.188084608566, 6019.277686677583, 6029.577929544934, 5576.553875689935, 5849.970750146264, 5862.0, 5864.296284445851, 5937.0, 13710.24593647349, 13870.098443601235, 14611.78082328766, 25489.362765930815, 25696.0, 2456.4718585504024, 2496.364881002946, 2520.861352625605, 2532.822702410832, 2540.606206038067, 9774.90225097747, 10439.37363758177, 10570.260081794277, 12224.816627750592, 15220.771688424682, 1587.618971446856, 1726.2835923091902, 1764.97352539712, 1781.1806568978286, 1819.0631824610336, 134064.0, 134930.0, 135608.0, 138070.30964845212, 141936.0, 11640.592579259684, 11803.049237690577, 11885.0, 11923.940380298043, 12093.972012379047, 16011.439599613957, 16038.599035023984, 16047.277872495766, 16056.27746751399, 16099.275532601061, 8219.712310069119, 8397.244248017707, 8560.272376847914, 8679.566021698889, 8699.565021749015, 7882.369410447151, 8062.355011599058, 8158.877616835753, 8165.510069395856, 8312.21034001769, 4814.662973591815, 4826.710397376169, 4828.227483602606, 4838.709677419366, 4891.437484689235, 1822.851603489793, 1890.933817316415, 1951.3950675290705, 2013.5167559785546, 2022.2012305139388, 393.190028541206, 419.0466688284117, 429.29809761040923, 431.5123909981701, 1021.9437930913722, 11978.221415607957, 12051.698707532381, 12252.754944901051, 12311.815322770166, 12361.381930903419, 444.46885971264027, 449.81107934667517, 455.5672111494094, 462.53977292594084, 476.7282648890169, 5386.5152136307925, 5753.51095156908, 6093.238345206849, 6248.343923888017, 6295.905561416583, 414928.0, 618744.0, 674506.0, 682006.589967047, 701892.0, 114597.70804583862, 142206.15587688188, 143264.28367858095, 151800.0, 152770.0, 356.53472218754774, 455.71062375391205, 456.1174128062144, 456.22441848857073, 456.66891503660247, 11258.887411125785, 11282.717932051764, 11354.637443506832, 11411.60059397917, 11579.478923448296, 42682.35976460294, 50032.0, 50143.498565013884, 54936.0, 55449.68254761818, 81555.0, 82255.35489290301, 83574.0, 85554.57222713847, 94649.73690916896, 5864.501517371071, 6167.876642467215, 7403.444741644506, 8209.671613135408, 8644.789729437758, 9651.469169195761, 12878.356082195665, 13130.606081817561, 13437.395317210554, 13711.177329360271, 11075.280106793032, 11601.303921764722, 11635.53457861676, 11769.058475322125, 11969.581064662694, 10779.97590228927, 11145.721356965816, 11212.943935280271, 11511.366874821964, 11767.588134415597, 10900.836487452852, 11566.537338506365, 11652.94173529127, 11681.182317237712, 11780.936956391572, 7382.926170738224, 7670.7315243966195, 8367.790805229917, 8741.0, 8884.244839188741, 10874.565017399216, 11486.138539609396, 11549.538018479167, 11710.473028713557, 11757.294562326288], "value": 8686.232374532427, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1244.6390546741445, 1409.1967578480271, 1423.7294913966346, 1437.496876093367, 1439.5897169306743, 7379.225181355954, 8102.230288122626, 8351.665933362658, 8354.997400311955, 8462.280706139985, 8219.671213151467, 8310.75067747967, 8311.459755115928, 8312.168783121684, 8320.542370169638, 1945.3288615427725, 2231.6094683430415, 2263.4567703751063, 2266.8186545076437, 2269.5347453771938, 1865.7481240032625, 2014.8992550372423, 2168.815650669695, 2243.080337061805, 2264.275431861805, 2203.6584329428965, 2225.810806081485, 2227.7103976483118, 2230.7434645015787, 2237.910483580663, 2224.8665080095166, 2247.5504899020098, 2249.6850440938283, 2255.988720056406, 2258.311215079408, 2207.006846918877, 2238.71274017439, 2262.242148880127, 2271.0802125139344, 2273.4089136824377, 8283.668653253862, 8384.03583587889, 8387.454815436977, 8393.6222869971, 8406.033306169807, 4990.8752281193265, 5125.2312153176945, 5127.256547800585, 5137.537621614036, 5139.049275883974, 3275.7870738401925, 3358.345122701074, 3419.247765491588, 3427.241070937894, 3517.2262102337445, 1159.8782127876536, 1197.5449329254914, 1199.7720433117768, 1243.1298091336012, 1262.8800263974908, 10789.784204315869, 10881.619143329945, 10923.399213043358, 10932.398718070583, 10949.178811589083, 4424.579664931826, 4597.287420449836, 4605.0559635274685, 4673.95326046742, 4693.577578017995, 9377.062293770568, 9543.570539325747, 9565.234781217487, 9568.665096721581, 9600.0, 5222.529972302511, 5224.425313215544, 5225.111731005738, 5229.424763276036, 5232.502912223333, 3962.663173630216, 4670.276107203389, 4752.857414277577, 4753.073150735642, 4773.451053128934, 3760.811959402019, 3790.2040571480297, 3822.522184726909, 3881.670058045098, 3899.493065901415, 1136.2784631758727, 1206.6184219069116, 1256.5099611151475, 1261.2495565138727, 1271.0784681106124, 829.995850020746, 865.0873328638347, 891.4695756025154, 898.1736802142045, 933.3280038372361, 5398.676079435247, 5487.5884308677005, 5601.439856014366, 5660.405657405913, 5769.1634712967225, 700.7967689370128, 964.5469220191696, 990.4206039466849, 1002.8144793213279, 1014.8224060789332, 3595.640435956436, 3718.1448266898765, 3805.5052843130215, 3847.576766555677, 3847.9807600961817, 9827.557759900821, 10155.238357123246, 10169.644062457779, 10183.388996660224, 10185.898141018495, 9290.256779457763, 9763.902360976299, 10024.448655324028, 10143.442110683984, 10146.44194569291, 7003.5797852129035, 7359.963200183965, 7863.960680196562, 7890.250426209615, 7896.368290536743, 5548.500634942875, 5553.16702494629, 5566.22072909801, 5793.768249270151, 5912.940870591239, 7634.236576342321, 8513.872291915503, 8555.529445880537, 9345.859812102688, 9403.247740180906, 8768.298536116969, 9222.123898229658, 9366.297527685314, 9447.858282125912, 9573.856392154263, 6560.540762146604, 6688.598684078971, 6807.0, 7196.856062878608, 7267.672954717156, 7852.84294314122, 7927.286544211049, 8004.83990320202, 8006.119326873923, 8014.479058861155, 7144.571325720473, 7188.5327453715345, 7231.5299505531975, 7250.818729531804, 7265.709371625076, 7031.261717519791, 7081.645917704197, 7116.750913718202, 7141.785746427616, 7143.2499587544635, 6286.271275399302, 6432.2281326241155, 6444.45222156122, 6448.484121270381, 6459.0, 4265.232258193555, 4812.975935120302, 4940.431850337113, 4991.9500804991485, 5148.330717006766, 4242.257604919188, 4919.877003074952, 5015.949840501548, 5017.874553136055, 5218.478152184751], "value": 4375.318984426671, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.47967816482077, 345.20784388934175, 364.50973440722237, 366.65717554086945, 368.77873276034364, 4108.157827645333, 4981.982297169648, 5218.112920803458, 5250.4487028861995, 5291.126964050927, 4297.634701050414, 4557.1569259686985, 4723.787824279489, 4904.438871849179, 5140.742962851852, 3423.760336776427, 3810.5617853946737, 3879.9612003880025, 3974.642282194602, 3984.0637450199324, 3297.7856439331485, 3957.3866050762035, 4006.4559005031724, 4013.1740058273417, 4023.839046438139, 3574.160072382988, 3639.6178401267885, 3676.871309504168, 3940.9605903941024, 3946.625070618287, 3944.3294639910996, 3979.6020397959974, 3981.0, 3994.400839874042, 4001.9599804002023, 2684.8657567121763, 3029.9698102645207, 3348.916277093068, 3480.860765569374, 3495.06024156593, 4138.337865941435, 4820.408795447414, 5041.764767631954, 5046.167382381903, 5056.544910958031, 3801.9239615207816, 4507.2788353863225, 4519.864404067883, 4528.8451444881675, 4548.5906268436, 5191.714455704935, 5383.327084114486, 5448.591355648303, 5556.222128901957, 5567.832965011056, 3775.4903088083306, 3781.1681430085337, 3785.791781452019, 3792.9518060247433, 3800.5629352624524, 4347.69566130371, 4598.586127248564, 5460.399356070829, 5537.750801213955, 5578.470045345685, 3643.5627724672677, 3808.1622043150464, 3834.2331533693373, 3837.501124853751, 3852.4799152114106, 3889.6110388961442, 3902.2780785554764, 3902.804859757001, 3922.2547715933883, 3931.567527571965, 3975.582563830756, 4265.677639931631, 4512.909741805145, 4560.657950653715, 4573.438874230442, 4204.409333452171, 5060.949390506048, 5321.600879934021, 5510.724463776795, 5558.8604336111, 1075.7041813501194, 1152.389233706137, 1158.8006413362298, 1236.344737289238, 1244.775940330749, 778.6301506784346, 796.6972550430916, 842.9789255268668, 847.174005344784, 867.778716427307, 4453.101588252961, 4555.544445555418, 4791.6406269529925, 4821.79455136216, 4872.707637541759, 4702.788374523155, 5202.2803695358, 5392.325959255093, 5397.271368365299, 5423.484768946943, 4166.812493437742, 4315.611594956469, 4389.868303950887, 4412.3160910059, 4816.349792778, 2333.9183128590416, 2587.9353016174746, 2706.594010898342, 2758.793090518179, 2808.4693841856238, 4529.071540334224, 4840.346553215341, 4956.975215123901, 5032.446430892599, 5412.485813847757, 5236.371635403776, 5284.199374331611, 5508.9724551377785, 5523.972380138073, 5563.360213575491, 4934.3338649282605, 5217.191335343028, 5503.972480137654, 5550.530014143748, 5607.299087614048, 4924.261360795909, 4957.4639205126905, 5258.158694608845, 5339.864054378279, 5398.811041613525, 4140.4617399737845, 4171.416001759817, 4510.548945105464, 5233.633645644921, 5268.683878967274, 4545.204589196944, 4862.9756851216935, 5684.403137670485, 5713.143028545752, 5729.369769325454, 3215.5658986036588, 3772.358699021228, 3800.3919372900205, 3810.2760475509563, 3839.0074322704077, 5670.943290567042, 5688.947544704243, 5709.25779648643, 5710.200571919851, 5716.28546431696, 4892.2661600760175, 5171.250168725439, 5277.973610131925, 5452.809151679672, 5493.423190564933, 4435.423394958635, 4464.955350446454, 5466.699331536804, 5487.4238204987905, 5532.068003957908, 5166.269299784614, 5358.0, 5384.83056265989, 5404.108801791023, 5434.2935418395355, 4644.58198762113, 4796.592289655418, 5457.754401052041, 5518.392976772471, 5539.479536810822, 4621.584057434847, 4979.377577802775, 5112.258722485144, 5246.029484545371, 5353.812616558402], "value": 3890.0564865110628, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [700.1877821726791, 715.7673756029288, 755.7317152410894, 809.6558962440963, 821.9301359384452, 14253.928730356347, 14459.78310325346, 14774.409023639068, 14831.0, 14831.184284864328, 11980.580679676214, 13154.408051637676, 13349.399277032533, 13374.531891383804, 13380.866191338084, 855.7732200966749, 863.5897948474455, 881.2685471059006, 882.7307671160326, 885.3890815337429, 856.2251162697737, 869.0744357259498, 872.16708043818, 872.3152325424543, 873.3668090634303, 817.4523069543391, 902.9074819468451, 926.2821313482038, 927.5130556457846, 927.9884925431279, 916.7799728065249, 917.3532659475054, 917.4357769971487, 917.857732051533, 919.2278486071698, 669.1034014420651, 683.5932620091038, 684.3601232847303, 684.849333146707, 684.9657517124174, 12418.81371779424, 12658.177218480769, 12712.110152289344, 12814.590395056537, 12861.164024338388, 11583.18917675763, 12185.903268705859, 12520.937395312965, 12596.937015314956, 12811.487540498369, 11017.724556886142, 12540.557835848904, 12579.937100314532, 12627.93686031573, 12953.870461295455, 4239.258129827268, 4423.469183697945, 4433.933490997638, 4465.799039043251, 4523.932141017887, 91165.0, 92402.07597924002, 93524.06475935222, 93944.0, 94141.5292923531, 10342.689719308433, 10424.947875260727, 10524.105451036594, 10674.0, 10687.572497100184, 19959.60080798376, 20187.39437816868, 20222.393328200178, 20226.98865056741, 20266.0, 7189.784306470814, 7271.0, 7303.89044164338, 7397.26027397256, 7515.962420187864, 4075.0, 4125.216208920296, 4136.689748268892, 4143.233501802176, 4153.91692166155, 3408.7613867029568, 3517.3492903812876, 3523.1896663767475, 3523.7004854587135, 3545.8936231913085, 535.376286626084, 646.8479907221799, 658.8263351667147, 663.7378235596913, 679.4700133895569, 492.5173330136486, 518.8625014371195, 530.4059453412203, 532.5180711456138, 533.8772082421065, 8235.752927412188, 8571.22858942698, 9041.933460132783, 9471.526423678926, 9577.329586928987, 593.2702775586054, 596.8000719758843, 657.0964923230558, 658.2101478226052, 661.6394065234459, 4456.376107344974, 4526.524714904954, 4546.636269098464, 4650.348951146842, 4732.526747325309, 93549.0, 97137.05725885443, 98916.80870951888, 99639.0, 101752.49123754333, 75777.0, 78385.0, 78535.21464785279, 78733.0, 80562.59718701369, 61531.15406537901, 66524.0, 74534.0, 74627.25372746312, 75532.0, 8753.737387878375, 8759.824803504021, 8774.947006359022, 8885.555722213734, 9064.728058158265, 39980.80009599934, 49558.504414956835, 50055.74972125116, 50387.74030649116, 50469.0, 64650.0, 65841.35396615123, 66600.0, 67305.0, 67546.66226668835, 10241.590336386462, 10322.2258330624, 10489.475526223505, 10610.469476525988, 11170.385628790169, 11686.298822070703, 11707.88292117102, 12021.218620789621, 12171.573994910135, 12298.385080746106, 10019.599216031276, 10268.97310268997, 11323.037541809039, 11516.963473287444, 11522.251053681484, 8694.347923905605, 10872.61945831892, 11036.834447483454, 11210.551577937118, 11259.268147570381, 10529.10502607287, 11170.944145279222, 11189.608363707232, 11213.887861121284, 11224.326540407601, 9104.576103836685, 9441.811163776823, 9499.857502137611, 9502.619895204114, 9689.515524223902, 7897.723579674684, 7962.601869906597, 8888.466691998501, 8957.372983891066, 9241.0], "value": 6941.957777522266, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1452.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 154.0, 113.0, 112.0, 112.0, 113.0, 120.0, 166.0, 156.0, 113.0], "value": 127.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 103.0, 144.0, 112.0, 109.0, 111.0, 110.0, 129.0, 108.0], "value": 117.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 185.0, 181.0, 183.0, 181.0, 182.0, 186.0, 185.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 87.0, 60.0, 59.0, 58.0, 57.0, 74.0, 59.0], "value": 65.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 70.0, 86.0, 70.0, 68.0, 70.0, 86.0, 58.0, 58.0, 89.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 90.0, 87.0, 91.0, 87.0, 90.0, 87.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 99.0, 101.0, 100.0, 100.0, 99.0, 99.0, 106.0, 99.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 86.0, 88.0, 85.0, 85.0, 85.0, 86.0, 85.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 58.0, 57.0, 59.0, 59.0, 58.0, 58.0, 59.0, 58.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 70.0, 75.0, 71.0, 68.0, 69.0, 70.0, 69.0, 70.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 88.0, 89.0, 84.0, 90.0, 87.0, 86.0, 88.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 142.0, 140.0, 136.0, 148.0, 151.0, 151.0, 151.0, 181.0, 156.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 173.0, 172.0, 180.0, 172.0, 172.0, 174.0, 173.0, 227.0, 160.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 68.0, 67.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 66.0, "unit": "score"}]}]} 04:50:54 INFO - Return code: 0 04:50:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:50:54 ERROR - # TBPL SUCCESS # 04:50:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:50:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:50:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:50:54 INFO - 2017-02-06 04:50:54.438 screenresolution[1663:10525] starting screenresolution argv=screenresolution get 04:50:54 INFO - 2017-02-06 04:50:54.441 screenresolution[1663:10525] Display 0: 1600x1200x32@60 04:50:54 INFO - 2017-02-06 04:50:54.447 screenresolution[1664:10528] starting screenresolution argv=screenresolution list 04:50:54 INFO - Available Modes on Display 0 04:50:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:50:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:50:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:50:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:50:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:50:54 INFO - Intel Iris: 04:50:54 INFO - Chipset Model: Intel Iris 04:50:54 INFO - Type: GPU 04:50:54 INFO - Bus: Built-In 04:50:54 INFO - VRAM (Dynamic, Max): 1536 MB 04:50:54 INFO - Vendor: Intel (0x8086) 04:50:54 INFO - Device ID: 0x0a2e 04:50:54 INFO - Revision ID: 0x0009 04:50:54 INFO - Displays: 04:50:54 INFO - Display: 04:50:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:50:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:50:54 INFO - Display Serial Number: 1600x1200 60 04:50:54 INFO - Main Display: Yes 04:50:54 INFO - Mirror: Off 04:50:54 INFO - Online: Yes 04:50:54 INFO - Rotation: Supported 04:50:54 INFO - Return code: 0 04:50:54 INFO - Running post-action listener: _resource_record_post_action 04:50:54 INFO - [mozharness: 2017-02-06 12:50:54.687144Z] Finished run-tests step (success) 04:50:54 INFO - Running post-run listener: _resource_record_post_run 04:50:54 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32549888; Write bytes: 554914304; Read time: 196; Write time: 1650 04:50:54 INFO - TinderboxPrint: CPU usage
53.6% 04:50:54 INFO - TinderboxPrint: I/O read bytes / time
32,549,888 / 196 04:50:54 INFO - TinderboxPrint: I/O write bytes / time
554,914,304 / 1,650 04:50:54 INFO - TinderboxPrint: CPU idle
2,233.5 (46.4%) 04:50:54 INFO - TinderboxPrint: CPU system
961.8 (20.0%) 04:50:54 INFO - TinderboxPrint: CPU user
1,614.5 (33.6%) 04:50:54 INFO - TinderboxPrint: Swap in / out
280,891,392 / 0 04:50:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244081152; Write bytes: 238643200; Read time: 17833; Write time: 486 04:50:54 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30587904; Write bytes: 299031040; Read time: 179; Write time: 1121 04:50:55 INFO - Running post-run listener: _upload_blobber_files 04:50:55 INFO - Blob upload gear active. 04:50:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:50:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:50:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:50:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:50:55 INFO - (blobuploader) - INFO - Open directory for files ... 04:50:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:50:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:50:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:56 INFO - (blobuploader) - INFO - Done attempting. 04:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:50:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:57 INFO - (blobuploader) - INFO - Done attempting. 04:50:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:50:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:50:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:50:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:50:58 INFO - (blobuploader) - INFO - Done attempting. 04:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:51:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:01 INFO - (blobuploader) - INFO - Done attempting. 04:51:01 INFO - (blobuploader) - INFO - Iteration through files over. 04:51:01 INFO - Return code: 0 04:51:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:51:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:51:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d646b4b42f3f2e13cf051a01649dc43c04ad4927761288876d9de4aa73beeab5c716484f221cf2e326b884527f2e232ee3b0268ab0bea02aa51589fafeb79e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ca599e7ce62746c8445898ae9eb7cd8a60a5a63d756a05ebf9ded394ff8535485f0c8f9640144372f279242b9f5a3768c42a26904a9afd27c1093c39766fcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e49ffdd1f4c8e33d9294cd0e51fc466c569bb08f43a3148a591d7b3c97f540beca238c45c6f3ffba187e516223f86a667c811219a87745639fca0d78fe94fa9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ecd8498e02216df33301b860e9d19eff208347ae87280a247001971ac8c751b3b783a0c2fac1c05b3ea8ab7fe56326a6eb7412dc8c48ef4ad61b06dfbfb880b"} 04:51:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:51:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:51:01 INFO - Contents: 04:51:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d646b4b42f3f2e13cf051a01649dc43c04ad4927761288876d9de4aa73beeab5c716484f221cf2e326b884527f2e232ee3b0268ab0bea02aa51589fafeb79e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ca599e7ce62746c8445898ae9eb7cd8a60a5a63d756a05ebf9ded394ff8535485f0c8f9640144372f279242b9f5a3768c42a26904a9afd27c1093c39766fcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e49ffdd1f4c8e33d9294cd0e51fc466c569bb08f43a3148a591d7b3c97f540beca238c45c6f3ffba187e516223f86a667c811219a87745639fca0d78fe94fa9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ecd8498e02216df33301b860e9d19eff208347ae87280a247001971ac8c751b3b783a0c2fac1c05b3ea8ab7fe56326a6eb7412dc8c48ef4ad61b06dfbfb880b"} 04:51:01 INFO - Running post-run listener: copy_logs_to_upload_dir 04:51:01 INFO - Copying logs to upload dir... 04:51:01 INFO - mkdir: /builds/slave/test/build/upload/logs 04:51:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.400180 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-02-06 04:51:01.082616) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 04:51:01.086593) ========= 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.yVMLUlO5IV/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.AaTS429W7Q/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d646b4b42f3f2e13cf051a01649dc43c04ad4927761288876d9de4aa73beeab5c716484f221cf2e326b884527f2e232ee3b0268ab0bea02aa51589fafeb79e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ca599e7ce62746c8445898ae9eb7cd8a60a5a63d756a05ebf9ded394ff8535485f0c8f9640144372f279242b9f5a3768c42a26904a9afd27c1093c39766fcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e49ffdd1f4c8e33d9294cd0e51fc466c569bb08f43a3148a591d7b3c97f540beca238c45c6f3ffba187e516223f86a667c811219a87745639fca0d78fe94fa9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ecd8498e02216df33301b860e9d19eff208347ae87280a247001971ac8c751b3b783a0c2fac1c05b3ea8ab7fe56326a6eb7412dc8c48ef4ad61b06dfbfb880b"} build_url:https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011057 build_url: 'https://queue.taskcluster.net/v1/task/OTDf6WIzR6y5-dtsa2FChA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d646b4b42f3f2e13cf051a01649dc43c04ad4927761288876d9de4aa73beeab5c716484f221cf2e326b884527f2e232ee3b0268ab0bea02aa51589fafeb79e0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/18ca599e7ce62746c8445898ae9eb7cd8a60a5a63d756a05ebf9ded394ff8535485f0c8f9640144372f279242b9f5a3768c42a26904a9afd27c1093c39766fcf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e49ffdd1f4c8e33d9294cd0e51fc466c569bb08f43a3148a591d7b3c97f540beca238c45c6f3ffba187e516223f86a667c811219a87745639fca0d78fe94fa9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9ecd8498e02216df33301b860e9d19eff208347ae87280a247001971ac8c751b3b783a0c2fac1c05b3ea8ab7fe56326a6eb7412dc8c48ef4ad61b06dfbfb880b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 04:51:01.124828) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 04:51:01.125290) ========= 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.yVMLUlO5IV/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.AaTS429W7Q/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.005250 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 04:51:01.174104) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 04:51:01.174495) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 04:51:01.174834) ========= ========= Total master_lag: 0.19 =========