builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0004 starttime: 1489010181.14 results: success (0) buildid: 20170308122623 builduid: d0562f6066554afb890dad33787c0833 revision: fc5b01fb884a73b3bf8d7f03d30b7b8d498c52e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:21.135637) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:21.136111) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:21.136386) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:21.161211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:21.161481) ========= 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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-08 13:56:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.0M=0.001s 2017-03-08 13:56:21 (13.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138882 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:21.326219) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:21.326820) ========= 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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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.021643 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:21.378543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 13:56:21.379141) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fc5b01fb884a73b3bf8d7f03d30b7b8d498c52e9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fc5b01fb884a73b3bf8d7f03d30b7b8d498c52e9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R2Ad71Iwc6/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.CTuEzSB30J/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-08 13:56:21,471 truncating revision to first 12 chars 2017-03-08 13:56:21,471 Setting DEBUG logging. 2017-03-08 13:56:21,471 attempt 1/10 2017-03-08 13:56:21,471 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fc5b01fb884a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-08 13:56:23,161 unpacking tar archive at: mozilla-aurora-fc5b01fb884a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.945340 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 13:56:23.349775) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:23.350118) ========= script_repo_revision: fc5b01fb884a73b3bf8d7f03d30b7b8d498c52e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:23.350501) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:23.350765) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 13:56:23.370466) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-08 13:56:23.370750) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.R2Ad71Iwc6/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.CTuEzSB30J/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:56:23 INFO - MultiFileLogger online at 20170308 13:56:23 in /builds/slave/test 13:56:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:56:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:56:23 INFO - {'append_to_log': False, 13:56:23 INFO - 'base_work_dir': '/builds/slave/test', 13:56:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:56:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:56:23 INFO - 'branch': 'Mozilla-Aurora', 13:56:23 INFO - 'buildbot_json_path': 'buildprops.json', 13:56:23 INFO - 'config_files': ('talos/mac_config.py',), 13:56:23 INFO - 'default_actions': ('clobber', 13:56:23 INFO - 'read-buildbot-config', 13:56:23 INFO - 'download-and-extract', 13:56:23 INFO - 'populate-webroot', 13:56:23 INFO - 'create-virtualenv', 13:56:23 INFO - 'install', 13:56:23 INFO - 'run-tests'), 13:56:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:56:23 INFO - 'download_minidump_stackwalk': True, 13:56:23 INFO - 'download_symbols': 'ondemand', 13:56:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:56:23 INFO - 'tooltool.py': '/tools/tooltool.py', 13:56:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:56:23 INFO - '/tools/misc-python/virtualenv.py')}, 13:56:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:56:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:56:23 INFO - 'gecko_profile': False, 13:56:23 INFO - 'gecko_profile_interval': 0, 13:56:23 INFO - 'installer_path': 'installer.exe', 13:56:23 INFO - 'log_level': 'info', 13:56:23 INFO - 'log_name': 'talos', 13:56:23 INFO - 'log_to_console': True, 13:56:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:56:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:56:23 INFO - 'opt_config_files': (), 13:56:23 INFO - 'pip_index': False, 13:56:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:56:23 INFO - 'cmd': ('bash', 13:56:23 INFO - '-c', 13:56:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:56:23 INFO - 'enabled': True, 13:56:23 INFO - 'halt_on_failure': False, 13:56:23 INFO - 'name': 'check_screen_resolution'},), 13:56:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:56:23 INFO - 'cmd': ('bash', 13:56:23 INFO - '-c', 13:56:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:56:23 INFO - 'enabled': True, 13:56:23 INFO - 'halt_on_failure': False, 13:56:23 INFO - 'name': 'check_screen_resolution'},), 13:56:23 INFO - 'run_cmd_checks_enabled': True, 13:56:23 INFO - 'suite': 'dromaeojs-e10s', 13:56:23 INFO - 'system_bits': '32', 13:56:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:56:23 INFO - 'title': 't-yosemite-r7-0004', 13:56:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:56:23 INFO - 'use_talos_json': True, 13:56:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:56:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:56:23 INFO - 'work_dir': 'build'} 13:56:23 INFO - [mozharness: 2017-03-08 21:56:23.583694Z] Running clobber step. 13:56:23 INFO - Running pre-action listener: _resource_record_pre_action 13:56:23 INFO - Running main action method: clobber 13:56:23 INFO - rmtree: /builds/slave/test/build 13:56:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:56:25 INFO - Running post-action listener: _resource_record_post_action 13:56:25 INFO - [mozharness: 2017-03-08 21:56:25.287493Z] Finished clobber step (success) 13:56:25 INFO - [mozharness: 2017-03-08 21:56:25.287661Z] Running read-buildbot-config step. 13:56:25 INFO - Running pre-action listener: _resource_record_pre_action 13:56:25 INFO - Running main action method: read_buildbot_config 13:56:25 INFO - Using buildbot properties: 13:56:25 INFO - { 13:56:25 INFO - "project": "", 13:56:25 INFO - "product": "firefox", 13:56:25 INFO - "script_repo_revision": "production", 13:56:25 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:56:25 INFO - "repository": "", 13:56:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:56:25 INFO - "buildid": "20170308122623", 13:56:25 INFO - "pgo_build": "False", 13:56:25 INFO - "basedir": "/builds/slave/test", 13:56:25 INFO - "buildnumber": 8, 13:56:25 INFO - "slavename": "t-yosemite-r7-0004", 13:56:25 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:56:25 INFO - "platform": "yosemite_r7", 13:56:25 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:56:25 INFO - "branch": "mozilla-aurora", 13:56:25 INFO - "repo_path": "releases/mozilla-aurora", 13:56:25 INFO - "revision": "fc5b01fb884a73b3bf8d7f03d30b7b8d498c52e9", 13:56:25 INFO - "stage_platform": "macosx64", 13:56:25 INFO - "builduid": "d0562f6066554afb890dad33787c0833", 13:56:25 INFO - "slavebuilddir": "test" 13:56:25 INFO - } 13:56:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 13:56:25 INFO - Running post-action listener: _resource_record_post_action 13:56:25 INFO - [mozharness: 2017-03-08 21:56:25.291031Z] Finished read-buildbot-config step (success) 13:56:25 INFO - [mozharness: 2017-03-08 21:56:25.291158Z] Running download-and-extract step. 13:56:25 INFO - Running pre-action listener: _resource_record_pre_action 13:56:25 INFO - Running main action method: download_and_extract 13:56:25 INFO - mkdir: /builds/slave/test/build/tests 13:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:25 INFO - trying https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 13:56:25 INFO - Downloading https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 13:56:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 13:56:26 INFO - Downloaded 1199 bytes. 13:56:26 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 13:56:26 INFO - Using the following test package requirements: 13:56:26 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 13:56:26 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:56:26 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 13:56:26 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:56:26 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 13:56:26 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:56:26 INFO - u'jsshell-mac.zip'], 13:56:26 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:56:26 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 13:56:26 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 13:56:26 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:56:26 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 13:56:26 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:56:26 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 13:56:26 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:56:26 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 13:56:26 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 13:56:26 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 13:56:26 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:56:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 13:56:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:56:26 INFO - Fetch https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 13:56:28 INFO - Expected file size: 51777055 13:56:28 INFO - Obtained file size: 51777055 13:56:44 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:56:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 13:56:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:56:44 INFO - Fetch https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 13:56:46 INFO - Expected file size: 51777055 13:56:46 INFO - Obtained file size: 51777055 13:57:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 13:57:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:57:01 INFO - Fetch https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 13:57:02 INFO - Expected file size: 14404424 13:57:02 INFO - Obtained file size: 14404424 13:57:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:03 INFO - trying https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:57:03 INFO - Downloading https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 13:57:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 13:57:05 INFO - Downloaded 74610118 bytes. 13:57:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:57:05 INFO - mkdir: /builds/slave/test/properties 13:57:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:57:05 INFO - Writing to file /builds/slave/test/properties/build_url 13:57:05 INFO - Contents: 13:57:05 INFO - build_url:https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 13:57:06 INFO - Running post-action listener: _resource_record_post_action 13:57:06 INFO - Running post-action listener: set_extra_try_arguments 13:57:06 INFO - [mozharness: 2017-03-08 21:57:06.049963Z] Finished download-and-extract step (success) 13:57:06 INFO - [mozharness: 2017-03-08 21:57:06.050115Z] Running populate-webroot step. 13:57:06 INFO - Running pre-action listener: _resource_record_pre_action 13:57:06 INFO - Running main action method: populate_webroot 13:57:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:57:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:57:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:57:06 INFO - '--tppagecycles', 13:57:06 INFO - '7'], 13:57:06 INFO - 'tests': ['tsvgm']}}, 13:57:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:57:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:57:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:57:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:57:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:57:06 INFO - 'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:57:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:57:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:57:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:57:06 INFO - 'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['damp', 'tps']}, 13:57:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:57:06 INFO - 'tests': ['damp', 'tps']}, 13:57:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['dromaeo_dom']}, 13:57:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:57:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['basic_compositor_video']}, 13:57:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:57:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['a11yr', 13:57:06 INFO - 'ts_paint', 13:57:06 INFO - 'tpaint', 13:57:06 INFO - 'sessionrestore', 13:57:06 INFO - 'sessionrestore_no_auto_restore', 13:57:06 INFO - 'tabpaint']}, 13:57:06 INFO - 'other-e10s': {'tests': ['a11yr', 13:57:06 INFO - 'ts_paint', 13:57:06 INFO - 'tpaint', 13:57:06 INFO - 'sessionrestore', 13:57:06 INFO - 'sessionrestore_no_auto_restore', 13:57:06 INFO - 'tabpaint']}, 13:57:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:57:06 INFO - 'ts_paint', 13:57:06 INFO - 'tpaint', 13:57:06 INFO - 'sessionrestore', 13:57:06 INFO - 'sessionrestore_no_auto_restore', 13:57:06 INFO - 'tabpaint']}, 13:57:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:57:06 INFO - 'ts_paint', 13:57:06 INFO - 'tpaint', 13:57:06 INFO - 'sessionrestore', 13:57:06 INFO - 'sessionrestore_no_auto_restore', 13:57:06 INFO - 'tabpaint']}, 13:57:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['a11yr', 13:57:06 INFO - 'ts_paint', 13:57:06 INFO - 'tpaint', 13:57:06 INFO - 'sessionrestore', 13:57:06 INFO - 'sessionrestore_no_auto_restore', 13:57:06 INFO - 'tabpaint']}, 13:57:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['a11yr', 13:57:06 INFO - 'ts_paint', 13:57:06 INFO - 'tpaint', 13:57:06 INFO - 'sessionrestore', 13:57:06 INFO - 'sessionrestore_no_auto_restore', 13:57:06 INFO - 'tabpaint']}, 13:57:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['tsvgx', 13:57:06 INFO - 'tsvgr_opacity', 13:57:06 INFO - 'tart', 13:57:06 INFO - 'tscrollx', 13:57:06 INFO - 'cart', 13:57:06 INFO - 'tsvg_static']}, 13:57:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:57:06 INFO - 'tsvgr_opacity', 13:57:06 INFO - 'tart', 13:57:06 INFO - 'tscrollx', 13:57:06 INFO - 'cart', 13:57:06 INFO - 'tsvg_static']}, 13:57:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:57:06 INFO - 'talos_options': ['--disable-e10s'], 13:57:06 INFO - 'tests': ['tp5o']}, 13:57:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:57:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:57:06 INFO - 'talos_options': ['--disable-e10s', 13:57:06 INFO - '--xperf_path', 13:57:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:57:06 INFO - 'tests': ['tp5n']}, 13:57:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:57:06 INFO - 'talos_options': ['--xperf_path', 13:57:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:57:06 INFO - 'tests': ['tp5n']}}, 13:57:06 INFO - 'talos.zip': {'path': '', 13:57:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:57:06 INFO - Running post-action listener: _resource_record_post_action 13:57:06 INFO - [mozharness: 2017-03-08 21:57:06.068408Z] Finished populate-webroot step (success) 13:57:06 INFO - [mozharness: 2017-03-08 21:57:06.068523Z] Running create-virtualenv step. 13:57:06 INFO - Running pre-action listener: _resource_record_pre_action 13:57:06 INFO - Running main action method: create_virtualenv 13:57:06 INFO - Creating virtualenv /builds/slave/test/build/venv 13:57:06 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 13:57:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:57:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:57:06 INFO - Using real prefix '/tools/python27' 13:57:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:57:06 INFO - Installing distribute.............................................................................................................................................................................................done. 13:57:09 INFO - Installing pip.................done. 13:57:09 INFO - Return code: 0 13:57:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:57:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:57:10 INFO - Reading from file tmpfile_stdout 13:57:10 INFO - Output received: 13:57:10 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) 13:57:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:57:10 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')]} 13:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:10 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')]} 13:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:10 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 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:10 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 13:57:10 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 13:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:10 INFO - 'HOME': '/Users/cltbld', 13:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:10 INFO - 'LOGNAME': 'cltbld', 13:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:10 INFO - 'MOZ_NO_REMOTE': '1', 13:57:10 INFO - 'NO_EM_RESTART': '1', 13:57:10 INFO - 'PAGER': '/bin/cat', 13:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:10 INFO - 'PWD': '/builds/slave/test', 13:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:10 INFO - 'SHELL': '/bin/bash', 13:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:10 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:10 INFO - 'USER': 'cltbld', 13:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:10 INFO - 'XPC_FLAGS': '0x0', 13:57:10 INFO - 'XPC_SERVICE_NAME': '0', 13:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:10 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 13:57:10 INFO - Cleaning up... 13:57:10 INFO - Return code: 0 13:57:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:57:10 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')]} 13:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:10 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')]} 13:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:10 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 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:10 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 13:57:10 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 13:57:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:10 INFO - 'HOME': '/Users/cltbld', 13:57:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:10 INFO - 'LOGNAME': 'cltbld', 13:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:10 INFO - 'MOZ_NO_REMOTE': '1', 13:57:10 INFO - 'NO_EM_RESTART': '1', 13:57:10 INFO - 'PAGER': '/bin/cat', 13:57:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:10 INFO - 'PWD': '/builds/slave/test', 13:57:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:10 INFO - 'SHELL': '/bin/bash', 13:57:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:10 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:10 INFO - 'USER': 'cltbld', 13:57:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:10 INFO - 'XPC_FLAGS': '0x0', 13:57:10 INFO - 'XPC_SERVICE_NAME': '0', 13:57:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:10 INFO - Downloading/unpacking psutil>=3.1.1 13:57:10 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 13:57:10 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 13:57:10 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 13:57:10 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 13:57:10 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 13:57:10 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 13:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:57:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:57:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:57:13 INFO - Installing collected packages: psutil 13:57:13 INFO - Running setup.py install for psutil 13:57:14 INFO - building 'psutil._psutil_osx' extension 13:57:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:57:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:57:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:57:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:57:14 INFO - building 'psutil._psutil_posix' extension 13:57:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:57:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:57:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:57:14 INFO - ^ 13:57:14 INFO - 1 warning generated. 13:57:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:57:14 INFO - Successfully installed psutil 13:57:14 INFO - Cleaning up... 13:57:14 INFO - Return code: 0 13:57:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:57: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')]} 13:57:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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')]} 13:57:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:57:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:57:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:14 INFO - 'HOME': '/Users/cltbld', 13:57:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:14 INFO - 'LOGNAME': 'cltbld', 13:57:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:14 INFO - 'MOZ_NO_REMOTE': '1', 13:57:14 INFO - 'NO_EM_RESTART': '1', 13:57:14 INFO - 'PAGER': '/bin/cat', 13:57:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:14 INFO - 'PWD': '/builds/slave/test', 13:57:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:14 INFO - 'SHELL': '/bin/bash', 13:57:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:14 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:14 INFO - 'USER': 'cltbld', 13:57:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:14 INFO - 'XPC_FLAGS': '0x0', 13:57:14 INFO - 'XPC_SERVICE_NAME': '0', 13:57:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:57:15 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 13:57:15 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 13:57:15 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 13:57:15 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 13:57:15 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 13:57:15 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 13:57:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:57:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:57:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:57:17 INFO - Installing collected packages: mozsystemmonitor 13:57:17 INFO - Running setup.py install for mozsystemmonitor 13:57:17 INFO - Successfully installed mozsystemmonitor 13:57:17 INFO - Cleaning up... 13:57:18 INFO - Return code: 0 13:57:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:57: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')]} 13:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57: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')]} 13:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:18 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 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:18 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 13:57:18 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 13:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:18 INFO - 'HOME': '/Users/cltbld', 13:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:18 INFO - 'LOGNAME': 'cltbld', 13:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:18 INFO - 'MOZ_NO_REMOTE': '1', 13:57:18 INFO - 'NO_EM_RESTART': '1', 13:57:18 INFO - 'PAGER': '/bin/cat', 13:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:18 INFO - 'PWD': '/builds/slave/test', 13:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:18 INFO - 'SHELL': '/bin/bash', 13:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:18 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:18 INFO - 'USER': 'cltbld', 13:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:18 INFO - 'XPC_FLAGS': '0x0', 13:57:18 INFO - 'XPC_SERVICE_NAME': '0', 13:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:18 INFO - Downloading/unpacking jsonschema==2.5.1 13:57:18 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 13:57:18 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 13:57:18 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 13:57:18 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 13:57:18 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 13:57:18 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 13:57:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:57:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:57:21 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 13:57:21 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 13:57:21 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 13:57:21 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 13:57:21 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 13:57:21 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 13:57:21 INFO - Downloading functools32-3.2.3-2.tar.gz 13:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:57:21 INFO - warning: no files found matching '*.txt' 13:57:21 INFO - no previously-included directories found matching 'build' 13:57:21 INFO - no previously-included directories found matching 'dist' 13:57:21 INFO - no previously-included directories found matching '.git*' 13:57:21 INFO - Installing collected packages: jsonschema, functools32 13:57:21 INFO - Running setup.py install for functools32 13:57:22 INFO - warning: no files found matching '*.txt' 13:57:22 INFO - no previously-included directories found matching 'build' 13:57:22 INFO - no previously-included directories found matching 'dist' 13:57:22 INFO - no previously-included directories found matching '.git*' 13:57:22 INFO - Successfully installed jsonschema functools32 13:57:22 INFO - Cleaning up... 13:57:22 INFO - Return code: 0 13:57:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:22 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 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:22 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 13:57:22 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 13:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:22 INFO - 'HOME': '/Users/cltbld', 13:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:22 INFO - 'LOGNAME': 'cltbld', 13:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:22 INFO - 'MOZ_NO_REMOTE': '1', 13:57:22 INFO - 'NO_EM_RESTART': '1', 13:57:22 INFO - 'PAGER': '/bin/cat', 13:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:22 INFO - 'PWD': '/builds/slave/test', 13:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:22 INFO - 'SHELL': '/bin/bash', 13:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:22 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:22 INFO - 'USER': 'cltbld', 13:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:22 INFO - 'XPC_FLAGS': '0x0', 13:57:22 INFO - 'XPC_SERVICE_NAME': '0', 13:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:57:22 INFO - Cleaning up... 13:57:22 INFO - Return code: 0 13:57:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:22 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 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:22 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 13:57:22 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 13:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:22 INFO - 'HOME': '/Users/cltbld', 13:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:22 INFO - 'LOGNAME': 'cltbld', 13:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:22 INFO - 'MOZ_NO_REMOTE': '1', 13:57:22 INFO - 'NO_EM_RESTART': '1', 13:57:22 INFO - 'PAGER': '/bin/cat', 13:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:22 INFO - 'PWD': '/builds/slave/test', 13:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:22 INFO - 'SHELL': '/bin/bash', 13:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:22 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:22 INFO - 'USER': 'cltbld', 13:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:22 INFO - 'XPC_FLAGS': '0x0', 13:57:22 INFO - 'XPC_SERVICE_NAME': '0', 13:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:22 INFO - Downloading/unpacking blobuploader==1.2.4 13:57:22 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 13:57:22 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 13:57:22 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 13:57:22 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 13:57:22 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 13:57:22 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 13:57:25 INFO - Downloading blobuploader-1.2.4.tar.gz 13:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:57:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:57:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:57: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 13:57: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 13:57: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 13:57: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 13:57: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 13:57: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 13:57:26 INFO - Downloading docopt-0.6.1.tar.gz 13:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:57:26 INFO - Installing collected packages: blobuploader, requests, docopt 13:57:26 INFO - Running setup.py install for blobuploader 13:57:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:57:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:57:26 INFO - Running setup.py install for requests 13:57:27 INFO - Running setup.py install for docopt 13:57:27 INFO - Successfully installed blobuploader requests docopt 13:57:27 INFO - Cleaning up... 13:57:27 INFO - Return code: 0 13:57:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:27 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 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:27 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 13:57:27 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 13:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:27 INFO - 'HOME': '/Users/cltbld', 13:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:27 INFO - 'LOGNAME': 'cltbld', 13:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:27 INFO - 'MOZ_NO_REMOTE': '1', 13:57:27 INFO - 'NO_EM_RESTART': '1', 13:57:27 INFO - 'PAGER': '/bin/cat', 13:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:27 INFO - 'PWD': '/builds/slave/test', 13:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:27 INFO - 'SHELL': '/bin/bash', 13:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:27 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:27 INFO - 'USER': 'cltbld', 13:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:27 INFO - 'XPC_FLAGS': '0x0', 13:57:27 INFO - 'XPC_SERVICE_NAME': '0', 13:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:57:27 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-wzwliQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:57:27 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-9xFGWw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:57:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:57:27 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-tPHeZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-8BekUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-_OtB_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-JKwvTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-CQg7Ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-zIpo7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-RjFAxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-iRiMfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-JoDXxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:57:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:57:28 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-DaZNRI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:57:29 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-dGqmU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:57:29 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-IM4Mnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:57:29 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-x5hDQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:57:29 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-W10Oyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:57:29 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-07ydvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:57:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:57:29 INFO - Running setup.py install for manifestparser 13:57:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:57:29 INFO - Running setup.py install for mozcrash 13:57:29 INFO - Running setup.py install for mozdebug 13:57:29 INFO - Running setup.py install for mozdevice 13:57:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:57:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:57:30 INFO - Running setup.py install for mozfile 13:57:30 INFO - Running setup.py install for mozhttpd 13:57:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:57:30 INFO - Running setup.py install for mozinfo 13:57:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:57:30 INFO - Running setup.py install for mozInstall 13:57:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:57:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:57:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:57:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:57:30 INFO - Running setup.py install for mozleak 13:57:30 INFO - Running setup.py install for mozlog 13:57:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:57:31 INFO - Running setup.py install for moznetwork 13:57:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:57:31 INFO - Running setup.py install for mozprocess 13:57:31 INFO - Running setup.py install for mozprofile 13:57:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:57:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:57:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:57:31 INFO - Running setup.py install for mozrunner 13:57:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:57:31 INFO - Running setup.py install for mozscreenshot 13:57:31 INFO - Running setup.py install for moztest 13:57:31 INFO - Running setup.py install for mozversion 13:57:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:57:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:57:32 INFO - Cleaning up... 13:57:32 INFO - Return code: 0 13:57:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:32 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 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:32 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 13:57:32 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 13:57:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:32 INFO - 'HOME': '/Users/cltbld', 13:57:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:32 INFO - 'LOGNAME': 'cltbld', 13:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:32 INFO - 'MOZ_NO_REMOTE': '1', 13:57:32 INFO - 'NO_EM_RESTART': '1', 13:57:32 INFO - 'PAGER': '/bin/cat', 13:57:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:32 INFO - 'PWD': '/builds/slave/test', 13:57:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:32 INFO - 'SHELL': '/bin/bash', 13:57:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:32 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:32 INFO - 'USER': 'cltbld', 13:57:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:32 INFO - 'XPC_FLAGS': '0x0', 13:57:32 INFO - 'XPC_SERVICE_NAME': '0', 13:57:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:57:32 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-s62Q4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:57:32 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)) 13:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:57:32 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-M9bzC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:57:32 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)) 13:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:57:32 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-aJOpvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:57:32 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)) 13:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:57:32 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-lft6LX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:57:32 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)) 13:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:57:32 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-wjTpo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:57:32 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)) 13:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:57:32 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-iUaccb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-Fl0kdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-Iau_kM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-HM9uJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-NBOgpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-kMd7eG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-nfvUQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:57:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-NrfCVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-Bw_gpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-dxKCKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:57:33 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)) 13:57:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:57:33 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-YxqHJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:57:34 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)) 13:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:57:34 INFO - Running setup.py (path:/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/pip-BeY14c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:57:34 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)) 13:57:34 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)) 13:57:34 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)) 13:57:34 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)) 13:57:34 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)) 13:57:34 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)) 13:57:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:57: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 13:57: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 13:57: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 13:57: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 13:57: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 13:57: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 13:57:36 INFO - Downloading blessings-1.6.tar.gz 13:57:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:57:36 INFO - Installing collected packages: blessings 13:57:36 INFO - Running setup.py install for blessings 13:57:37 INFO - Successfully installed blessings 13:57:37 INFO - Cleaning up... 13:57:37 INFO - Return code: 0 13:57:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:57:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:57:37 INFO - Reading from file tmpfile_stdout 13:57:37 INFO - Current package versions: 13:57:37 INFO - blessings == 1.6 13:57:37 INFO - blobuploader == 1.2.4 13:57:37 INFO - docopt == 0.6.1 13:57:37 INFO - functools32 == 3.2.3-2 13:57:37 INFO - jsonschema == 2.5.1 13:57:37 INFO - manifestparser == 1.1 13:57:37 INFO - mozInstall == 1.12 13:57:37 INFO - mozcrash == 1.0 13:57:37 INFO - mozdebug == 0.1 13:57:37 INFO - mozdevice == 0.49 13:57:37 INFO - mozfile == 1.2 13:57:37 INFO - mozhttpd == 0.7 13:57:37 INFO - mozinfo == 0.9 13:57:37 INFO - mozleak == 0.1 13:57:37 INFO - mozlog == 3.4 13:57:37 INFO - moznetwork == 0.27 13:57:37 INFO - mozprocess == 0.25 13:57:37 INFO - mozprofile == 0.28 13:57:37 INFO - mozrunner == 6.13 13:57:37 INFO - mozscreenshot == 0.1 13:57:37 INFO - mozsystemmonitor == 0.3 13:57:37 INFO - moztest == 0.8 13:57:37 INFO - mozversion == 1.4 13:57:37 INFO - psutil == 3.1.1 13:57:37 INFO - requests == 1.2.3 13:57:37 INFO - wsgiref == 0.1.2 13:57:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:57:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:37 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 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:37 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 13:57:37 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 13:57:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:37 INFO - 'HOME': '/Users/cltbld', 13:57:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:37 INFO - 'LOGNAME': 'cltbld', 13:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:37 INFO - 'MOZ_NO_REMOTE': '1', 13:57:37 INFO - 'NO_EM_RESTART': '1', 13:57:37 INFO - 'PAGER': '/bin/cat', 13:57:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:37 INFO - 'PWD': '/builds/slave/test', 13:57:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:37 INFO - 'SHELL': '/bin/bash', 13:57:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:37 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:37 INFO - 'USER': 'cltbld', 13:57:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:37 INFO - 'XPC_FLAGS': '0x0', 13:57:37 INFO - 'XPC_SERVICE_NAME': '0', 13:57:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 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)) 13:57:37 INFO - Cleaning up... 13:57:37 INFO - Return code: 0 13:57:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:57:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:57:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:57:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:57:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e966b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e969768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb713cbbab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, '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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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 13:57:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:57:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:37 INFO - 'HOME': '/Users/cltbld', 13:57:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:37 INFO - 'LOGNAME': 'cltbld', 13:57:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:37 INFO - 'MOZ_NO_REMOTE': '1', 13:57:37 INFO - 'NO_EM_RESTART': '1', 13:57:37 INFO - 'PAGER': '/bin/cat', 13:57:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:37 INFO - 'PWD': '/builds/slave/test', 13:57:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:37 INFO - 'SHELL': '/bin/bash', 13:57:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:37 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:37 INFO - 'USER': 'cltbld', 13:57:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:37 INFO - 'XPC_FLAGS': '0x0', 13:57:37 INFO - 'XPC_SERVICE_NAME': '0', 13:57:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:57:37 INFO - Cleaning up... 13:57:37 INFO - Return code: 0 13:57:37 INFO - Running post-action listener: _resource_record_post_action 13:57:37 INFO - Running post-action listener: _start_resource_monitoring 13:57:37 INFO - Starting resource monitoring. 13:57:37 INFO - [mozharness: 2017-03-08 21:57:37.941943Z] Finished create-virtualenv step (success) 13:57:37 INFO - [mozharness: 2017-03-08 21:57:37.943032Z] Running install step. 13:57:37 INFO - Running pre-action listener: _resource_record_pre_action 13:57:37 INFO - Running main action method: install 13:57:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:57:38 INFO - Reading from file tmpfile_stdout 13:57:38 INFO - Detecting whether we're running mozinstall >=1.0... 13:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:57:38 INFO - Reading from file tmpfile_stdout 13:57:38 INFO - Output received: 13:57:38 INFO - Usage: mozinstall [options] installer 13:57:38 INFO - Options: 13:57:38 INFO - -h, --help show this help message and exit 13:57:38 INFO - -d DEST, --destination=DEST 13:57:38 INFO - Directory to install application into. [default: 13:57:38 INFO - "/builds/slave/test"] 13:57:38 INFO - --app=APP Application being installed. [default: firefox] 13:57:38 INFO - mkdir: /builds/slave/test/build/application 13:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:57:58 INFO - Reading from file tmpfile_stdout 13:57:58 INFO - Output received: 13:57:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:57:58 INFO - Running post-action listener: _resource_record_post_action 13:57:58 INFO - [mozharness: 2017-03-08 21:57:58.752369Z] Finished install step (success) 13:57:58 INFO - [mozharness: 2017-03-08 21:57:58.752539Z] Running run-tests step. 13:57:58 INFO - Running pre-action listener: _resource_record_pre_action 13:57:58 INFO - Running main action method: run_tests 13:57:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:57:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:57:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:57:58 INFO - 2017-03-08 13:57:58.766 screenresolution[1911:16045] starting screenresolution argv=screenresolution get 13:57:58 INFO - 2017-03-08 13:57:58.769 screenresolution[1911:16045] Display 0: 1600x1200x32@60 13:57:58 INFO - 2017-03-08 13:57:58.775 screenresolution[1912:16047] starting screenresolution argv=screenresolution list 13:57:58 INFO - Available Modes on Display 0 13:57:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:57:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:57:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:57:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:57:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:57:59 INFO - Intel Iris: 13:57:59 INFO - Chipset Model: Intel Iris 13:57:59 INFO - Type: GPU 13:57:59 INFO - Bus: Built-In 13:57:59 INFO - VRAM (Dynamic, Max): 1536 MB 13:57:59 INFO - Vendor: Intel (0x8086) 13:57:59 INFO - Device ID: 0x0a2e 13:57:59 INFO - Revision ID: 0x0009 13:57:59 INFO - Displays: 13:57:59 INFO - Display: 13:57:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:57:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:57:59 INFO - Display Serial Number: 1600x1200 60 13:57:59 INFO - Main Display: Yes 13:57:59 INFO - Mirror: Off 13:57:59 INFO - Online: Yes 13:57:59 INFO - Rotation: Supported 13:57:59 INFO - Return code: 0 13:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:57:59 INFO - Python 2.7.3 13:57:59 INFO - Return code: 0 13:57:59 INFO - grabbing minidump binary from tooltool 13:57:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:57:59 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 0x10e744d30>, '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 0x10e66bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e739be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:57:59 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 13:57:59 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 13:57:59 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 13:57:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:57:59 INFO - Return code: 0 13:57:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:57:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:57:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:57:59 INFO - ENV: RUST_BACKTRACE is now 1 13:57:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:57:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:57:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:57:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0004 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:57:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.R2Ad71Iwc6/Render', 13:57:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:57:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:57:59 INFO - 'HOME': '/Users/cltbld', 13:57:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:57:59 INFO - 'LOGNAME': 'cltbld', 13:57:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:57:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:57:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:57:59 INFO - 'MOZ_NO_REMOTE': '1', 13:57:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:57:59 INFO - 'NO_EM_RESTART': '1', 13:57:59 INFO - 'PAGER': '/bin/cat', 13:57:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:57:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:57:59 INFO - 'PWD': '/builds/slave/test', 13:57:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:57:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:57:59 INFO - 'RUST_BACKTRACE': '1', 13:57:59 INFO - 'SHELL': '/bin/bash', 13:57:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CTuEzSB30J/Listeners', 13:57:59 INFO - 'TMPDIR': '/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/', 13:57:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:57:59 INFO - 'USER': 'cltbld', 13:57:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:57:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:57:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:57:59 INFO - 'XPC_FLAGS': '0x0', 13:57:59 INFO - 'XPC_SERVICE_NAME': '0', 13:57:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:57:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0004', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:57:59 INFO - mozversion application_buildid: 20170308122623 13:57:59 INFO - mozversion application_changeset: fc5b01fb884a73b3bf8d7f03d30b7b8d498c52e9 13:57:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:57:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:57:59 INFO - mozversion application_name: Firefox 13:57:59 INFO - mozversion application_remotingname: firefox-dev 13:57:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:57:59 INFO - mozversion application_vendor: Mozilla 13:57:59 INFO - mozversion application_version: 54.0a2 13:57:59 INFO - mozversion platform_buildid: 20170308122623 13:57:59 INFO - mozversion platform_changeset: fc5b01fb884a73b3bf8d7f03d30b7b8d498c52e9 13:57:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:57:59 INFO - mozversion platform_version: 54.0a2 13:57:59 INFO - using testdate: 1489010279 13:57:59 INFO - actual date: 1489010279 13:57:59 INFO - starting webserver on 'localhost:52939' 13:57:59 INFO - SUITE-START | Running 2 tests 13:57:59 INFO - TEST-START | dromaeo_css 13:57:59 INFO - operating with platform_type : mac_ 13:57:59 INFO - Initialising browser for dromaeo_css test... 13:57:59 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpvx5EwS/profile http://localhost:52939/getInfo.html) 13:58:02 INFO - PROCESS | 1922 | 2017-03-08 13:58:02.169 plugin-container[1924:16215] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 13:58:02 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:58:02 INFO - PROCESS | 1922 | 2017-03-08 13:58:02.172 plugin-container[1924:16215] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 13:58:02 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:58:02 INFO - PROCESS | 1922 | 2017-03-08 13:58:02.172 plugin-container[1924:16215] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:58:02 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 13:58:02 INFO - PROCESS | 1922 | colorDepth:24 13:58:02 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 13:58:02 INFO - PROCESS | 1922 | __metrics 13:58:02 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 13:58:02 INFO - TEST-INFO | 1922: exit 0 13:58:02 INFO - Browser initialized. 13:58:02 INFO - Running cycle 1/1 for dromaeo_css test... 13:58:02 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmpvx5EwS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:58:05 INFO - PROCESS | 1925 | 2017-03-08 13:58:05.483 plugin-container[1926:16344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 13:58:05 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:58:05 INFO - PROCESS | 1925 | 2017-03-08 13:58:05.483 plugin-container[1926:16344] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 13:58:05 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:58:05 INFO - PROCESS | 1925 | 2017-03-08 13:58:05.483 plugin-container[1926:16344] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:58:15 INFO - PROCESS | 1925 | RSS: Main: 220692480 13:58:15 INFO - PROCESS | 1925 | 14:01:19 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52939/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52939/tests/dromaeo/cssquery-ext.html) 14:01:20 INFO - PROCESS | 1925 | RSS: Main: 238665728 14:01:20 INFO - PROCESS | 1925 | 14:03:03 INFO - PROCESS | 1925 | ************************* 14:03:03 INFO - PROCESS | 1925 | A coding exception was thrown and uncaught in a Task. 14:03:03 INFO - PROCESS | 1925 | 14:03:03 ERROR - PROCESS | 1925 | Full message: TypeError: NetworkError when attempting to fetch resource. 14:03:03 INFO - PROCESS | 1925 | Full stack: 14:03:03 INFO - PROCESS | 1925 | ************************* 14:04:22 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52939/tests/dromaeo/cssquery-ext.html (next: http://localhost:52939/tests/dromaeo/cssquery-jquery.html) 14:04:23 INFO - PROCESS | 1925 | RSS: Main: 249065472 14:04:23 INFO - PROCESS | 1925 | 14:07:25 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52939/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52939/tests/dromaeo/cssquery-mootools.html) 14:07:26 INFO - PROCESS | 1925 | RSS: Main: 256528384 14:07:26 INFO - PROCESS | 1925 | 14:10:28 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52939/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52939/tests/dromaeo/cssquery-prototype.html) 14:10:28 INFO - PROCESS | 1925 | RSS: Main: 252715008 14:10:28 INFO - PROCESS | 1925 | 14:13:31 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52939/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52939/tests/dromaeo/cssquery-yui.html) 14:13:32 INFO - PROCESS | 1925 | RSS: Main: 257794048 14:13:32 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:52939/tests/dromaeo/cssquery-yui.html (next: http://localhost:52939/tests/dromaeo/cssquery-dojo.html) 14:16:35 INFO - PROCESS | 1925 | RSS: Main: 254238720 14:16:35 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | __start_tp_report 14:16:35 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 14:16:35 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 14:16:35 INFO - PROCESS | 1925 | |i|pagename|runs| 14:16:35 INFO - PROCESS | 1925 | |0;dojo.html;3418.641042690517;3424.811635360056;3440.0367896988837;3452.4648679454695;3470.982645086774;6003.729832157553;6012.428819262174;6029.366916473773;6068.9999999999945;6094.573379863411;6035.305939816923;6044.214252147226;6045.939540604592;6099.481544068748;6104.328523862383;5425.104857698485;5450.754716037788;5478.233047373371;5488.643238189525;5550.4449555044575;5526;5545.667259964395;5562.582806289524;5563.944360556403;5596.104623260279;5321.946780532203;5356.517913387795;5532.474414930595;5537.280153580049;5545.77816887324;5402.6758394496455;5405.162199859028;5410.540104091137;5414.6480478769145;5430.5384042356445;5251.527362537352;5377.650452720561;5481.588880833913;5521.254630624854;5547.362053363874;6143.385661433866;6166.722497487579;6185.597936134137;6190.102435146879;6198.535109866733;4766.547178018082;6206.25524937006;6394.072859435356;6417.679116044179;6425.807225783234;6294.433500984934;6304.842378940517;6424.646644434554;6434.935650643481;6459.6447195404235;6180.042093475518;6183.84540386494;6202.162708034358;6208.782692605782;6215.658138802319;6466.224053113657;6467.094606755058;6629.403353698191;6634.86730265392;6648.235452922879;4559.951211221438;6124.724387402578;6207.193064901534;6223.408776166257;6250.062490626351;6410.326915673881;6415.358464153547;6422.582532135396;6435.549511534241;6451.9354806452275;6294.433500984934;6334.5249106317215;6345.397187267202;6353.745850165941;6361.427471527585;6136.825445958231;6180.536459765535;6284;6346.682665866688;6397.360263973658;4969.453360130383;5153.4073581538605;5154.381474223117;5182.404023537341;5209.114450543417;4498.955010449919;4532.6827122101795;4538.205813982606;4547.090581883636;4582.083583283356;6035.486983606443;6105.633661980295;6132.5707200495535;6158.692065396713;6167.475764559973;4948.62885283598;6221.129041934042;6254;6431.389018043278;6501.707423165969;4760.809567617326;5830.658948441882;6142.867137759775;6149.262088549313;6160.630362178284;5147.176451767699;5669.546436285087;5701.315842098892;5701.315842098975;5716.57125715573;6307.274663413767;6358.332375100733;6524.771632992823;6674.599524028574;6690.130283063268;6386.680665966776;6463.25672547663;6489.675516224264;6511.544191906711;6519.511036672364;5533.695646739468;6661.3338666132995;6669.1330127083165;6690.230623478362;6695.598264104169;6225.501959843111;6276.6547839869245;6336.683165841598;6430.421262086435;6439.549231553746;6376.681165941592;6386.584871983307;6429.51778616615;6440.645764482999;6455.483561315177;6361.90457143134;6362.204724409449;6363.90454143179;6365.586236894587;6377.489800816016;6108.038540470994;6278.968605156945;6326.398992095744;6336.746530138743;6350.904736428864;6107.84730381744;6129.297663865929;6182.845428864314;6216.72024758896;6237.5009999200865;6341.27075386318;6348.936510634834;6351.491880649444;6352.777652782131;6359;5896.233489646319;6117.614269788098;6140.631562106288;6241.563090583615;6257.812265632038 14:16:35 INFO - PROCESS | 1925 | |1;ext.html;742.4394582090524;800.5516910530104;822.8683410654296;948.2840455456129;956.3735753081734;38932.416013759816;39721;39763.80118099412;39765.40351894724;39826.60173398269;38159.809200953954;38325.85022449324;38330.65842695506;38408.615913840855;38408.80795596025;7849.9607501962555;7963.840723185533;10416.583336666525;10735.248532602722;10850.403227822466;9687.757806054835;9721.125098741111;9738.220942324633;9744.07431294026;9787;9727.36772109814;9738.513074346254;9784.559694813715;9785.217182625373;9799.265055120859;8156.2659360657235;9753.073458021512;9771.364861283993;9782.9510852446;9810.264230182729;10377.273590848643;10417.79164416715;10434.68695939123;10456.738581535446;10471.371717696962;27906.720932790617;33482;33898.49152262718;34100.82949585236;34246.80136195221;3724.7206459515646;3770.6417890300377;3786.9053273668105;3788.090858194041;3815.408611665169;24844.75776211182;25039;25507.23478295654;26345.20964371072;26449.942002319884;3019.9547006794915;3086.6450358208867;3159.131238909318;3246.480563109915;3270.2314955985325;367687;372879.99999999726;372924;374356;374658;44563.55436445659;44643.10713785706;44661.4368497101;44738.77630611826;44868.3269750954;46116;46183.53816461859;46240.53759462429;46406.535934640895;46468.30297545539;13619;13858.653533661538;13912.51306204278;13946.721065578631;13957.930210348883;11891.762164756656;11979.221350612183;11991.700207494883;11996.280223186637;12006;11513.424328783527;12050.457729402198;12070.913617774442;12106.092043096804;12175.208611440228;5591.993441180626;5700.087985922232;5715.085586306171;5756.251687280626;5773.8267851963665;981.2769564458684;1137.9374134422521;1162.9011534019535;1186.887245711656;1199.4602428907026;20876.895615521826;26707.33231669185;26747.465050698876;26864.731352686613;26898.32754181122;1276.7957126859658;1285.2609749394135;1291.1155858237137;1291.205908366348;1336.5722968649939;13258;13650.726985460235;13724.179258224807;13791.310434478237;13815.930920345334;483679.16320837045;486702;487674.56162718957;489714;490106;47640;49250;51157.48842511527;54963.45036549663;55158.72420637871;1151.3782557419067;1208.3716467436884;1405.3675845869195;1429.8641629045223;1435.224978511613;21007.054682539016;25823.870880646227;26289.474210515302;26480.470390591694;26855;32367.51448728318;38949.02627434337;39387.80306098451;39915.80042099771;39951;485946;486086;488109;488181;488909;28445.288867778472;28610.427791444472;28631.997880074097;28671.71328286774;28701.85649071741;36348.27303453863;36997.075073123386;37644.05889852776;38129.23741525209;39726.80136599414;32461.513077303385;32660.673393265763;32828.015159545255;33033;34033.829830850686;32909.67090329066;32970.67029329676;33720.325593487505;33735.98792036243;33985;32725.509117363734;33246.83376583102;33389.66610333963;33395;33428.331433371684;27051.835853132096;29756.702432975395;29814.85092574523;29830.867758885004;29843;22275.66586501236;22432;23409.244306677407;23509.059637614988;23535 14:16:35 INFO - PROCESS | 1925 | |2;jquery.html;373.9906502337442;402.3301203496181;402.48682929265163;416.2278972506001;441.9547769525074;16206.918965405157;20775.27286544971;20926.790732092675;21456.356309310708;21692.56614867702;21626.567468650617;21667.891660541714;22211.333659990185;22478.55042899141;22696.319110426713;6446.967765161179;6858.108445902026;6864.210615779174;6882;6896;6449.6775161241985;6689.23073846509;6732.697028633699;6756.054152418666;6776.864462710742;6443.677816109176;6724.562903411263;6763.560368576052;6784.694688738994;6796.626185559792;6598.967005164967;6740.258571557125;6740.32596740327;6750.358715922004;6757.797266082025;6303.684815759239;6364.586301890362;6522.543421960465;6534.771283005072;6559.24568674599;26216.60675089875;26902;27056.45887082267;27181.592276115873;27314.180574582795;2891.667458242308;2924.400497897926;2979.06159559738;3034.9393012139853;3049.725524702765;15903.681926361523;16435.01389916597;16499.752503712454;17193;17211.655766884716;1900.7719073711244;1962.9312974045984;2027.807358300959;2031.7155598216261;2071.8342532597358;120959;134109;136594.31702841521;153477;185598;20792.064357103965;20958.476038098863;21216.893915530323;21362.893185533972;21739.45651358697;31813.204669882976;31862.681373186435;32383.35233295368;32452.51321230136;32522.512162317584;10305.793884122275;10442.947785261025;10501.89498105009;10564.841527377095;10713.142948564097;8784.516851573097;8796.780080498038;8803.471791692518;8858.247049000778;8939.329550283624;8161.18388161191;9614.515624456802;9670.226381889432;9771.925088240288;9932.801343973224;4184.895377615584;4269.252880745919;4282.779407868742;4282.8715138545895;4298.46269216348;760.5702777930452;873;882.5102068352127;890.692711014699;922.1976880114344;20484.487887802923;21150;21185.78814211869;21329.933503324773;21341.78658213429;747.3610063395827;914.0904799724184;916.7478943290648;940.0834186667944;945.0030218119812;7629.923700763033;10718.142548596094;10960.945195274133;11053.447327633747;11072;640218;733883.3305833436;754821;772184;775463;133349.3332533331;144163;145866;162764;163431;1123.893230143135;1211.6304527119137;1213.3694073530708;1237.2329155923385;1237.9814302785285;18409.90795046016;18568.721469177704;18695.439136825917;18920.716189257448;19171.712424313366;63557;64571;64995.35004649893;65479.345206547325;65645.67177164083;88941.22117557742;89552;90731;91158.63262050942;91245;11050.944745276222;11430.914063163986;11731.23746956445;11848.881511185122;12021.218620789621;12676.176048556814;13426.664333391744;13486.797698034341;13713.725725485236;13882.09766365183;11618.535258589562;11843.585474508696;11954.924056834927;11976.221545599858;12083.214591051554;11449.969502744792;11465.885341146482;11552.480138393625;11577.24747891418;11670.649880503599;10853.891461085288;11822.467988940347;11983.76032479328;12143;12154.149209555246;11437.142214333791;11608.361540115375;11878.406079696153;12034.277943323428;12063.939680301542;11320.320780753182;11503.252288601214;11553.7111572208;11785.823212651989;11800.940995294968 14:16:35 INFO - PROCESS | 1925 | |3;mootools.html;1439.359485029161;1564.632311406819;1593.466188826743;1603.4708546179759;1607.1401800036979;9441.575129119188;9687.370320929134;10681.412522311279;11351.772964540703;11371.886281137186;10640.78718425631;10950.06924411424;11199.216054876162;11200.215984881062;11292.491837867296;2189.8467107302495;2324.1052194904914;2434.306222726523;2463.5319289335057;2469.6663801547143;2406.915757948472;2415.2029830156007;2437.183543512925;2439.4511234972165;2455.2388759484616;2414.1188466209883;2424.696912885889;2438.17102185257;2439.9878000609974;2443.51129774045;1941.4175747275901;2212.970968499639;2410.2166795791436;2410.3371572817528;2417.190241269168;2372.7389987101405;2375.489269807;2404.290734233405;2423.2730180945646;2429.708434987813;9112.681056163068;11210.327380357123;11213.60752373663;11235.887641123647;11249.437528123643;5780.450857168562;5966.2840459144745;5974.581779275452;5976.312724036748;5979.133025711248;3629.491871138043;3698.7595806272507;3713.832877520518;3773;3794.1842503861526;1207.085282117622;1217.3304682424632;1247.2267194339477;1268.0109514578644;1268.0743057567936;15617.765733514007;15774.921125394414;15784.447544336008;15804.920975395164;15808.920955395264;4934.1118598651865;5008.5993120550265;5024.045431368028;5032.446430892599;5067.695938243717;12918.870811291954;12964.611061668165;12970.092093553549;12979.091463597457;12990;4988.226824842155;5872.0898260769645;5975.79084732041;5982.880342393215;5998.520118390518;4525.162844873643;5178.844634660966;5242.266082748418;5377.892442151135;5378.354597448331;3899.7660140391667;4107.199096176217;4198.874033778991;4285.742855428684;4317.352397140391;1133.5975728616388;1193.3376975778526;1193.462941676249;1207.2816674078908;1209.407390378708;930.7626555228373;939.9248060155171;950.847864341702;957.1194501059042;997.3118548201718;6306.463950564162;6476.773312934025;6493.51298652603;6634;6658.533902626769;949.924006079512;975.4488713876636;1019.8368261078192;1037.4708898461838;1053.0943388685723;4212.220739163204;4297.8495752648505;4311.310190369526;4417.712848664826;4433.889152771142;12509.749805003848;12711.936440317739;12856.228626282265;12867.806982895265;12879.678008049686;12480.937595311965;12504.124711270124;12759.872401276054;12795.232286062866;12843.165194262343;9850.950745246371;9875.308728389087;9916.454594997344;9941.950290248647;9961.850572241423;5213.348331458567;6196.039613859859;6238.75044998195;6252.9374706254175;6295.716692748746;9071.637134514545;11813.40932953366;11894.881051189377;12024.75950481003;12101.939490302491;12053.156279060733;12078;12095.253221099638;12128.151029427856;12177.086718495972;7050.541714788727;7949.205079492004;8822.294216462538;8905.55472226399;9210.861837072584;10138.087572118544;10447.582096716047;10558.419286939292;10563.683089507327;10577.995090466393;9196.448213107235;9208.585613647268;9279.72160835176;9340.299477539347;9367.484788336707;8928.330375221754;9080.500572468314;9122.771930701785;9150.993390726882;9188.908110918805;8010.118886922317;8014.919850801417;8029.4379393441895;8059.153788852086;8078.394120441108;5713.742881570256;5838.766449341979;5876.44173803488;5922.230110085661;6109.450149486567;5940.762369505172;5992.730327135377;6004.699765011645;6051.818445446644;6230.43926046658 14:16:35 INFO - PROCESS | 1925 | |4;prototype.html;367.1903452886384;372.53247174795627;383.23735765826;386.69257939937745;392.47212499188583;5173.301604283421;5272.340957380327;5527.613067085305;5583.4974852263285;5592.036220802971;5185.962807438508;5316.9734151329285;5332.413434522199;5372.785088596452;5397.271368365269;3704.185079282567;3783.3379158647263;4051.4530538377385;4204.642605378546;4456.844010459635;3772.773633581994;4210.915781684364;4450.221211288012;4453.309736990772;4474.724703459515;3559.8252576649775;4322.697411181219;4397.516273209944;4406.361077643755;4423.734575925439;3871.3999330103597;3970.4067818623903;4147.958520414818;4267.594578515036;4443.511213766483;3424.520567120605;3511.7015053720465;3829.0427393151713;4122.979385103085;4142.5857414258635;4059.8985025374304;4347.630451411634;5191.377034755816;5236.895262094775;5245.763940622643;4586.564276393738;4985.152524070917;5126.410462796789;5151.665141765773;5164.948350516484;4718.450479233234;5405.540529055035;5434.83695489136;5636.718164091779;5804.593678442468;3831.9233615327817;4531.048479819242;4544.727316361029;4586.495485496593;4586.977065114686;4969.850904472872;5540.889182216333;5819.447152520504;6064.787732429344;6121.510279177655;3985.5217373915316;4477.485089214782;4608.423947006624;4612.993347893677;4672.392588963413;4077.571854955246;4734.692245004064;4775;4781.354517140211;4787.234042553175;4582.747948862844;4627.30590411441;4628.861134165981;4664;5131.025105230069;5693.943060569424;5713.542916566665;5720.93929747583;5750.514598613005;5760.436605710503;1516.5601975427223;1577.0208388472395;1602.3350309621376;1612.927418266181;1616.4180894877834;1029.763154474475;1094.2832444748544;1123.7694724276903;1204.7168915304896;1222.541546919905;5147.742612869416;5212.817551385683;5223.869403264873;5234.895302093937;5248.868778280497;5037.496250374933;5217.921121892952;5836.336644056423;5884.911726324109;5933.643981361046;4300.430642566662;4311.07311927937;4542.886427839265;4994.475580064113;5009.148444764399;2576.4074262919257;2821.3228825081833;3037.9544306835414;3058.1284333964713;3068.8465576721073;5144.99672563854;5257.658252213594;5898.203742494794;6120.357362476876;6214;5118.8976220476125;5139.948600514022;5448.046591846411;5691.41040826423;5994.602159136381;4679.906401871944;5913.46249975001;6070.332263451017;6274.968625156845;6369.745210191726;5495.499547705865;5634.718264086698;5867.589268751147;5954.702264886825;5977.964873710315;5513.959834860967;5788.594798364074;5840;5884.235049443545;5908.400303866285;5886.205362276038;6003.189569408074;6059.909101363395;6116.042866282747;6122.5408094392205;4168.437260969851;4345.630621397217;5222.712750798591;5226.137687281556;5228.692826793302;5539.279893613804;5637.408072152365;5841.94158058414;5953.940460595339;6060.818140462744;5170.922436163536;5377.408485066561;5413.780350056458;5754.19441278213;5781.219535362672;5248.501392367719;5320.547753441001;5726.169705392612;5726.627769194989;5745;4338.769699543047;4913.9017219656125;5278.630495865253;5367.364267519168;5657.236273103243;4786.540105267787;5107.336046313955;5783.248177737036;5809.804607465961;5890.970545147246;4971.23521150001;5334.973325133349;5429.520645467426;5640.6180485779605;5672.624788250984 14:16:35 INFO - PROCESS | 1925 | |5;yui.html;709.5104377979197;715.7566427414678;761.0980987529774;789.7512283630656;843.814360840615;14397.78403323951;15153.24233788309;15702.921485392586;15760.921195394021;15761.527154185378;14524.636884077878;14585.708285834276;14731.189784561844;14732.042417242872;14742.778858317133;935.6304259817368;963.8795150606175;967.4098799732158;967.9467629280387;969.6363863551169;912.3704643795793;940.1068984464752;944.834653935562;960.3325688646394;962.7448726087592;1012.0537297626706;1014.5434554450527;1016.1616666250336;1016.6695823857239;1017.1608423050975;851.5188918261163;1000.0649392817702;1006.2151521812998;1010.2625083688948;1013.716159475348;750.7635094945075;751.5678484871166;752.6048824367197;753.7361923326791;753.7889390970537;15605.531834044883;15945.521634350987;15949.362025518965;16386.098764567945;16624.00255984645;13652.726945461036;14013.369398377097;14098.859011409857;14100.435982560686;14128.92935535326;11700.482755534811;11877.406129693567;11880.67702387454;11914.285142891455;11992.70018249542;3584.121890136907;3625.782453052825;3642.974547990243;3652.141746689526;3799.6010418905894;113409.43295283553;113442.43278783635;113846.43076784645;118086;119979;9345.439273643604;9919.156871665846;10446.373217606968;10461.633842815465;10483.633072842567;17837;21497.57004859894;21533.78466215349;21867.562648746934;21868.781312186675;6340.936590634127;7446.255374462618;7553.622318884034;7681.347085497683;7794.961025194838;4234.87295381139;4235.639970602533;4314.654827613784;4342.413774140514;4446.088551846864;3194.2493514024186;3232.69289417505;3264.281857991238;3295.2585668224456;3296.35721034396;597.8804239152187;600.5255847880126;600.7657013764635;609.1411110334407;615.5537235504223;514.590023336064;522.339240860312;527.881226723984;532.8294945617442;548.629674969394;9332.39339442934;9579.473128977834;9784.951075244579;9899.950500247596;9954.104130628133;638.141699414287;655.2693746472648;663.5222639699409;671.9496037797087;680.0954730208811;4392.231359512072;4709.152352576569;4716.174669432836;4769.117713223065;4794.304825800239;103432.48283758532;108082.45958770312;108161.45919270354;109159;110357;71685.64157179181;77546.28587999393;91648.0495789287;93467;96518;62878;81049.99999999882;82251;83397;84379;9147.176754092163;9214.953925230599;9220.262379009797;9252.889653241655;9279.443233406017;52104.218436722724;52623.73688131535;52637.47362526326;53722.73138634282;53924.73037634787;64755.67622161859;65661.34338656552;66529.66940661256;67052.99420508598;68247.65876170587;8689.17452841979;9349.48577828226;10345.581367619749;10358.549803027432;10678.145748339959;11938.820917686067;11986.520539178335;11989.700257493632;12051.457684404048;12189.69525761863;11307.717307067389;11454.778902983178;11664.475098620413;11737;12061.939690301493;11239.213255072393;11397.772044559228;11478.483468244116;11544.711382215175;11990.160688751703;11205.60780372683;11668.94165529167;11702.414879255834;12059.879401205875;12072.275663460221;7851.56816375082;9292.256619470561;9511.381760185566;9546.85679714791;10055.949720251352;8994.962342261355;9637.518124093627;9656;9671.758206044906;9744.658936937172 14:16:35 INFO - PROCESS | 1925 | __end_tp_report 14:16:35 INFO - PROCESS | 1925 | __start_cc_report 14:16:35 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,413 14:16:35 INFO - PROCESS | 1925 | __end_cc_report 14:16:35 INFO - PROCESS | 1925 | __startTimestamp1489011395495__endTimestamp 14:16:35 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | ------- Summary: start ------- 14:16:35 INFO - PROCESS | 1925 | Number of tests: 6 14:16:35 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | [#0] dojo.html Cycles:165 Average:5937.26 Median:6183.85 stddev:678.24 (11.0%) stddev-sans-first:650.91 14:16:35 INFO - PROCESS | 1925 | Values: 3418.6 3424.8 3440.0 3452.5 3471.0 6003.7 6012.4 6029.4 6069.0 6094.6 6035.3 6044.2 6045.9 6099.5 6104.3 5425.1 5450.8 5478.2 5488.6 5550.4 5526.0 5545.7 5562.6 5563.9 5596.1 5321.9 5356.5 5532.5 5537.3 5545.8 5402.7 5405.2 5410.5 5414.6 5430.5 5251.5 5377.7 5481.6 5521.3 5547.4 6143.4 6166.7 6185.6 6190.1 6198.5 4766.5 6206.3 6394.1 6417.7 6425.8 6294.4 6304.8 6424.6 6434.9 6459.6 6180.0 6183.8 6202.2 6208.8 6215.7 6466.2 6467.1 6629.4 6634.9 6648.2 4560.0 6124.7 6207.2 6223.4 6250.1 6410.3 6415.4 6422.6 6435.5 6451.9 6294.4 6334.5 6345.4 6353.7 6361.4 6136.8 6180.5 6284.0 6346.7 6397.4 4969.5 5153.4 5154.4 5182.4 5209.1 4499.0 4532.7 4538.2 4547.1 4582.1 6035.5 6105.6 6132.6 6158.7 6167.5 4948.6 6221.1 6254.0 6431.4 6501.7 4760.8 5830.7 6142.9 6149.3 6160.6 5147.2 5669.5 5701.3 5701.3 5716.6 6307.3 6358.3 6524.8 6674.6 6690.1 6386.7 6463.3 6489.7 6511.5 6519.5 5533.7 6661.3 6669.1 6690.2 6695.6 6225.5 6276.7 6336.7 6430.4 6439.5 6376.7 6386.6 6429.5 6440.6 6455.5 6361.9 6362.2 6363.9 6365.6 6377.5 6108.0 6279.0 6326.4 6336.7 6350.9 6107.8 6129.3 6182.8 6216.7 6237.5 6341.3 6348.9 6351.5 6352.8 6359.0 5896.2 6117.6 6140.6 6241.6 6257.8 14:16:35 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | [#1] ext.html Cycles:180 Average:56985.99 Median:33601.16 stddev:120486.68 (358.6%) stddev-sans-first:120750.68 14:16:35 INFO - PROCESS | 1925 | Values: 742.4 800.6 822.9 948.3 956.4 38932.4 39721.0 39763.8 39765.4 39826.6 38159.8 38325.9 38330.7 38408.6 38408.8 7850.0 7963.8 10416.6 10735.2 10850.4 9687.8 9721.1 9738.2 9744.1 9787.0 9727.4 9738.5 9784.6 9785.2 9799.3 8156.3 9753.1 9771.4 9783.0 9810.3 10377.3 10417.8 10434.7 10456.7 10471.4 27906.7 33482.0 33898.5 34100.8 34246.8 3724.7 3770.6 3786.9 3788.1 3815.4 24844.8 25039.0 25507.2 26345.2 26449.9 3020.0 3086.6 3159.1 3246.5 3270.2 367687.0 372880.0 372924.0 374356.0 374658.0 44563.6 44643.1 44661.4 44738.8 44868.3 46116.0 46183.5 46240.5 46406.5 46468.3 13619.0 13858.7 13912.5 13946.7 13957.9 11891.8 11979.2 11991.7 11996.3 12006.0 11513.4 12050.5 12070.9 12106.1 12175.2 5592.0 5700.1 5715.1 5756.3 5773.8 981.3 1137.9 1162.9 1186.9 1199.5 20876.9 26707.3 26747.5 26864.7 26898.3 1276.8 1285.3 1291.1 1291.2 1336.6 13258.0 13650.7 13724.2 13791.3 13815.9 483679.2 486702.0 487674.6 489714.0 490106.0 47640.0 49250.0 51157.5 54963.5 55158.7 1151.4 1208.4 1405.4 1429.9 1435.2 21007.1 25823.9 26289.5 26480.5 26855.0 32367.5 38949.0 39387.8 39915.8 39951.0 485946.0 486086.0 488109.0 488181.0 488909.0 28445.3 28610.4 28632.0 28671.7 28701.9 36348.3 36997.1 37644.1 38129.2 39726.8 32461.5 32660.7 32828.0 33033.0 34033.8 32909.7 32970.7 33720.3 33736.0 33985.0 32725.5 33246.8 33389.7 33395.0 33428.3 27051.8 29756.7 29814.9 29830.9 29843.0 22275.7 22432.0 23409.2 23509.1 23535.0 14:16:35 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | [#2] jquery.html Cycles:180 Average:42623.64 Median:21975.40 stddev:122864.75 (559.1%) stddev-sans-first:123168.47 14:16:35 INFO - PROCESS | 1925 | Values: 374.0 402.3 402.5 416.2 442.0 16206.9 20775.3 20926.8 21456.4 21692.6 21626.6 21667.9 22211.3 22478.6 22696.3 6447.0 6858.1 6864.2 6882.0 6896.0 6449.7 6689.2 6732.7 6756.1 6776.9 6443.7 6724.6 6763.6 6784.7 6796.6 6599.0 6740.3 6740.3 6750.4 6757.8 6303.7 6364.6 6522.5 6534.8 6559.2 26216.6 26902.0 27056.5 27181.6 27314.2 2891.7 2924.4 2979.1 3034.9 3049.7 15903.7 16435.0 16499.8 17193.0 17211.7 1900.8 1962.9 2027.8 2031.7 2071.8 120959.0 134109.0 136594.3 153477.0 185598.0 20792.1 20958.5 21216.9 21362.9 21739.5 31813.2 31862.7 32383.4 32452.5 32522.5 10305.8 10442.9 10501.9 10564.8 10713.1 8784.5 8796.8 8803.5 8858.2 8939.3 8161.2 9614.5 9670.2 9771.9 9932.8 4184.9 4269.3 4282.8 4282.9 4298.5 760.6 873.0 882.5 890.7 922.2 20484.5 21150.0 21185.8 21329.9 21341.8 747.4 914.1 916.7 940.1 945.0 7629.9 10718.1 10960.9 11053.4 11072.0 640218.0 733883.3 754821.0 772184.0 775463.0 133349.3 144163.0 145866.0 162764.0 163431.0 1123.9 1211.6 1213.4 1237.2 1238.0 18409.9 18568.7 18695.4 18920.7 19171.7 63557.0 64571.0 64995.4 65479.3 65645.7 88941.2 89552.0 90731.0 91158.6 91245.0 11050.9 11430.9 11731.2 11848.9 12021.2 12676.2 13426.7 13486.8 13713.7 13882.1 11618.5 11843.6 11954.9 11976.2 12083.2 11450.0 11465.9 11552.5 11577.2 11670.6 10853.9 11822.5 11983.8 12143.0 12154.1 11437.1 11608.4 11878.4 12034.3 12063.9 11320.3 11503.3 11553.7 11785.8 11800.9 14:16:35 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | [#3] mootools.html Cycles:180 Average:6711.59 Median:3706.30 stddev:4145.10 (111.8%) stddev-sans-first:4137.80 14:16:35 INFO - PROCESS | 1925 | Values: 1439.4 1564.6 1593.5 1603.5 1607.1 9441.6 9687.4 10681.4 11351.8 11371.9 10640.8 10950.1 11199.2 11200.2 11292.5 2189.8 2324.1 2434.3 2463.5 2469.7 2406.9 2415.2 2437.2 2439.5 2455.2 2414.1 2424.7 2438.2 2440.0 2443.5 1941.4 2213.0 2410.2 2410.3 2417.2 2372.7 2375.5 2404.3 2423.3 2429.7 9112.7 11210.3 11213.6 11235.9 11249.4 5780.5 5966.3 5974.6 5976.3 5979.1 3629.5 3698.8 3713.8 3773.0 3794.2 1207.1 1217.3 1247.2 1268.0 1268.1 15617.8 15774.9 15784.4 15804.9 15808.9 4934.1 5008.6 5024.0 5032.4 5067.7 12918.9 12964.6 12970.1 12979.1 12990.0 4988.2 5872.1 5975.8 5982.9 5998.5 4525.2 5178.8 5242.3 5377.9 5378.4 3899.8 4107.2 4198.9 4285.7 4317.4 1133.6 1193.3 1193.5 1207.3 1209.4 930.8 939.9 950.8 957.1 997.3 6306.5 6476.8 6493.5 6634.0 6658.5 949.9 975.4 1019.8 1037.5 1053.1 4212.2 4297.8 4311.3 4417.7 4433.9 12509.7 12711.9 12856.2 12867.8 12879.7 12480.9 12504.1 12759.9 12795.2 12843.2 9851.0 9875.3 9916.5 9942.0 9961.9 5213.3 6196.0 6238.8 6252.9 6295.7 9071.6 11813.4 11894.9 12024.8 12101.9 12053.2 12078.0 12095.3 12128.2 12177.1 7050.5 7949.2 8822.3 8905.6 9210.9 10138.1 10447.6 10558.4 10563.7 10578.0 9196.4 9208.6 9279.7 9340.3 9367.5 8928.3 9080.5 9122.8 9151.0 9188.9 8010.1 8014.9 8029.4 8059.2 8078.4 5713.7 5838.8 5876.4 5922.2 6109.5 5940.8 5992.7 6004.7 6051.8 6230.4 14:16:35 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | [#4] prototype.html Cycles:180 Average:4720.57 Median:5158.31 stddev:1349.51 (26.2%) stddev-sans-first:1313.14 14:16:35 INFO - PROCESS | 1925 | Values: 367.2 372.5 383.2 386.7 392.5 5173.3 5272.3 5527.6 5583.5 5592.0 5186.0 5317.0 5332.4 5372.8 5397.3 3704.2 3783.3 4051.5 4204.6 4456.8 3772.8 4210.9 4450.2 4453.3 4474.7 3559.8 4322.7 4397.5 4406.4 4423.7 3871.4 3970.4 4148.0 4267.6 4443.5 3424.5 3511.7 3829.0 4123.0 4142.6 4059.9 4347.6 5191.4 5236.9 5245.8 4586.6 4985.2 5126.4 5151.7 5164.9 4718.5 5405.5 5434.8 5636.7 5804.6 3831.9 4531.0 4544.7 4586.5 4587.0 4969.9 5540.9 5819.4 6064.8 6121.5 3985.5 4477.5 4608.4 4613.0 4672.4 4077.6 4734.7 4775.0 4781.4 4787.2 4582.7 4627.3 4628.9 4664.0 5131.0 5693.9 5713.5 5720.9 5750.5 5760.4 1516.6 1577.0 1602.3 1612.9 1616.4 1029.8 1094.3 1123.8 1204.7 1222.5 5147.7 5212.8 5223.9 5234.9 5248.9 5037.5 5217.9 5836.3 5884.9 5933.6 4300.4 4311.1 4542.9 4994.5 5009.1 2576.4 2821.3 3038.0 3058.1 3068.8 5145.0 5257.7 5898.2 6120.4 6214.0 5118.9 5139.9 5448.0 5691.4 5994.6 4679.9 5913.5 6070.3 6275.0 6369.7 5495.5 5634.7 5867.6 5954.7 5978.0 5514.0 5788.6 5840.0 5884.2 5908.4 5886.2 6003.2 6059.9 6116.0 6122.5 4168.4 4345.6 5222.7 5226.1 5228.7 5539.3 5637.4 5841.9 5953.9 6060.8 5170.9 5377.4 5413.8 5754.2 5781.2 5248.5 5320.5 5726.2 5726.6 5745.0 4338.8 4913.9 5278.6 5367.4 5657.2 4786.5 5107.3 5783.2 5809.8 5891.0 4971.2 5335.0 5429.5 5640.6 5672.6 14:16:35 INFO - PROCESS | 1925 | 14:16:35 INFO - PROCESS | 1925 | [#5] yui.html Cycles:180 Average:20446.97 Median:4419.16 stddev:30695.76 (694.6%) stddev-sans-first:30746.09 14:16:35 INFO - PROCESS | 1925 | Values: 709.5 715.8 761.1 789.8 843.8 14397.8 15153.2 15702.9 15760.9 15761.5 14524.6 14585.7 14731.2 14732.0 14742.8 935.6 963.9 967.4 967.9 969.6 912.4 940.1 944.8 960.3 962.7 1012.1 1014.5 1016.2 1016.7 1017.2 851.5 1000.1 1006.2 1010.3 1013.7 750.8 751.6 752.6 753.7 753.8 15605.5 15945.5 15949.4 16386.1 16624.0 13652.7 14013.4 14098.9 14100.4 14128.9 11700.5 11877.4 11880.7 11914.3 11992.7 3584.1 3625.8 3643.0 3652.1 3799.6 113409.4 113442.4 113846.4 118086.0 119979.0 9345.4 9919.2 10446.4 10461.6 10483.6 17837.0 21497.6 21533.8 21867.6 21868.8 6340.9 7446.3 7553.6 7681.3 7795.0 4234.9 4235.6 4314.7 4342.4 4446.1 3194.2 3232.7 3264.3 3295.3 3296.4 597.9 600.5 600.8 609.1 615.6 514.6 522.3 527.9 532.8 548.6 9332.4 9579.5 9785.0 9900.0 9954.1 638.1 655.3 663.5 671.9 680.1 4392.2 4709.2 4716.2 4769.1 4794.3 103432.5 108082.5 108161.5 109159.0 110357.0 71685.6 77546.3 91648.0 93467.0 96518.0 62878.0 81050.0 82251.0 83397.0 84379.0 9147.2 9215.0 9220.3 9252.9 9279.4 52104.2 52623.7 52637.5 53722.7 53924.7 64755.7 65661.3 66529.7 67053.0 68247.7 8689.2 9349.5 10345.6 10358.5 10678.1 11938.8 11986.5 11989.7 12051.5 12189.7 11307.7 11454.8 11664.5 11737.0 12061.9 11239.2 11397.8 11478.5 11544.7 11990.2 11205.6 11668.9 11702.4 12059.9 12072.3 7851.6 9292.3 9511.4 9546.9 10055.9 8995.0 9637.5 9656.0 9671.8 9744.7 14:16:35 INFO - PROCESS | 1925 | -------- Summary: end -------- 14:16:35 INFO - PROCESS | 1925 | 14:16:36 INFO - TEST-INFO | 1925: exit 0 14:16:36 INFO - TEST-OK | dromaeo_css | took 1116803ms 14:16:36 INFO - TEST-START | kraken 14:16:36 INFO - operating with platform_type : mac_ 14:16:36 INFO - Initialising browser for kraken test... 14:16:36 INFO - TEST-INFO | started process 1931 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmp8RQrLW/profile http://localhost:52939/getInfo.html) 14:16:38 INFO - PROCESS | 1931 | 2017-03-08 14:16:38.882 plugin-container[1932:18470] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3b, name = 'com.apple.tsm.portname' 14:16:38 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:16:38 INFO - PROCESS | 1931 | 2017-03-08 14:16:38.885 plugin-container[1932:18470] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 14:16:38 INFO - PROCESS | 1931 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:16:38 INFO - PROCESS | 1931 | 2017-03-08 14:16:38.885 plugin-container[1932:18470] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:16:38 INFO - PROCESS | 1931 | __metrics Screen width/height:1600/1200 14:16:38 INFO - PROCESS | 1931 | colorDepth:24 14:16:38 INFO - PROCESS | 1931 | Browser inner width/height: 1024/689 14:16:38 INFO - PROCESS | 1931 | __metrics 14:16:39 ERROR - PROCESS | 1931 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 14:16:39 INFO - TEST-INFO | 1931: exit 0 14:16:39 INFO - Browser initialized. 14:16:39 INFO - Running cycle 1/1 for kraken test... 14:16:39 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/tmp8RQrLW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:16:42 INFO - PROCESS | 1933 | 2017-03-08 14:16:42.210 plugin-container[1934:18604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8833, name = 'com.apple.tsm.portname' 14:16:42 INFO - PROCESS | 1933 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:16:42 INFO - PROCESS | 1933 | 2017-03-08 14:16:42.211 plugin-container[1934:18604] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 14:16:42 INFO - PROCESS | 1933 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:16:42 INFO - PROCESS | 1933 | 2017-03-08 14:16:42.211 plugin-container[1934:18604] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:16:51 INFO - PROCESS | 1933 | RSS: Main: 220561408 14:16:51 INFO - PROCESS | 1933 | 14:16:55 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52939/tests/kraken/driver.html?testName=audio-beat-detection) 14:16:56 INFO - PROCESS | 1933 | RSS: Main: 235339776 14:16:56 INFO - PROCESS | 1933 | 14:16:58 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52939/tests/kraken/driver.html?testName=audio-dft) 14:16:59 INFO - PROCESS | 1933 | RSS: Main: 243052544 14:16:59 INFO - PROCESS | 1933 | 14:17:02 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52939/tests/kraken/driver.html?testName=audio-fft) 14:17:03 INFO - PROCESS | 1933 | RSS: Main: 246718464 14:17:03 INFO - PROCESS | 1933 | 14:17:05 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52939/tests/kraken/driver.html?testName=audio-oscillator) 14:17:05 INFO - PROCESS | 1933 | RSS: Main: 247132160 14:17:05 INFO - PROCESS | 1933 | 14:17:07 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52939/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:17:08 INFO - PROCESS | 1933 | RSS: Main: 248516608 14:17:08 INFO - PROCESS | 1933 | 14:17:11 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52939/tests/kraken/driver.html?testName=imaging-darkroom) 14:17:12 INFO - PROCESS | 1933 | RSS: Main: 252948480 14:17:12 INFO - PROCESS | 1933 | 14:17:15 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52939/tests/kraken/driver.html?testName=imaging-desaturate) 14:17:16 INFO - PROCESS | 1933 | RSS: Main: 249794560 14:17:16 INFO - PROCESS | 1933 | 14:17:19 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52939/tests/kraken/driver.html?testName=json-parse-financial) 14:17:20 INFO - PROCESS | 1933 | RSS: Main: 250851328 14:17:20 INFO - PROCESS | 1933 | 14:17:21 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52939/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:17:22 INFO - PROCESS | 1933 | RSS: Main: 250830848 14:17:22 INFO - PROCESS | 1933 | 14:17:24 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52939/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:17:25 INFO - PROCESS | 1933 | RSS: Main: 250851328 14:17:25 INFO - PROCESS | 1933 | 14:17:27 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52939/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:17:27 INFO - PROCESS | 1933 | RSS: Main: 250920960 14:17:27 INFO - PROCESS | 1933 | 14:17:31 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52939/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:17:31 INFO - PROCESS | 1933 | RSS: Main: 251326464 14:17:31 INFO - PROCESS | 1933 | 14:17:34 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52939/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:17:35 INFO - PROCESS | 1933 | RSS: Main: 250941440 14:17:35 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:52939/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52939/tests/kraken/driver.html?testName=ai-astar) 14:17:37 INFO - PROCESS | 1933 | RSS: Main: 254492672 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | __start_tp_report 14:17:37 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 14:17:37 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 14:17:37 INFO - PROCESS | 1933 | |i|pagename|runs| 14:17:37 INFO - PROCESS | 1933 | |0;ai-astar;110;122;121;120;120;121;119;121;123;118 14:17:37 INFO - PROCESS | 1933 | |1;audio-beat-detection;145;115;112;148;113;114;112;112;134;125 14:17:37 INFO - PROCESS | 1933 | |2;audio-dft;208;201;200;226;195;199;196;198;203;200 14:17:37 INFO - PROCESS | 1933 | |3;audio-fft;84;60;63;83;64;63;61;59;74;61 14:17:37 INFO - PROCESS | 1933 | |4;audio-oscillator;92;70;86;71;72;70;81;70;70;82 14:17:37 INFO - PROCESS | 1933 | |5;imaging-gaussian-blur;92;90;91;113;90;89;89;90;92;89 14:17:37 INFO - PROCESS | 1933 | |6;imaging-darkroom;96;106;98;149;127;94;124;154;107;141 14:17:37 INFO - PROCESS | 1933 | |7;imaging-desaturate;91;126;144;100;90;93;91;90;92;90 14:17:37 INFO - PROCESS | 1933 | |8;json-parse-financial;67;65;66;69;70;69;68;68;68;70 14:17:37 INFO - PROCESS | 1933 | |9;json-stringify-tinderbox;62;63;65;74;64;59;79;93;88;59 14:17:37 INFO - PROCESS | 1933 | |10;stanford-crypto-aes;87;99;105;87;84;72;105;102;82;88 14:17:37 INFO - PROCESS | 1933 | |11;stanford-crypto-ccm;146;156;156;153;154;153;147;187;212;169 14:17:37 INFO - PROCESS | 1933 | |12;stanford-crypto-pbkdf2;229;176;157;161;210;215;225;174;169;149 14:17:37 INFO - PROCESS | 1933 | |13;stanford-crypto-sha256-iterative;78;86;86;77;67;68;66;67;64;84 14:17:37 INFO - PROCESS | 1933 | __end_tp_report 14:17:37 INFO - PROCESS | 1933 | __start_cc_report 14:17:37 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,1013 14:17:37 INFO - PROCESS | 1933 | __end_cc_report 14:17:37 INFO - PROCESS | 1933 | __startTimestamp1489011457582__endTimestamp 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | ------- Summary: start ------- 14:17:37 INFO - PROCESS | 1933 | Number of tests: 14 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#0] ai-astar Cycles:10 Average:119.50 Median:121.00 stddev:3.63 (3.0%) stddev-sans-first:1.51 14:17:37 INFO - PROCESS | 1933 | Values: 110.0 122.0 121.0 120.0 120.0 121.0 119.0 121.0 123.0 118.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:120.00 stddev:14.31 (11.9%) stddev-sans-first:12.77 14:17:37 INFO - PROCESS | 1933 | Values: 145.0 115.0 112.0 148.0 113.0 114.0 112.0 112.0 134.0 125.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#2] audio-dft Cycles:10 Average:202.60 Median:200.50 stddev:9.00 (4.5%) stddev-sans-first:9.33 14:17:37 INFO - PROCESS | 1933 | Values: 208.0 201.0 200.0 226.0 195.0 199.0 196.0 198.0 203.0 200.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#3] audio-fft Cycles:10 Average:67.20 Median:63.50 stddev:9.54 (15.0%) stddev-sans-first:7.95 14:17:37 INFO - PROCESS | 1933 | Values: 84.0 60.0 63.0 83.0 64.0 63.0 61.0 59.0 74.0 61.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:76.50 stddev:8.17 (10.7%) stddev-sans-first:6.42 14:17:37 INFO - PROCESS | 1933 | Values: 92.0 70.0 86.0 71.0 72.0 70.0 81.0 70.0 70.0 82.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#5] imaging-gaussian-blur Cycles:10 Average:92.50 Median:90.00 stddev:7.29 (8.1%) stddev-sans-first:7.73 14:17:37 INFO - PROCESS | 1933 | Values: 92.0 90.0 91.0 113.0 90.0 89.0 89.0 90.0 92.0 89.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#6] imaging-darkroom Cycles:10 Average:119.60 Median:151.50 stddev:22.61 (14.9%) stddev-sans-first:22.31 14:17:37 INFO - PROCESS | 1933 | Values: 96.0 106.0 98.0 149.0 127.0 94.0 124.0 154.0 107.0 141.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#7] imaging-desaturate Cycles:10 Average:100.70 Median:90.50 stddev:18.80 (20.8%) stddev-sans-first:19.61 14:17:37 INFO - PROCESS | 1933 | Values: 91.0 126.0 144.0 100.0 90.0 93.0 91.0 90.0 92.0 90.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#8] json-parse-financial Cycles:10 Average:68.00 Median:68.50 stddev:1.63 (2.4%) stddev-sans-first:1.69 14:17:37 INFO - PROCESS | 1933 | Values: 67.0 65.0 66.0 69.0 70.0 69.0 68.0 68.0 68.0 70.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:69.50 stddev:12.30 (17.7%) stddev-sans-first:12.65 14:17:37 INFO - PROCESS | 1933 | Values: 62.0 63.0 65.0 74.0 64.0 59.0 79.0 93.0 88.0 59.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:85.50 stddev:11.10 (13.0%) stddev-sans-first:11.67 14:17:37 INFO - PROCESS | 1933 | Values: 87.0 99.0 105.0 87.0 84.0 72.0 105.0 102.0 82.0 88.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#11] stanford-crypto-ccm Cycles:10 Average:163.30 Median:156.00 stddev:20.91 (13.4%) stddev-sans-first:21.22 14:17:37 INFO - PROCESS | 1933 | Values: 146.0 156.0 156.0 153.0 154.0 153.0 147.0 187.0 212.0 169.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:193.00 stddev:30.10 (15.6%) stddev-sans-first:27.72 14:17:37 INFO - PROCESS | 1933 | Values: 229.0 176.0 157.0 161.0 210.0 215.0 225.0 174.0 169.0 149.0 14:17:37 INFO - PROCESS | 1933 | 14:17:37 INFO - PROCESS | 1933 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.30 Median:77.50 stddev:8.88 (11.5%) stddev-sans-first:9.32 14:17:37 INFO - PROCESS | 1933 | Values: 78.0 86.0 86.0 77.0 67.0 68.0 66.0 67.0 64.0 84.0 14:17:37 INFO - PROCESS | 1933 | -------- Summary: end -------- 14:17:37 INFO - PROCESS | 1933 | 14:17:38 INFO - TEST-INFO | 1933: exit 0 14:17:38 INFO - TEST-OK | kraken | took 62318ms 14:17:38 INFO - SUITE-END | took 1179s 14:17:38 INFO - Completed test suite (00:19:39) 14:17:38 INFO - javascript benchmark 14:17:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7661.722307065029, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3418.641042690517, 3424.811635360056, 3440.0367896988837, 3452.4648679454695, 3470.982645086774, 6003.729832157553, 6012.428819262174, 6029.366916473773, 6068.9999999999945, 6094.573379863411, 6035.305939816923, 6044.214252147226, 6045.939540604592, 6099.481544068748, 6104.328523862383, 5425.104857698485, 5450.754716037788, 5478.233047373371, 5488.643238189525, 5550.4449555044575, 5526.0, 5545.667259964395, 5562.582806289524, 5563.944360556403, 5596.104623260279, 5321.946780532203, 5356.517913387795, 5532.474414930595, 5537.280153580049, 5545.77816887324, 5402.6758394496455, 5405.162199859028, 5410.540104091137, 5414.6480478769145, 5430.5384042356445, 5251.527362537352, 5377.650452720561, 5481.588880833913, 5521.254630624854, 5547.362053363874, 6143.385661433866, 6166.722497487579, 6185.597936134137, 6190.102435146879, 6198.535109866733, 4766.547178018082, 6206.25524937006, 6394.072859435356, 6417.679116044179, 6425.807225783234, 6294.433500984934, 6304.842378940517, 6424.646644434554, 6434.935650643481, 6459.6447195404235, 6180.042093475518, 6183.84540386494, 6202.162708034358, 6208.782692605782, 6215.658138802319, 6466.224053113657, 6467.094606755058, 6629.403353698191, 6634.86730265392, 6648.235452922879, 4559.951211221438, 6124.724387402578, 6207.193064901534, 6223.408776166257, 6250.062490626351, 6410.326915673881, 6415.358464153547, 6422.582532135396, 6435.549511534241, 6451.9354806452275, 6294.433500984934, 6334.5249106317215, 6345.397187267202, 6353.745850165941, 6361.427471527585, 6136.825445958231, 6180.536459765535, 6284.0, 6346.682665866688, 6397.360263973658, 4969.453360130383, 5153.4073581538605, 5154.381474223117, 5182.404023537341, 5209.114450543417, 4498.955010449919, 4532.6827122101795, 4538.205813982606, 4547.090581883636, 4582.083583283356, 6035.486983606443, 6105.633661980295, 6132.5707200495535, 6158.692065396713, 6167.475764559973, 4948.62885283598, 6221.129041934042, 6254.0, 6431.389018043278, 6501.707423165969, 4760.809567617326, 5830.658948441882, 6142.867137759775, 6149.262088549313, 6160.630362178284, 5147.176451767699, 5669.546436285087, 5701.315842098892, 5701.315842098975, 5716.57125715573, 6307.274663413767, 6358.332375100733, 6524.771632992823, 6674.599524028574, 6690.130283063268, 6386.680665966776, 6463.25672547663, 6489.675516224264, 6511.544191906711, 6519.511036672364, 5533.695646739468, 6661.3338666132995, 6669.1330127083165, 6690.230623478362, 6695.598264104169, 6225.501959843111, 6276.6547839869245, 6336.683165841598, 6430.421262086435, 6439.549231553746, 6376.681165941592, 6386.584871983307, 6429.51778616615, 6440.645764482999, 6455.483561315177, 6361.90457143134, 6362.204724409449, 6363.90454143179, 6365.586236894587, 6377.489800816016, 6108.038540470994, 6278.968605156945, 6326.398992095744, 6336.746530138743, 6350.904736428864, 6107.84730381744, 6129.297663865929, 6182.845428864314, 6216.72024758896, 6237.5009999200865, 6341.27075386318, 6348.936510634834, 6351.491880649444, 6352.777652782131, 6359.0, 5896.233489646319, 6117.614269788098, 6140.631562106288, 6241.563090583615, 6257.812265632038], "value": 5896.115488571813, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.4394582090524, 800.5516910530104, 822.8683410654296, 948.2840455456129, 956.3735753081734, 38932.416013759816, 39721.0, 39763.80118099412, 39765.40351894724, 39826.60173398269, 38159.809200953954, 38325.85022449324, 38330.65842695506, 38408.615913840855, 38408.80795596025, 7849.9607501962555, 7963.840723185533, 10416.583336666525, 10735.248532602722, 10850.403227822466, 9687.757806054835, 9721.125098741111, 9738.220942324633, 9744.07431294026, 9787.0, 9727.36772109814, 9738.513074346254, 9784.559694813715, 9785.217182625373, 9799.265055120859, 8156.2659360657235, 9753.073458021512, 9771.364861283993, 9782.9510852446, 9810.264230182729, 10377.273590848643, 10417.79164416715, 10434.68695939123, 10456.738581535446, 10471.371717696962, 27906.720932790617, 33482.0, 33898.49152262718, 34100.82949585236, 34246.80136195221, 3724.7206459515646, 3770.6417890300377, 3786.9053273668105, 3788.090858194041, 3815.408611665169, 24844.75776211182, 25039.0, 25507.23478295654, 26345.20964371072, 26449.942002319884, 3019.9547006794915, 3086.6450358208867, 3159.131238909318, 3246.480563109915, 3270.2314955985325, 367687.0, 372879.99999999726, 372924.0, 374356.0, 374658.0, 44563.55436445659, 44643.10713785706, 44661.4368497101, 44738.77630611826, 44868.3269750954, 46116.0, 46183.53816461859, 46240.53759462429, 46406.535934640895, 46468.30297545539, 13619.0, 13858.653533661538, 13912.51306204278, 13946.721065578631, 13957.930210348883, 11891.762164756656, 11979.221350612183, 11991.700207494883, 11996.280223186637, 12006.0, 11513.424328783527, 12050.457729402198, 12070.913617774442, 12106.092043096804, 12175.208611440228, 5591.993441180626, 5700.087985922232, 5715.085586306171, 5756.251687280626, 5773.8267851963665, 981.2769564458684, 1137.9374134422521, 1162.9011534019535, 1186.887245711656, 1199.4602428907026, 20876.895615521826, 26707.33231669185, 26747.465050698876, 26864.731352686613, 26898.32754181122, 1276.7957126859658, 1285.2609749394135, 1291.1155858237137, 1291.205908366348, 1336.5722968649939, 13258.0, 13650.726985460235, 13724.179258224807, 13791.310434478237, 13815.930920345334, 483679.16320837045, 486702.0, 487674.56162718957, 489714.0, 490106.0, 47640.0, 49250.0, 51157.48842511527, 54963.45036549663, 55158.72420637871, 1151.3782557419067, 1208.3716467436884, 1405.3675845869195, 1429.8641629045223, 1435.224978511613, 21007.054682539016, 25823.870880646227, 26289.474210515302, 26480.470390591694, 26855.0, 32367.51448728318, 38949.02627434337, 39387.80306098451, 39915.80042099771, 39951.0, 485946.0, 486086.0, 488109.0, 488181.0, 488909.0, 28445.288867778472, 28610.427791444472, 28631.997880074097, 28671.71328286774, 28701.85649071741, 36348.27303453863, 36997.075073123386, 37644.05889852776, 38129.23741525209, 39726.80136599414, 32461.513077303385, 32660.673393265763, 32828.015159545255, 33033.0, 34033.829830850686, 32909.67090329066, 32970.67029329676, 33720.325593487505, 33735.98792036243, 33985.0, 32725.509117363734, 33246.83376583102, 33389.66610333963, 33395.0, 33428.331433371684, 27051.835853132096, 29756.702432975395, 29814.85092574523, 29830.867758885004, 29843.0, 22275.66586501236, 22432.0, 23409.244306677407, 23509.059637614988, 23535.0], "value": 18176.458639812998, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.9906502337442, 402.3301203496181, 402.48682929265163, 416.2278972506001, 441.9547769525074, 16206.918965405157, 20775.27286544971, 20926.790732092675, 21456.356309310708, 21692.56614867702, 21626.567468650617, 21667.891660541714, 22211.333659990185, 22478.55042899141, 22696.319110426713, 6446.967765161179, 6858.108445902026, 6864.210615779174, 6882.0, 6896.0, 6449.6775161241985, 6689.23073846509, 6732.697028633699, 6756.054152418666, 6776.864462710742, 6443.677816109176, 6724.562903411263, 6763.560368576052, 6784.694688738994, 6796.626185559792, 6598.967005164967, 6740.258571557125, 6740.32596740327, 6750.358715922004, 6757.797266082025, 6303.684815759239, 6364.586301890362, 6522.543421960465, 6534.771283005072, 6559.24568674599, 26216.60675089875, 26902.0, 27056.45887082267, 27181.592276115873, 27314.180574582795, 2891.667458242308, 2924.400497897926, 2979.06159559738, 3034.9393012139853, 3049.725524702765, 15903.681926361523, 16435.01389916597, 16499.752503712454, 17193.0, 17211.655766884716, 1900.7719073711244, 1962.9312974045984, 2027.807358300959, 2031.7155598216261, 2071.8342532597358, 120959.0, 134109.0, 136594.31702841521, 153477.0, 185598.0, 20792.064357103965, 20958.476038098863, 21216.893915530323, 21362.893185533972, 21739.45651358697, 31813.204669882976, 31862.681373186435, 32383.35233295368, 32452.51321230136, 32522.512162317584, 10305.793884122275, 10442.947785261025, 10501.89498105009, 10564.841527377095, 10713.142948564097, 8784.516851573097, 8796.780080498038, 8803.471791692518, 8858.247049000778, 8939.329550283624, 8161.18388161191, 9614.515624456802, 9670.226381889432, 9771.925088240288, 9932.801343973224, 4184.895377615584, 4269.252880745919, 4282.779407868742, 4282.8715138545895, 4298.46269216348, 760.5702777930452, 873.0, 882.5102068352127, 890.692711014699, 922.1976880114344, 20484.487887802923, 21150.0, 21185.78814211869, 21329.933503324773, 21341.78658213429, 747.3610063395827, 914.0904799724184, 916.7478943290648, 940.0834186667944, 945.0030218119812, 7629.923700763033, 10718.142548596094, 10960.945195274133, 11053.447327633747, 11072.0, 640218.0, 733883.3305833436, 754821.0, 772184.0, 775463.0, 133349.3332533331, 144163.0, 145866.0, 162764.0, 163431.0, 1123.893230143135, 1211.6304527119137, 1213.3694073530708, 1237.2329155923385, 1237.9814302785285, 18409.90795046016, 18568.721469177704, 18695.439136825917, 18920.716189257448, 19171.712424313366, 63557.0, 64571.0, 64995.35004649893, 65479.345206547325, 65645.67177164083, 88941.22117557742, 89552.0, 90731.0, 91158.63262050942, 91245.0, 11050.944745276222, 11430.914063163986, 11731.23746956445, 11848.881511185122, 12021.218620789621, 12676.176048556814, 13426.664333391744, 13486.797698034341, 13713.725725485236, 13882.09766365183, 11618.535258589562, 11843.585474508696, 11954.924056834927, 11976.221545599858, 12083.214591051554, 11449.969502744792, 11465.885341146482, 11552.480138393625, 11577.24747891418, 11670.649880503599, 10853.891461085288, 11822.467988940347, 11983.76032479328, 12143.0, 12154.149209555246, 11437.142214333791, 11608.361540115375, 11878.406079696153, 12034.277943323428, 12063.939680301542, 11320.320780753182, 11503.252288601214, 11553.7111572208, 11785.823212651989, 11800.940995294968], "value": 11591.028138202615, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1439.359485029161, 1564.632311406819, 1593.466188826743, 1603.4708546179759, 1607.1401800036979, 9441.575129119188, 9687.370320929134, 10681.412522311279, 11351.772964540703, 11371.886281137186, 10640.78718425631, 10950.06924411424, 11199.216054876162, 11200.215984881062, 11292.491837867296, 2189.8467107302495, 2324.1052194904914, 2434.306222726523, 2463.5319289335057, 2469.6663801547143, 2406.915757948472, 2415.2029830156007, 2437.183543512925, 2439.4511234972165, 2455.2388759484616, 2414.1188466209883, 2424.696912885889, 2438.17102185257, 2439.9878000609974, 2443.51129774045, 1941.4175747275901, 2212.970968499639, 2410.2166795791436, 2410.3371572817528, 2417.190241269168, 2372.7389987101405, 2375.489269807, 2404.290734233405, 2423.2730180945646, 2429.708434987813, 9112.681056163068, 11210.327380357123, 11213.60752373663, 11235.887641123647, 11249.437528123643, 5780.450857168562, 5966.2840459144745, 5974.581779275452, 5976.312724036748, 5979.133025711248, 3629.491871138043, 3698.7595806272507, 3713.832877520518, 3773.0, 3794.1842503861526, 1207.085282117622, 1217.3304682424632, 1247.2267194339477, 1268.0109514578644, 1268.0743057567936, 15617.765733514007, 15774.921125394414, 15784.447544336008, 15804.920975395164, 15808.920955395264, 4934.1118598651865, 5008.5993120550265, 5024.045431368028, 5032.446430892599, 5067.695938243717, 12918.870811291954, 12964.611061668165, 12970.092093553549, 12979.091463597457, 12990.0, 4988.226824842155, 5872.0898260769645, 5975.79084732041, 5982.880342393215, 5998.520118390518, 4525.162844873643, 5178.844634660966, 5242.266082748418, 5377.892442151135, 5378.354597448331, 3899.7660140391667, 4107.199096176217, 4198.874033778991, 4285.742855428684, 4317.352397140391, 1133.5975728616388, 1193.3376975778526, 1193.462941676249, 1207.2816674078908, 1209.407390378708, 930.7626555228373, 939.9248060155171, 950.847864341702, 957.1194501059042, 997.3118548201718, 6306.463950564162, 6476.773312934025, 6493.51298652603, 6634.0, 6658.533902626769, 949.924006079512, 975.4488713876636, 1019.8368261078192, 1037.4708898461838, 1053.0943388685723, 4212.220739163204, 4297.8495752648505, 4311.310190369526, 4417.712848664826, 4433.889152771142, 12509.749805003848, 12711.936440317739, 12856.228626282265, 12867.806982895265, 12879.678008049686, 12480.937595311965, 12504.124711270124, 12759.872401276054, 12795.232286062866, 12843.165194262343, 9850.950745246371, 9875.308728389087, 9916.454594997344, 9941.950290248647, 9961.850572241423, 5213.348331458567, 6196.039613859859, 6238.75044998195, 6252.9374706254175, 6295.716692748746, 9071.637134514545, 11813.40932953366, 11894.881051189377, 12024.75950481003, 12101.939490302491, 12053.156279060733, 12078.0, 12095.253221099638, 12128.151029427856, 12177.086718495972, 7050.541714788727, 7949.205079492004, 8822.294216462538, 8905.55472226399, 9210.861837072584, 10138.087572118544, 10447.582096716047, 10558.419286939292, 10563.683089507327, 10577.995090466393, 9196.448213107235, 9208.585613647268, 9279.72160835176, 9340.299477539347, 9367.484788336707, 8928.330375221754, 9080.500572468314, 9122.771930701785, 9150.993390726882, 9188.908110918805, 8010.118886922317, 8014.919850801417, 8029.4379393441895, 8059.153788852086, 8078.394120441108, 5713.742881570256, 5838.766449341979, 5876.44173803488, 5922.230110085661, 6109.450149486567, 5940.762369505172, 5992.730327135377, 6004.699765011645, 6051.818445446644, 6230.43926046658], "value": 5167.697548806118, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.1903452886384, 372.53247174795627, 383.23735765826, 386.69257939937745, 392.47212499188583, 5173.301604283421, 5272.340957380327, 5527.613067085305, 5583.4974852263285, 5592.036220802971, 5185.962807438508, 5316.9734151329285, 5332.413434522199, 5372.785088596452, 5397.271368365269, 3704.185079282567, 3783.3379158647263, 4051.4530538377385, 4204.642605378546, 4456.844010459635, 3772.773633581994, 4210.915781684364, 4450.221211288012, 4453.309736990772, 4474.724703459515, 3559.8252576649775, 4322.697411181219, 4397.516273209944, 4406.361077643755, 4423.734575925439, 3871.3999330103597, 3970.4067818623903, 4147.958520414818, 4267.594578515036, 4443.511213766483, 3424.520567120605, 3511.7015053720465, 3829.0427393151713, 4122.979385103085, 4142.5857414258635, 4059.8985025374304, 4347.630451411634, 5191.377034755816, 5236.895262094775, 5245.763940622643, 4586.564276393738, 4985.152524070917, 5126.410462796789, 5151.665141765773, 5164.948350516484, 4718.450479233234, 5405.540529055035, 5434.83695489136, 5636.718164091779, 5804.593678442468, 3831.9233615327817, 4531.048479819242, 4544.727316361029, 4586.495485496593, 4586.977065114686, 4969.850904472872, 5540.889182216333, 5819.447152520504, 6064.787732429344, 6121.510279177655, 3985.5217373915316, 4477.485089214782, 4608.423947006624, 4612.993347893677, 4672.392588963413, 4077.571854955246, 4734.692245004064, 4775.0, 4781.354517140211, 4787.234042553175, 4582.747948862844, 4627.30590411441, 4628.861134165981, 4664.0, 5131.025105230069, 5693.943060569424, 5713.542916566665, 5720.93929747583, 5750.514598613005, 5760.436605710503, 1516.5601975427223, 1577.0208388472395, 1602.3350309621376, 1612.927418266181, 1616.4180894877834, 1029.763154474475, 1094.2832444748544, 1123.7694724276903, 1204.7168915304896, 1222.541546919905, 5147.742612869416, 5212.817551385683, 5223.869403264873, 5234.895302093937, 5248.868778280497, 5037.496250374933, 5217.921121892952, 5836.336644056423, 5884.911726324109, 5933.643981361046, 4300.430642566662, 4311.07311927937, 4542.886427839265, 4994.475580064113, 5009.148444764399, 2576.4074262919257, 2821.3228825081833, 3037.9544306835414, 3058.1284333964713, 3068.8465576721073, 5144.99672563854, 5257.658252213594, 5898.203742494794, 6120.357362476876, 6214.0, 5118.8976220476125, 5139.948600514022, 5448.046591846411, 5691.41040826423, 5994.602159136381, 4679.906401871944, 5913.46249975001, 6070.332263451017, 6274.968625156845, 6369.745210191726, 5495.499547705865, 5634.718264086698, 5867.589268751147, 5954.702264886825, 5977.964873710315, 5513.959834860967, 5788.594798364074, 5840.0, 5884.235049443545, 5908.400303866285, 5886.205362276038, 6003.189569408074, 6059.909101363395, 6116.042866282747, 6122.5408094392205, 4168.437260969851, 4345.630621397217, 5222.712750798591, 5226.137687281556, 5228.692826793302, 5539.279893613804, 5637.408072152365, 5841.94158058414, 5953.940460595339, 6060.818140462744, 5170.922436163536, 5377.408485066561, 5413.780350056458, 5754.19441278213, 5781.219535362672, 5248.501392367719, 5320.547753441001, 5726.169705392612, 5726.627769194989, 5745.0, 4338.769699543047, 4913.9017219656125, 5278.630495865253, 5367.364267519168, 5657.236273103243, 4786.540105267787, 5107.336046313955, 5783.248177737036, 5809.804607465961, 5890.970545147246, 4971.23521150001, 5334.973325133349, 5429.520645467426, 5640.6180485779605, 5672.624788250984], "value": 4326.071847441894, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.5104377979197, 715.7566427414678, 761.0980987529774, 789.7512283630656, 843.814360840615, 14397.78403323951, 15153.24233788309, 15702.921485392586, 15760.921195394021, 15761.527154185378, 14524.636884077878, 14585.708285834276, 14731.189784561844, 14732.042417242872, 14742.778858317133, 935.6304259817368, 963.8795150606175, 967.4098799732158, 967.9467629280387, 969.6363863551169, 912.3704643795793, 940.1068984464752, 944.834653935562, 960.3325688646394, 962.7448726087592, 1012.0537297626706, 1014.5434554450527, 1016.1616666250336, 1016.6695823857239, 1017.1608423050975, 851.5188918261163, 1000.0649392817702, 1006.2151521812998, 1010.2625083688948, 1013.716159475348, 750.7635094945075, 751.5678484871166, 752.6048824367197, 753.7361923326791, 753.7889390970537, 15605.531834044883, 15945.521634350987, 15949.362025518965, 16386.098764567945, 16624.00255984645, 13652.726945461036, 14013.369398377097, 14098.859011409857, 14100.435982560686, 14128.92935535326, 11700.482755534811, 11877.406129693567, 11880.67702387454, 11914.285142891455, 11992.70018249542, 3584.121890136907, 3625.782453052825, 3642.974547990243, 3652.141746689526, 3799.6010418905894, 113409.43295283553, 113442.43278783635, 113846.43076784645, 118086.0, 119979.0, 9345.439273643604, 9919.156871665846, 10446.373217606968, 10461.633842815465, 10483.633072842567, 17837.0, 21497.57004859894, 21533.78466215349, 21867.562648746934, 21868.781312186675, 6340.936590634127, 7446.255374462618, 7553.622318884034, 7681.347085497683, 7794.961025194838, 4234.87295381139, 4235.639970602533, 4314.654827613784, 4342.413774140514, 4446.088551846864, 3194.2493514024186, 3232.69289417505, 3264.281857991238, 3295.2585668224456, 3296.35721034396, 597.8804239152187, 600.5255847880126, 600.7657013764635, 609.1411110334407, 615.5537235504223, 514.590023336064, 522.339240860312, 527.881226723984, 532.8294945617442, 548.629674969394, 9332.39339442934, 9579.473128977834, 9784.951075244579, 9899.950500247596, 9954.104130628133, 638.141699414287, 655.2693746472648, 663.5222639699409, 671.9496037797087, 680.0954730208811, 4392.231359512072, 4709.152352576569, 4716.174669432836, 4769.117713223065, 4794.304825800239, 103432.48283758532, 108082.45958770312, 108161.45919270354, 109159.0, 110357.0, 71685.64157179181, 77546.28587999393, 91648.0495789287, 93467.0, 96518.0, 62878.0, 81049.99999999882, 82251.0, 83397.0, 84379.0, 9147.176754092163, 9214.953925230599, 9220.262379009797, 9252.889653241655, 9279.443233406017, 52104.218436722724, 52623.73688131535, 52637.47362526326, 53722.73138634282, 53924.73037634787, 64755.67622161859, 65661.34338656552, 66529.66940661256, 67052.99420508598, 68247.65876170587, 8689.17452841979, 9349.48577828226, 10345.581367619749, 10358.549803027432, 10678.145748339959, 11938.820917686067, 11986.520539178335, 11989.700257493632, 12051.457684404048, 12189.69525761863, 11307.717307067389, 11454.778902983178, 11664.475098620413, 11737.0, 12061.939690301493, 11239.213255072393, 11397.772044559228, 11478.483468244116, 11544.711382215175, 11990.160688751703, 11205.60780372683, 11668.94165529167, 11702.414879255834, 12059.879401205875, 12072.275663460221, 7851.56816375082, 9292.256619470561, 9511.381760185566, 9546.85679714791, 10055.949720251352, 8994.962342261355, 9637.518124093627, 9656.0, 9671.758206044906, 9744.658936937172], "value": 7283.35449168795, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 122.0, 121.0, 120.0, 120.0, 121.0, 119.0, 121.0, 123.0, 118.0], "value": 119.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 112.0, 148.0, 113.0, 114.0, 112.0, 112.0, 134.0, 125.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 201.0, 200.0, 226.0, 195.0, 199.0, 196.0, 198.0, 203.0, 200.0], "value": 202.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 63.0, 83.0, 64.0, 63.0, 61.0, 59.0, 74.0, 61.0], "value": 67.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 86.0, 71.0, 72.0, 70.0, 81.0, 70.0, 70.0, 82.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 91.0, 113.0, 90.0, 89.0, 89.0, 90.0, 92.0, 89.0], "value": 92.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 106.0, 98.0, 149.0, 127.0, 94.0, 124.0, 154.0, 107.0, 141.0], "value": 119.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 126.0, 144.0, 100.0, 90.0, 93.0, 91.0, 90.0, 92.0, 90.0], "value": 100.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 66.0, 69.0, 70.0, 69.0, 68.0, 68.0, 68.0, 70.0], "value": 68.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 65.0, 74.0, 64.0, 59.0, 79.0, 93.0, 88.0, 59.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 99.0, 105.0, 87.0, 84.0, 72.0, 105.0, 102.0, 82.0, 88.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 156.0, 156.0, 153.0, 154.0, 153.0, 147.0, 187.0, 212.0, 169.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 176.0, 157.0, 161.0, 210.0, 215.0, 225.0, 174.0, 169.0, 149.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 86.0, 86.0, 77.0, 67.0, 68.0, 66.0, 67.0, 64.0, 84.0], "value": 74.3, "unit": "score"}]}]} 14:17:38 INFO - Return code: 0 14:17:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:17:38 ERROR - # TBPL SUCCESS # 14:17:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:17:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:17:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:17:38 INFO - 2017-03-08 14:17:38.913 screenresolution[1938:18996] starting screenresolution argv=screenresolution get 14:17:38 INFO - 2017-03-08 14:17:38.916 screenresolution[1938:18996] Display 0: 1600x1200x32@60 14:17:38 INFO - 2017-03-08 14:17:38.922 screenresolution[1939:18999] starting screenresolution argv=screenresolution list 14:17:38 INFO - Available Modes on Display 0 14:17:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:17:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:17:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:17:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:17:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:17:39 INFO - Intel Iris: 14:17:39 INFO - Chipset Model: Intel Iris 14:17:39 INFO - Type: GPU 14:17:39 INFO - Bus: Built-In 14:17:39 INFO - VRAM (Dynamic, Max): 1536 MB 14:17:39 INFO - Vendor: Intel (0x8086) 14:17:39 INFO - Device ID: 0x0a2e 14:17:39 INFO - Revision ID: 0x0009 14:17:39 INFO - Displays: 14:17:39 INFO - Display: 14:17:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:17:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:17:39 INFO - Display Serial Number: 1600x1200 60 14:17:39 INFO - Main Display: Yes 14:17:39 INFO - Mirror: Off 14:17:39 INFO - Online: Yes 14:17:39 INFO - Rotation: Supported 14:17:39 INFO - Return code: 0 14:17:39 INFO - Running post-action listener: _resource_record_post_action 14:17:39 INFO - [mozharness: 2017-03-08 22:17:39.160044Z] Finished run-tests step (success) 14:17:39 INFO - Running post-run listener: _resource_record_post_run 14:17:39 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 761856; Write bytes: 513257472; Read time: 23; Write time: 1738 14:17:39 INFO - TinderboxPrint: CPU usage
54.5% 14:17:39 INFO - TinderboxPrint: I/O read bytes / time
761,856 / 23 14:17:39 INFO - TinderboxPrint: I/O write bytes / time
513,257,472 / 1,738 14:17:39 INFO - TinderboxPrint: CPU idle
2,185.9 (45.5%) 14:17:39 INFO - TinderboxPrint: CPU system
964.3 (20.1%) 14:17:39 INFO - TinderboxPrint: CPU user
1,651.4 (34.4%) 14:17:39 INFO - TinderboxPrint: Swap in / out
209,444,864 / 0 14:17:39 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200741376; Write bytes: 200644608; Read time: 15599; Write time: 443 14:17:39 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 749568; Write bytes: 301559808; Read time: 12; Write time: 1236 14:17:39 INFO - Running post-run listener: _upload_blobber_files 14:17:39 INFO - Blob upload gear active. 14:17:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:17:39 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 14:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:17:40 INFO - (blobuploader) - INFO - Open directory for files ... 14:17:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:17:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:17:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:17:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:17:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:17:41 INFO - (blobuploader) - INFO - Done attempting. 14:17:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:17:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:17:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:17:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:17:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:17:42 INFO - (blobuploader) - INFO - Done attempting. 14:17:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:17:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:17:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:17:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:17:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:17:43 INFO - (blobuploader) - INFO - Done attempting. 14:17:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:17:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:17:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:17:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:17:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:17:46 INFO - (blobuploader) - INFO - Done attempting. 14:17:46 INFO - (blobuploader) - INFO - Iteration through files over. 14:17:46 INFO - Return code: 0 14:17:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:17:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:17:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15735f56b19bb83526e8c106bb13c0c26552400a125fe04568764cafb0e194e97c57251a39925ea219c3b29790ecfb09e42beceb15347fe4a7624718c2e985a9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9d7cda82e9ae7dfd855655afa2d288d306847017f658e48f1a091530a7c4037925cca96f1fed79648f2ea05cdf99f3b3261bb61c18fdba262d9e55b61e8c827", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2403a00f6ae1929d7ff78a175320487ee5b2a263a4bff9be73e778bc5ed7f9a63c5f6e54ce260927b019d409ee8ec8b49320e064f8dbd96e4a157256dbc1fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6829b41b7b55edfc09863f30d03e628dd0f827d46671df60926a98aaba52ff9f440a204c6552eeb17c528e45cce31c8dd11da5edeb689088d9557a4a82d0c91c"} 14:17:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:17:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:17:46 INFO - Contents: 14:17:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15735f56b19bb83526e8c106bb13c0c26552400a125fe04568764cafb0e194e97c57251a39925ea219c3b29790ecfb09e42beceb15347fe4a7624718c2e985a9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9d7cda82e9ae7dfd855655afa2d288d306847017f658e48f1a091530a7c4037925cca96f1fed79648f2ea05cdf99f3b3261bb61c18fdba262d9e55b61e8c827", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2403a00f6ae1929d7ff78a175320487ee5b2a263a4bff9be73e778bc5ed7f9a63c5f6e54ce260927b019d409ee8ec8b49320e064f8dbd96e4a157256dbc1fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6829b41b7b55edfc09863f30d03e628dd0f827d46671df60926a98aaba52ff9f440a204c6552eeb17c528e45cce31c8dd11da5edeb689088d9557a4a82d0c91c"} 14:17:46 INFO - Running post-run listener: copy_logs_to_upload_dir 14:17:46 INFO - Copying logs to upload dir... 14:17:46 INFO - mkdir: /builds/slave/test/build/upload/logs 14:17:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.911532 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-08 14:17:46.335325) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 14:17:46.338646) ========= 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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15735f56b19bb83526e8c106bb13c0c26552400a125fe04568764cafb0e194e97c57251a39925ea219c3b29790ecfb09e42beceb15347fe4a7624718c2e985a9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9d7cda82e9ae7dfd855655afa2d288d306847017f658e48f1a091530a7c4037925cca96f1fed79648f2ea05cdf99f3b3261bb61c18fdba262d9e55b61e8c827", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2403a00f6ae1929d7ff78a175320487ee5b2a263a4bff9be73e778bc5ed7f9a63c5f6e54ce260927b019d409ee8ec8b49320e064f8dbd96e4a157256dbc1fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6829b41b7b55edfc09863f30d03e628dd0f827d46671df60926a98aaba52ff9f440a204c6552eeb17c528e45cce31c8dd11da5edeb689088d9557a4a82d0c91c"} build_url:https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011242 build_url: 'https://queue.taskcluster.net/v1/task/bY0nnvr5Trm5TeHY4aKWWw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15735f56b19bb83526e8c106bb13c0c26552400a125fe04568764cafb0e194e97c57251a39925ea219c3b29790ecfb09e42beceb15347fe4a7624718c2e985a9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9d7cda82e9ae7dfd855655afa2d288d306847017f658e48f1a091530a7c4037925cca96f1fed79648f2ea05cdf99f3b3261bb61c18fdba262d9e55b61e8c827", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4e2403a00f6ae1929d7ff78a175320487ee5b2a263a4bff9be73e778bc5ed7f9a63c5f6e54ce260927b019d409ee8ec8b49320e064f8dbd96e4a157256dbc1fc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6829b41b7b55edfc09863f30d03e628dd0f827d46671df60926a98aaba52ff9f440a204c6552eeb17c528e45cce31c8dd11da5edeb689088d9557a4a82d0c91c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 14:17:46.375949) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 14:17:46.376395) ========= 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.R2Ad71Iwc6/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.CTuEzSB30J/Listeners TMPDIR=/var/folders/w2/w1z_phsd18j4ppkx071wqglm00000w/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.005375 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 14:17:46.424557) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 14:17:46.424845) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 14:17:46.425164) ========= ========= Total master_lag: 0.20 =========