builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0350 starttime: 1481301750.42 results: success (0) buildid: 20161209062450 builduid: b146c5af0cb14140a282cfc81f793515 revision: 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:30.424555) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:30.425010) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:30.425316) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:30.451237) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:30.451607) ========= 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.tAHcANkG6q/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.jfsGQXjOpx/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 --2016-12-09 08:42:30-- 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% 19.5M=0.001s 2016-12-09 08:42:30 (19.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123969 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:30.596684) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:30.597010) ========= 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.tAHcANkG6q/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.jfsGQXjOpx/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.024056 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:30.645710) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-09 08:42:30.646095) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 --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 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tAHcANkG6q/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.jfsGQXjOpx/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 2016-12-09 08:42:30,742 truncating revision to first 12 chars 2016-12-09 08:42:30,742 Setting DEBUG logging. 2016-12-09 08:42:30,742 attempt 1/10 2016-12-09 08:42:30,742 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0d52c1ee6bce?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-09 08:42:32,727 unpacking tar archive at: mozilla-aurora-0d52c1ee6bce/testing/mozharness/ program finished with exit code 0 elapsedTime=2.279868 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-12-09 08:42:32.949947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:32.950293) ========= script_repo_revision: 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:32.950691) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:32.950976) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-09 08:42:32.970653) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-09 08:42:32.970954) ========= /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.tAHcANkG6q/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.jfsGQXjOpx/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:42:33 INFO - MultiFileLogger online at 20161209 08:42:33 in /builds/slave/test 08:42:33 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 08:42:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:42:33 INFO - {'append_to_log': False, 08:42:33 INFO - 'base_work_dir': '/builds/slave/test', 08:42:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:42:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:42:33 INFO - 'branch': 'Mozilla-Aurora', 08:42:33 INFO - 'buildbot_json_path': 'buildprops.json', 08:42:33 INFO - 'config_files': ('talos/mac_config.py',), 08:42:33 INFO - 'default_actions': ('clobber', 08:42:33 INFO - 'read-buildbot-config', 08:42:33 INFO - 'download-and-extract', 08:42:33 INFO - 'populate-webroot', 08:42:33 INFO - 'create-virtualenv', 08:42:33 INFO - 'install', 08:42:33 INFO - 'run-tests'), 08:42:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:42:33 INFO - 'download_minidump_stackwalk': True, 08:42:33 INFO - 'download_symbols': 'ondemand', 08:42:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:42:33 INFO - 'tooltool.py': '/tools/tooltool.py', 08:42:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:42:33 INFO - '/tools/misc-python/virtualenv.py')}, 08:42:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:42:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:42:33 INFO - 'installer_path': 'installer.exe', 08:42:33 INFO - 'log_level': 'info', 08:42:33 INFO - 'log_name': 'talos', 08:42:33 INFO - 'log_to_console': True, 08:42:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:42:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:42:33 INFO - 'opt_config_files': (), 08:42:33 INFO - 'pip_index': False, 08:42:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:42:33 INFO - 'cmd': ('bash', 08:42:33 INFO - '-c', 08:42:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:42:33 INFO - 'enabled': True, 08:42:33 INFO - 'halt_on_failure': False, 08:42:33 INFO - 'name': 'check_screen_resolution'},), 08:42:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:42:33 INFO - 'cmd': ('bash', 08:42:33 INFO - '-c', 08:42:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:42:33 INFO - 'enabled': True, 08:42:33 INFO - 'halt_on_failure': False, 08:42:33 INFO - 'name': 'check_screen_resolution'},), 08:42:33 INFO - 'run_cmd_checks_enabled': True, 08:42:33 INFO - 'sps_profile': False, 08:42:33 INFO - 'sps_profile_interval': 0, 08:42:33 INFO - 'suite': 'dromaeojs-e10s', 08:42:33 INFO - 'system_bits': '32', 08:42:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:42:33 INFO - 'title': 't-yosemite-r7-0350', 08:42:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:42:33 INFO - 'use_talos_json': True, 08:42:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:42:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:42:33 INFO - 'work_dir': 'build'} 08:42:33 INFO - [mozharness: 2016-12-09 16:42:33.173818Z] Running clobber step. 08:42:33 INFO - Running pre-action listener: _resource_record_pre_action 08:42:33 INFO - Running main action method: clobber 08:42:33 INFO - rmtree: /builds/slave/test/build 08:42:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:42:35 INFO - Running post-action listener: _resource_record_post_action 08:42:35 INFO - [mozharness: 2016-12-09 16:42:35.407994Z] Finished clobber step (success) 08:42:35 INFO - [mozharness: 2016-12-09 16:42:35.408137Z] Running read-buildbot-config step. 08:42:35 INFO - Running pre-action listener: _resource_record_pre_action 08:42:35 INFO - Running main action method: read_buildbot_config 08:42:35 INFO - Using buildbot properties: 08:42:35 INFO - { 08:42:35 INFO - "project": "", 08:42:35 INFO - "product": "firefox", 08:42:35 INFO - "script_repo_revision": "production", 08:42:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:42:35 INFO - "repository": "", 08:42:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:42:35 INFO - "buildid": "20161209062450", 08:42:35 INFO - "pgo_build": "False", 08:42:35 INFO - "basedir": "/builds/slave/test", 08:42:35 INFO - "buildnumber": 478, 08:42:35 INFO - "slavename": "t-yosemite-r7-0350", 08:42:35 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:42:35 INFO - "platform": "yosemite_r7", 08:42:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:42:35 INFO - "branch": "mozilla-aurora", 08:42:35 INFO - "repo_path": "releases/mozilla-aurora", 08:42:35 INFO - "revision": "0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854", 08:42:35 INFO - "stage_platform": "macosx64", 08:42:35 INFO - "builduid": "b146c5af0cb14140a282cfc81f793515", 08:42:35 INFO - "slavebuilddir": "test" 08:42:35 INFO - } 08:42:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:42:35 INFO - Running post-action listener: _resource_record_post_action 08:42:35 INFO - [mozharness: 2016-12-09 16:42:35.411653Z] Finished read-buildbot-config step (success) 08:42:35 INFO - [mozharness: 2016-12-09 16:42:35.411759Z] Running download-and-extract step. 08:42:35 INFO - Running pre-action listener: _resource_record_pre_action 08:42:35 INFO - Running main action method: download_and_extract 08:42:35 INFO - mkdir: /builds/slave/test/build/tests 08:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:42:35 INFO - trying https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:42:35 INFO - Downloading https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:42:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:42:36 INFO - Downloaded 1199 bytes. 08:42:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:42:36 INFO - Using the following test package requirements: 08:42:36 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:42:36 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:42:36 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:42:36 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:42:36 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:42:36 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:42:36 INFO - u'jsshell-mac.zip'], 08:42:36 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:42:36 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:42:36 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:42:36 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:42:36 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:42:36 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:42:36 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:42:36 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:42:36 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:42:36 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:42:36 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:42:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:42:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:42:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:42:36 INFO - Fetch https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:42:37 INFO - Http code: 200 08:42:37 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 08:42:37 INFO - x-amz-cf-id: Puj7U2WAdcBxbIQJ9DcKbvUuRvLySaWLJQ-2fdqDBhYNp3RFKR5p9g== 08:42:37 INFO - x-amz-version-id: GV6_c8uxiGff80i6eP4zPHVnv0cSz4MJ 08:42:37 INFO - Expected file size: 16281045 08:42:37 INFO - Obtained file size: 16281045 08:42:44 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:42:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:42:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:42:44 INFO - Fetch https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:42:44 INFO - Http code: 200 08:42:44 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 08:42:44 INFO - x-amz-cf-id: MceaWhYnDQQPvu6q2bSkU3zh7CePP16TvPwjF9_D6jcgj91K0K5iOA== 08:42:44 INFO - x-amz-version-id: GV6_c8uxiGff80i6eP4zPHVnv0cSz4MJ 08:42:45 INFO - Expected file size: 16281045 08:42:45 INFO - Obtained file size: 16281045 08:42:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:42:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:42:51 INFO - Fetch https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:42:52 INFO - Http code: 200 08:42:52 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 08:42:52 INFO - x-amz-cf-id: We3Fpm7Vz0iaOH-imlelNuVFd4XlWERhAEo7nnsctA91JuFVKp91pQ== 08:42:52 INFO - x-amz-version-id: zlkNd1oAOTWuOP_YTPpyrqWD0sCSb47r 08:42:52 INFO - Expected file size: 14409038 08:42:52 INFO - Obtained file size: 14409038 08:42:52 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')]} 08:42:52 INFO - trying https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:42:52 INFO - Downloading https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:42:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:42:55 INFO - Downloaded 109784256 bytes. 08:42:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:42:55 INFO - mkdir: /builds/slave/test/properties 08:42:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:42:55 INFO - Writing to file /builds/slave/test/properties/build_url 08:42:55 INFO - Contents: 08:42:55 INFO - build_url:https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:42:56 INFO - Running post-action listener: _resource_record_post_action 08:42:56 INFO - Running post-action listener: set_extra_try_arguments 08:42:56 INFO - [mozharness: 2016-12-09 16:42:56.349940Z] Finished download-and-extract step (success) 08:42:56 INFO - [mozharness: 2016-12-09 16:42:56.350065Z] Running populate-webroot step. 08:42:56 INFO - Running pre-action listener: _resource_record_pre_action 08:42:56 INFO - Running main action method: populate_webroot 08:42:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:42:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:42:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:42:56 INFO - '--tppagecycles', 08:42:56 INFO - '7'], 08:42:56 INFO - 'tests': ['tsvgm']}}, 08:42:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:42:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:42:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:42:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:42:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:56 INFO - 'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:42:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:56 INFO - 'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['damp', 'tps']}, 08:42:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:56 INFO - 'tests': ['damp', 'tps']}, 08:42:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['dromaeo_dom']}, 08:42:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:42:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['basic_compositor_video']}, 08:42:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:42:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['a11yr', 08:42:56 INFO - 'ts_paint', 08:42:56 INFO - 'tpaint', 08:42:56 INFO - 'sessionrestore', 08:42:56 INFO - 'sessionrestore_no_auto_restore', 08:42:56 INFO - 'tabpaint']}, 08:42:56 INFO - 'other-e10s': {'tests': ['a11yr', 08:42:56 INFO - 'ts_paint', 08:42:56 INFO - 'tpaint', 08:42:56 INFO - 'sessionrestore', 08:42:56 INFO - 'sessionrestore_no_auto_restore', 08:42:56 INFO - 'tabpaint']}, 08:42:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:42:56 INFO - 'ts_paint', 08:42:56 INFO - 'tpaint', 08:42:56 INFO - 'sessionrestore', 08:42:56 INFO - 'sessionrestore_no_auto_restore', 08:42:56 INFO - 'tabpaint']}, 08:42:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:42:56 INFO - 'ts_paint', 08:42:56 INFO - 'tpaint', 08:42:56 INFO - 'sessionrestore', 08:42:56 INFO - 'sessionrestore_no_auto_restore', 08:42:56 INFO - 'tabpaint']}, 08:42:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['a11yr', 08:42:56 INFO - 'ts_paint', 08:42:56 INFO - 'tpaint', 08:42:56 INFO - 'sessionrestore', 08:42:56 INFO - 'sessionrestore_no_auto_restore', 08:42:56 INFO - 'tabpaint']}, 08:42:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['a11yr', 08:42:56 INFO - 'ts_paint', 08:42:56 INFO - 'tpaint', 08:42:56 INFO - 'sessionrestore', 08:42:56 INFO - 'sessionrestore_no_auto_restore', 08:42:56 INFO - 'tabpaint']}, 08:42:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['tsvgx', 08:42:56 INFO - 'tsvgr_opacity', 08:42:56 INFO - 'tart', 08:42:56 INFO - 'tscrollx', 08:42:56 INFO - 'cart']}, 08:42:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:42:56 INFO - 'tsvgr_opacity', 08:42:56 INFO - 'tart', 08:42:56 INFO - 'tscrollx', 08:42:56 INFO - 'cart']}, 08:42:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:56 INFO - 'talos_options': ['--disable-e10s'], 08:42:56 INFO - 'tests': ['tp5o']}, 08:42:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:56 INFO - 'tests': ['tp5o']}, 08:42:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:56 INFO - 'talos_options': ['--disable-e10s', 08:42:56 INFO - '--xperf_path', 08:42:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:56 INFO - 'tests': ['tp5n']}, 08:42:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:42:56 INFO - 'talos_options': ['--xperf_path', 08:42:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:42:56 INFO - 'tests': ['tp5n']}}, 08:42:56 INFO - 'talos.zip': {'path': '', 08:42:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:42:56 INFO - Running post-action listener: _resource_record_post_action 08:42:56 INFO - [mozharness: 2016-12-09 16:42:56.369175Z] Finished populate-webroot step (success) 08:42:56 INFO - [mozharness: 2016-12-09 16:42:56.369277Z] Running create-virtualenv step. 08:42:56 INFO - Running pre-action listener: _resource_record_pre_action 08:42:56 INFO - Running main action method: create_virtualenv 08:42:56 INFO - Creating virtualenv /builds/slave/test/build/venv 08:42:56 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 08:42:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:42:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:42:56 INFO - Using real prefix '/tools/python27' 08:42:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:42:57 INFO - Installing distribute.............................................................................................................................................................................................done. 08:43:00 INFO - Installing pip.................done. 08:43:00 INFO - Return code: 0 08:43:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:43:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:43:00 INFO - Reading from file tmpfile_stdout 08:43:00 INFO - Output received: 08:43:00 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) 08:43:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:43:00 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')]} 08:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:00 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')]} 08:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:00 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:00 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 08:43:00 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 08:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:00 INFO - 'HOME': '/Users/cltbld', 08:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:00 INFO - 'LOGNAME': 'cltbld', 08:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:00 INFO - 'MOZ_NO_REMOTE': '1', 08:43:00 INFO - 'NO_EM_RESTART': '1', 08:43:00 INFO - 'PAGER': '/bin/cat', 08:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:00 INFO - 'PWD': '/builds/slave/test', 08:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:00 INFO - 'SHELL': '/bin/bash', 08:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:00 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:00 INFO - 'USER': 'cltbld', 08:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:00 INFO - 'XPC_FLAGS': '0x0', 08:43:00 INFO - 'XPC_SERVICE_NAME': '0', 08:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:00 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 08:43:00 INFO - Cleaning up... 08:43:00 INFO - Return code: 0 08:43:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:43:00 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')]} 08:43:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:00 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')]} 08:43:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:00 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:00 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 08:43:00 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 08:43:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:00 INFO - 'HOME': '/Users/cltbld', 08:43:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:00 INFO - 'LOGNAME': 'cltbld', 08:43:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:00 INFO - 'MOZ_NO_REMOTE': '1', 08:43:00 INFO - 'NO_EM_RESTART': '1', 08:43:00 INFO - 'PAGER': '/bin/cat', 08:43:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:00 INFO - 'PWD': '/builds/slave/test', 08:43:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:00 INFO - 'SHELL': '/bin/bash', 08:43:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:00 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:00 INFO - 'USER': 'cltbld', 08:43:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:00 INFO - 'XPC_FLAGS': '0x0', 08:43:00 INFO - 'XPC_SERVICE_NAME': '0', 08:43:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:00 INFO - Downloading/unpacking psutil>=3.1.1 08:43:00 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 08:43:00 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 08:43:00 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 08:43:00 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 08:43:00 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 08:43:00 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 08:43:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:43:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:04 INFO - Installing collected packages: psutil 08:43:04 INFO - Running setup.py install for psutil 08:43:04 INFO - building 'psutil._psutil_osx' extension 08:43:04 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 08:43:05 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 08:43:05 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 08:43:05 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 08:43:05 INFO - building 'psutil._psutil_posix' extension 08:43:05 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 08:43:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:43:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:43:05 INFO - ^ 08:43:05 INFO - 1 warning generated. 08:43:05 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 08:43:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:43:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:43:05 INFO - Successfully installed psutil 08:43:05 INFO - Cleaning up... 08:43:05 INFO - Return code: 0 08:43:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:05 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:05 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 08:43:05 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 08:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:05 INFO - 'HOME': '/Users/cltbld', 08:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:05 INFO - 'LOGNAME': 'cltbld', 08:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:05 INFO - 'MOZ_NO_REMOTE': '1', 08:43:05 INFO - 'NO_EM_RESTART': '1', 08:43:05 INFO - 'PAGER': '/bin/cat', 08:43:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:05 INFO - 'PWD': '/builds/slave/test', 08:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:05 INFO - 'SHELL': '/bin/bash', 08:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:05 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:05 INFO - 'USER': 'cltbld', 08:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:05 INFO - 'XPC_FLAGS': '0x0', 08:43:05 INFO - 'XPC_SERVICE_NAME': '0', 08:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:43:05 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 08:43:05 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 08:43:05 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 08:43:05 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 08:43:05 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 08:43:05 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 08:43:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:43:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:43:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:43:08 INFO - Installing collected packages: mozsystemmonitor 08:43:08 INFO - Running setup.py install for mozsystemmonitor 08:43:08 INFO - Successfully installed mozsystemmonitor 08:43:08 INFO - Cleaning up... 08:43:08 INFO - Return code: 0 08:43:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:43:08 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')]} 08:43:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:08 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')]} 08:43:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:08 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:08 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 08:43:08 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 08:43:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:08 INFO - 'HOME': '/Users/cltbld', 08:43:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:08 INFO - 'LOGNAME': 'cltbld', 08:43:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:08 INFO - 'MOZ_NO_REMOTE': '1', 08:43:08 INFO - 'NO_EM_RESTART': '1', 08:43:08 INFO - 'PAGER': '/bin/cat', 08:43:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:08 INFO - 'PWD': '/builds/slave/test', 08:43:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:08 INFO - 'SHELL': '/bin/bash', 08:43:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:08 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:08 INFO - 'USER': 'cltbld', 08:43:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:08 INFO - 'XPC_FLAGS': '0x0', 08:43:08 INFO - 'XPC_SERVICE_NAME': '0', 08:43:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:09 INFO - Downloading/unpacking jsonschema==2.5.1 08:43:09 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 08:43:09 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 08:43:09 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 08:43:09 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 08:43:09 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 08:43:09 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 08:43:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:43:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:43:11 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 08:43:11 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 08:43:11 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 08:43:11 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 08:43:11 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 08:43:11 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 08:43:12 INFO - Downloading functools32-3.2.3-2.tar.gz 08:43:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:43:12 INFO - warning: no files found matching '*.txt' 08:43:12 INFO - no previously-included directories found matching 'build' 08:43:12 INFO - no previously-included directories found matching 'dist' 08:43:12 INFO - no previously-included directories found matching '.git*' 08:43:12 INFO - Installing collected packages: jsonschema, functools32 08:43:12 INFO - Running setup.py install for functools32 08:43:12 INFO - warning: no files found matching '*.txt' 08:43:12 INFO - no previously-included directories found matching 'build' 08:43:12 INFO - no previously-included directories found matching 'dist' 08:43:12 INFO - no previously-included directories found matching '.git*' 08:43:12 INFO - Successfully installed jsonschema functools32 08:43:12 INFO - Cleaning up... 08:43:12 INFO - Return code: 0 08:43:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:43:12 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')]} 08:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:12 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')]} 08:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:12 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:12 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 08:43:12 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 08:43:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:12 INFO - 'HOME': '/Users/cltbld', 08:43:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:12 INFO - 'LOGNAME': 'cltbld', 08:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:12 INFO - 'MOZ_NO_REMOTE': '1', 08:43:12 INFO - 'NO_EM_RESTART': '1', 08:43:12 INFO - 'PAGER': '/bin/cat', 08:43:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:12 INFO - 'PWD': '/builds/slave/test', 08:43:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:12 INFO - 'SHELL': '/bin/bash', 08:43:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:12 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:12 INFO - 'USER': 'cltbld', 08:43:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:12 INFO - 'XPC_FLAGS': '0x0', 08:43:12 INFO - 'XPC_SERVICE_NAME': '0', 08:43:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:43:12 INFO - Cleaning up... 08:43:12 INFO - Return code: 0 08:43:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:43:12 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')]} 08:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:12 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')]} 08:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:12 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:12 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 08:43:12 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 08:43:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:12 INFO - 'HOME': '/Users/cltbld', 08:43:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:12 INFO - 'LOGNAME': 'cltbld', 08:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:12 INFO - 'MOZ_NO_REMOTE': '1', 08:43:12 INFO - 'NO_EM_RESTART': '1', 08:43:12 INFO - 'PAGER': '/bin/cat', 08:43:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:12 INFO - 'PWD': '/builds/slave/test', 08:43:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:12 INFO - 'SHELL': '/bin/bash', 08:43:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:12 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:12 INFO - 'USER': 'cltbld', 08:43:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:12 INFO - 'XPC_FLAGS': '0x0', 08:43:12 INFO - 'XPC_SERVICE_NAME': '0', 08:43:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:13 INFO - Downloading/unpacking blobuploader==1.2.4 08:43:13 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 08:43:13 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 08:43:13 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 08:43:13 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 08:43:13 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 08:43:13 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 08:43:15 INFO - Downloading blobuploader-1.2.4.tar.gz 08:43:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:43:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:43: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 08:43: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 08:43: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 08:43: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 08:43: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 08:43: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 08:43:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:43:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:43:16 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 08:43:16 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 08:43:16 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 08:43:16 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 08:43:16 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 08:43:16 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 08:43:16 INFO - Downloading docopt-0.6.1.tar.gz 08:43:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:43:16 INFO - Installing collected packages: blobuploader, requests, docopt 08:43:16 INFO - Running setup.py install for blobuploader 08:43:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:43:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:43:17 INFO - Running setup.py install for requests 08:43:17 INFO - Running setup.py install for docopt 08:43:17 INFO - Successfully installed blobuploader requests docopt 08:43:17 INFO - Cleaning up... 08:43:17 INFO - Return code: 0 08:43:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43:17 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')]} 08:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:17 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')]} 08:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:17 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:17 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 08:43:17 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 08:43:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:17 INFO - 'HOME': '/Users/cltbld', 08:43:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:17 INFO - 'LOGNAME': 'cltbld', 08:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:17 INFO - 'MOZ_NO_REMOTE': '1', 08:43:17 INFO - 'NO_EM_RESTART': '1', 08:43:17 INFO - 'PAGER': '/bin/cat', 08:43:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:17 INFO - 'PWD': '/builds/slave/test', 08:43:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:17 INFO - 'SHELL': '/bin/bash', 08:43:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:17 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:17 INFO - 'USER': 'cltbld', 08:43:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:17 INFO - 'XPC_FLAGS': '0x0', 08:43:17 INFO - 'XPC_SERVICE_NAME': '0', 08:43:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-1Bnl1K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-k73BL3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-DAPR4V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-8zV_i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-fJ4nm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-tUhm6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-K0rKF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-rXNKgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:18 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-3haXLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-pMxP3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-hXzVPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-MOSWZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-DuV6Ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-BV15iW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-EgSWlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-DQzj_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:19 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-GEwTdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:43:19 INFO - Running setup.py install for manifestparser 08:43:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:43:20 INFO - Running setup.py install for mozcrash 08:43:20 INFO - Running setup.py install for mozdebug 08:43:20 INFO - Running setup.py install for mozdevice 08:43:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:43:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:43:20 INFO - Running setup.py install for mozfile 08:43:20 INFO - Running setup.py install for mozhttpd 08:43:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:43:20 INFO - Running setup.py install for mozinfo 08:43:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:43:20 INFO - Running setup.py install for mozInstall 08:43:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Running setup.py install for mozleak 08:43:21 INFO - Running setup.py install for mozlog 08:43:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Running setup.py install for moznetwork 08:43:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Running setup.py install for mozprocess 08:43:21 INFO - Running setup.py install for mozprofile 08:43:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:43:21 INFO - Running setup.py install for mozrunner 08:43:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:43:22 INFO - Running setup.py install for mozscreenshot 08:43:22 INFO - Running setup.py install for moztest 08:43:22 INFO - Running setup.py install for mozversion 08:43:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:43:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:43:22 INFO - Cleaning up... 08:43:22 INFO - Return code: 0 08:43:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43: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')]} 08:43:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43: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')]} 08:43:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:22 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:22 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 08:43:22 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 08:43:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:22 INFO - 'HOME': '/Users/cltbld', 08:43:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:22 INFO - 'LOGNAME': 'cltbld', 08:43:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:22 INFO - 'MOZ_NO_REMOTE': '1', 08:43:22 INFO - 'NO_EM_RESTART': '1', 08:43:22 INFO - 'PAGER': '/bin/cat', 08:43:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:22 INFO - 'PWD': '/builds/slave/test', 08:43:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:22 INFO - 'SHELL': '/bin/bash', 08:43:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:22 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:22 INFO - 'USER': 'cltbld', 08:43:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:22 INFO - 'XPC_FLAGS': '0x0', 08:43:22 INFO - 'XPC_SERVICE_NAME': '0', 08:43:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:43:22 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-HEprLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:43:22 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)) 08:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:43:22 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-6xviKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:43:22 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)) 08:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:43:22 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-pAXRST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:43:23 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-0KgiO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:43:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-uF3T8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:43:23 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-1rqZdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:43:23 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-sYKiDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:43:23 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-UFqGsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:43:23 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-z2PI8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:43:23 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-5XV2OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:43:23 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-Es1IAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:43:23 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)) 08:43:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:43:23 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-aFtS0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:43:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 08:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:43:24 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-7cDj7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:43:24 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)) 08:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:43:24 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-y5or5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:43:24 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)) 08:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:24 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-SHPVey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:43:24 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)) 08:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:43:24 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-pNbx32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:43:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 08:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:43:24 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-1KUtmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:43:24 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)) 08:43:24 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)) 08:43:24 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)) 08:43:24 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)) 08:43:24 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:24 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:43:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:43:24 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 08:43:24 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 08:43:24 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 08:43:24 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 08:43:24 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 08:43:24 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 08:43:27 INFO - Downloading blessings-1.6.tar.gz 08:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:43:27 INFO - Installing collected packages: blessings 08:43:27 INFO - Running setup.py install for blessings 08:43:27 INFO - Successfully installed blessings 08:43:27 INFO - Cleaning up... 08:43:27 INFO - Return code: 0 08:43:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:43:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:27 INFO - Reading from file tmpfile_stdout 08:43:27 INFO - Current package versions: 08:43:27 INFO - blessings == 1.6 08:43:27 INFO - blobuploader == 1.2.4 08:43:27 INFO - docopt == 0.6.1 08:43:27 INFO - functools32 == 3.2.3-2 08:43:27 INFO - jsonschema == 2.5.1 08:43:27 INFO - manifestparser == 1.1 08:43:27 INFO - mozInstall == 1.12 08:43:27 INFO - mozcrash == 1.0 08:43:27 INFO - mozdebug == 0.1 08:43:27 INFO - mozdevice == 0.48 08:43:27 INFO - mozfile == 1.2 08:43:27 INFO - mozhttpd == 0.7 08:43:27 INFO - mozinfo == 0.9 08:43:27 INFO - mozleak == 0.1 08:43:27 INFO - mozlog == 3.4 08:43:27 INFO - moznetwork == 0.27 08:43:27 INFO - mozprocess == 0.23 08:43:27 INFO - mozprofile == 0.28 08:43:27 INFO - mozrunner == 6.13 08:43:27 INFO - mozscreenshot == 0.1 08:43:27 INFO - mozsystemmonitor == 0.3 08:43:27 INFO - moztest == 0.7 08:43:27 INFO - mozversion == 1.4 08:43:27 INFO - psutil == 3.1.1 08:43:27 INFO - requests == 1.2.3 08:43:27 INFO - wsgiref == 0.1.2 08:43:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:43: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')]} 08:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:28 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 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:28 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 08:43:28 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 08:43:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:28 INFO - 'HOME': '/Users/cltbld', 08:43:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:28 INFO - 'LOGNAME': 'cltbld', 08:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:28 INFO - 'MOZ_NO_REMOTE': '1', 08:43:28 INFO - 'NO_EM_RESTART': '1', 08:43:28 INFO - 'PAGER': '/bin/cat', 08:43:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:28 INFO - 'PWD': '/builds/slave/test', 08:43:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:28 INFO - 'SHELL': '/bin/bash', 08:43:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:28 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:28 INFO - 'USER': 'cltbld', 08:43:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:28 INFO - 'XPC_FLAGS': '0x0', 08:43:28 INFO - 'XPC_SERVICE_NAME': '0', 08:43:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 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)) 08:43:28 INFO - Cleaning up... 08:43:28 INFO - Return code: 0 08:43:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:43:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10278a490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102700570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95ea576920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, '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.tAHcANkG6q/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.jfsGQXjOpx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 08:43:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:43:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:43:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:43:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:43:29 INFO - 'HOME': '/Users/cltbld', 08:43:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:43:29 INFO - 'LOGNAME': 'cltbld', 08:43:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:43:29 INFO - 'MOZ_NO_REMOTE': '1', 08:43:29 INFO - 'NO_EM_RESTART': '1', 08:43:29 INFO - 'PAGER': '/bin/cat', 08:43:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:43:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:43:29 INFO - 'PWD': '/builds/slave/test', 08:43:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:43:29 INFO - 'SHELL': '/bin/bash', 08:43:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:43:29 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:43:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:43:29 INFO - 'USER': 'cltbld', 08:43:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:43:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:43:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:43:29 INFO - 'XPC_FLAGS': '0x0', 08:43:29 INFO - 'XPC_SERVICE_NAME': '0', 08:43:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:43:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:43:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:43:29 INFO - Cleaning up... 08:43:29 INFO - Return code: 0 08:43:29 INFO - Running post-action listener: _resource_record_post_action 08:43:29 INFO - Running post-action listener: _start_resource_monitoring 08:43:29 INFO - Starting resource monitoring. 08:43:29 INFO - [mozharness: 2016-12-09 16:43:29.642844Z] Finished create-virtualenv step (success) 08:43:29 INFO - [mozharness: 2016-12-09 16:43:29.643822Z] Running install step. 08:43:29 INFO - Running pre-action listener: _resource_record_pre_action 08:43:29 INFO - Running main action method: install 08:43:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:43:29 INFO - Reading from file tmpfile_stdout 08:43:29 INFO - Detecting whether we're running mozinstall >=1.0... 08:43:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:43:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:43:30 INFO - Reading from file tmpfile_stdout 08:43:30 INFO - Output received: 08:43:30 INFO - Usage: mozinstall [options] installer 08:43:30 INFO - Options: 08:43:30 INFO - -h, --help show this help message and exit 08:43:30 INFO - -d DEST, --destination=DEST 08:43:30 INFO - Directory to install application into. [default: 08:43:30 INFO - "/builds/slave/test"] 08:43:30 INFO - --app=APP Application being installed. [default: firefox] 08:43:30 INFO - mkdir: /builds/slave/test/build/application 08:43:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:43:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:44:01 INFO - Reading from file tmpfile_stdout 08:44:01 INFO - Output received: 08:44:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:44:01 INFO - Running post-action listener: _resource_record_post_action 08:44:01 INFO - [mozharness: 2016-12-09 16:44:01.122541Z] Finished install step (success) 08:44:01 INFO - [mozharness: 2016-12-09 16:44:01.122646Z] Running run-tests step. 08:44:01 INFO - Running pre-action listener: _resource_record_pre_action 08:44:01 INFO - Running main action method: run_tests 08:44:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:44:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:44:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:44:01 INFO - 2016-12-09 08:44:01.135 screenresolution[1622:7310] starting screenresolution argv=screenresolution get 08:44:01 INFO - 2016-12-09 08:44:01.138 screenresolution[1622:7310] Display 0: 1600x1200x32@60 08:44:01 INFO - 2016-12-09 08:44:01.143 screenresolution[1623:7312] starting screenresolution argv=screenresolution list 08:44:01 INFO - Available Modes on Display 0 08:44:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:44:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:44:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:44:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:44:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:44:01 INFO - Intel Iris: 08:44:01 INFO - Chipset Model: Intel Iris 08:44:01 INFO - Type: GPU 08:44:01 INFO - Bus: Built-In 08:44:01 INFO - VRAM (Dynamic, Max): 1536 MB 08:44:01 INFO - Vendor: Intel (0x8086) 08:44:01 INFO - Device ID: 0x0a2e 08:44:01 INFO - Revision ID: 0x0009 08:44:01 INFO - Displays: 08:44:01 INFO - Display: 08:44:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:44:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:44:01 INFO - Display Serial Number: 1600x1200 60 08:44:01 INFO - Main Display: Yes 08:44:01 INFO - Mirror: Off 08:44:01 INFO - Online: Yes 08:44:01 INFO - Rotation: Supported 08:44:01 INFO - Return code: 0 08:44:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:44:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:44:01 INFO - Python 2.7.3 08:44:01 INFO - Return code: 0 08:44:01 INFO - grabbing minidump binary from tooltool 08:44:01 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')]} 08:44:01 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 0x10259ed30>, '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 0x102404db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102596b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:44:01 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 08:44:01 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 08:44:01 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 08:44:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:44:01 INFO - Return code: 0 08:44:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:44:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:44:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:44:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:44:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:44:01 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/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 08:44:01 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/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0350 --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 08:44:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tAHcANkG6q/Render', 08:44:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:01 INFO - 'HOME': '/Users/cltbld', 08:44:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:01 INFO - 'LOGNAME': 'cltbld', 08:44:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:44:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:01 INFO - 'MOZ_NO_REMOTE': '1', 08:44:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:44:01 INFO - 'NO_EM_RESTART': '1', 08:44:01 INFO - 'PAGER': '/bin/cat', 08:44:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:01 INFO - 'PWD': '/builds/slave/test', 08:44:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:44:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:01 INFO - 'SHELL': '/bin/bash', 08:44:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jfsGQXjOpx/Listeners', 08:44:01 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 08:44:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:01 INFO - 'USER': 'cltbld', 08:44:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:01 INFO - 'XPC_FLAGS': '0x0', 08:44:01 INFO - 'XPC_SERVICE_NAME': '0', 08:44:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:01 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/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 08:44:01 INFO - mozversion application_buildid: 20161209062450 08:44:01 INFO - mozversion application_changeset: 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 08:44:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:44:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:44:01 INFO - mozversion application_name: Firefox 08:44:01 INFO - mozversion application_remotingname: firefox-dev 08:44:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:44:01 INFO - mozversion application_vendor: Mozilla 08:44:01 INFO - mozversion application_version: 52.0a2 08:44:01 INFO - mozversion platform_buildid: 20161209062450 08:44:01 INFO - mozversion platform_changeset: 0d52c1ee6bce8b6b8d0b1774ce4b15082e6b4854 08:44:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:44:01 INFO - mozversion platform_version: 52.0a2 08:44:01 INFO - using testdate: 1481301841 08:44:01 INFO - actual date: 1481301841 08:44:01 INFO - starting webserver on 'localhost:49235' 08:44:01 INFO - SUITE-START | Running 2 tests 08:44:01 INFO - TEST-START | dromaeo_css 08:44:01 INFO - operating with platform_type : mac_ 08:44:01 INFO - Initialising browser for dromaeo_css test... 08:44:01 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpHmfjSK/profile http://localhost:49235/getInfo.html) 08:44:04 INFO - PROCESS | 1633 | 2016-12-09 08:44:04.772 plugin-container[1635:7493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 08:44:04 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:04 INFO - PROCESS | 1633 | 2016-12-09 08:44:04.774 plugin-container[1635:7493] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 08:44:04 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:04 INFO - PROCESS | 1633 | 2016-12-09 08:44:04.774 plugin-container[1635:7493] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:04 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 08:44:04 INFO - PROCESS | 1633 | colorDepth:24 08:44:04 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 08:44:04 INFO - PROCESS | 1633 | __metrics 08:44:04 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:44:05 INFO - TEST-INFO | 1633: exit 0 08:44:05 INFO - Browser initialized. 08:44:05 INFO - Running cycle 1/1 for dromaeo_css test... 08:44:05 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpHmfjSK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:44:08 INFO - PROCESS | 1636 | 2016-12-09 08:44:08.565 plugin-container[1637:7620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:44:08 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:08 INFO - PROCESS | 1636 | 2016-12-09 08:44:08.566 plugin-container[1637:7620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 08:44:08 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:44:08 INFO - PROCESS | 1636 | 2016-12-09 08:44:08.566 plugin-container[1637:7620] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:44:18 INFO - PROCESS | 1636 | RSS: Main: 229457920 08:44:18 INFO - PROCESS | 1636 | 08:45:07 INFO - PROCESS | 1636 | 1481301907898 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:47:22 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 08:47:23 INFO - PROCESS | 1636 | RSS: Main: 233910272 08:47:23 INFO - PROCESS | 1636 | 08:50:26 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 08:50:26 INFO - PROCESS | 1636 | RSS: Main: 243400704 08:50:26 INFO - PROCESS | 1636 | 08:53:28 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 08:53:29 INFO - PROCESS | 1636 | RSS: Main: 246312960 08:53:29 INFO - PROCESS | 1636 | 08:56:31 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 08:56:31 INFO - PROCESS | 1636 | RSS: Main: 246521856 08:56:31 INFO - PROCESS | 1636 | 08:59:34 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 08:59:35 INFO - PROCESS | 1636 | RSS: Main: 249962496 08:59:35 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 09:02:38 INFO - PROCESS | 1636 | RSS: Main: 251719680 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | __start_tp_report 09:02:38 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 09:02:38 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 09:02:38 INFO - PROCESS | 1636 | |i|pagename|runs| 09:02:38 INFO - PROCESS | 1636 | |0;dojo.html;2851.8431486268255;3407.710344620707;3440.260344026034;3462.0825481247466;3471.75697701161;5597.132444471104;5615.241942337783;5633.577481688874;5701.315842098954;5708.65748055117;5067.670601410915;5270.552003079733;5729.713514324288;5737.25415695959;5762.510186634131;4027.496562929634;5138.768755405997;5202.349706286724;5287.973560132214;5342.786288548454;5133.948660513403;5209.400918894319;5266.631335806495;5333.306670132897;5346.224797404374;5066.341375621182;5120.615953803443;5135.3324067870935;5209.661372010845;5220.791168353261;5109.565686916617;5127.333446651949;5132.40977287613;5141.485851414866;5222.686638801684;5132.56373208274;5138.152204886189;5189.403218629868;5222.8694282642855;5228.163493841005;5505.889882202334;5567.331920169565;5647.604667673265;5669.829905102853;5726.942730572682;6206.565540412173;6219.564630475868;6237.376262373772;6243.157173781572;6260;6253.062040693887;6262.185915830913;6280.434760871498;6284.057391391282;6295.370462953668;5621.915671264935;5672.971635141798;5679.574031947538;5742.54059675225;5817.38917413674;6182.907256391158;6252.093446450239;6254.74981000764;6256.718447669866;6268.373162683695;6149.877002459926;6158;6162.352952939967;6165.5375846810775;6193.163922870445;6111.8472038198515;6257.9687101564205;6258.687065646791;6268.529860260499;6270.749170033238;6007.549433792396;6056.818295451144;6086;6211.409916057967;6268.059791031291;6104.8836088941825;6126.151269885539;6131.866048757567;6198.566100372931;6249.187605611241;4893.926591101136;4909.533594308535;4931.5315045070665;4934.506549345037;4959.231319145538;4430.025394413224;4432.357308190294;4434.955650443454;4461.13007963444;4468.597826195658;4587.7017993830295;5677.744501497443;5711.657300561979;5744.080947048452;5794.855128621818;6132.936844521141;6139.032198454696;6215.9378406216265;6235.064740288903;6262.718177681924;5787.392323806024;5793.2179155814265;5800.709964501758;5810.331811841692;5816.098504731774;5247.501487358695;5351.170568561879;5383.327084114486;5394.163904594794;5422.213779002022;6251.093591429309;6269.780557680459;6284.151639528605;6294.590851594632;6303.716332765129;6176.9073463897175;6294;6297.748090076346;6297.779577714758;6331.873362532816;6374.553781235269;6385.45723613498;6392.3927226913365;6397.4562162216735;6399.2640846303275;4886.560209581155;5271.78912843482;5578.470045345685;6070.423309785563;6184.5052395809125;4948.307236986897;5214.7914083436235;6140.508759299335;6221.222347206599;6253.343398943228;5697.800576979786;5984.700764961822;5992.850178745566;6023.27720673522;6055.697215139137;5463.48096930791;6001.639901605918;6030.427109424598;6054.273487181566;6081.1486391906055;4696;4731.858044258678;5502.944970550243;5707.029804933254;5864.794732184353;6111.014442317695;6112.381899552462;6112.898653789679;6117.462039585462;6130.459285710702;5908.970455147697;5935.5548333875995;5936;5954.19618351517;5960.493358064613 09:02:38 INFO - PROCESS | 1636 | |1;ext.html;488.4041469407323;516.3184596332842;521.5644936478042;526.807715183958;544.0859356281445;38690.41964370537;40579.79710101449;40600.59399406005;40652.79673601628;40677.98305042372;41586.58413415865;42088.57911420885;42125;42152;42219.78890105553;9420.152186303249;9472.715818525456;9547.093026162504;9563.713088607317;9579.994100619404;9508.334416590842;9523.666671666493;9548.665796697116;9567.23462123032;9606.423614583147;8246.876296855522;9557;9580.473073980927;9652.806943861153;9660.71017869465;9509.239260859114;9528.714138575853;9583.568739406743;9597.424154550748;9605.279604029725;9317.72046838596;9375;9430.151286384189;9456.669016584387;9460.101290377403;39797.60202397968;39827.80516584507;40158;40226;40247.59752402468;3193.3294008258295;3344.515045318415;3491.2668339648703;3728.850845966158;3764.5106136202116;27606.43613831685;29098.70901290981;29200.853995729885;29293.707062929312;30026.099217023526;3142.591463109804;3184.4904815229684;3219.098652377338;3235.773495855291;3236.304194598149;497068;500208;504997;505988;506071;64165.716685666026;65396.34603653903;65672.34327656662;65684;65711;67570;69010.65494672494;69235.65382173158;69324.61350772956;69438;16412.015279083054;16443.671126577403;16619.750703739453;16630.75053874193;16639.33442662304;12652.81020784689;12733.936330318475;12773.489060437665;12786.680332991564;12797;19144.808551914582;20140;20203;20249.898750506152;20262.986850657704;7151.7854464366155;8077.555734434663;8388.958055209807;8392;8410.32717382608;785.6700185921926;835.841190173865;863.5466380150472;864.8054187807693;877.7849426890452;26764.464710706066;28513.71486285152;28982.225857288147;30152.69847301543;30695.38609227803;923.533615524156;985.975350616226;1008.8284991551454;1022.4989755020135;1026.343140390151;13762.380692868845;15399.307031183624;15777.842221577866;15889.920550397173;16127.919360403357;506923.4653826781;686246;687172;687390;688905;57826;57886.42113578894;58535;58630.413695863346;58681;1362.5026865194134;1370.4312710225145;1393.5540626999264;1395.9092658977338;1398.9370478328499;28851.134465966054;29708.702912971457;29828.10515684533;29835.254118647208;30374;51013;51154.721131972;51290.48709513006;51394.22908656448;51404;500265;501904.4904775453;502045;504022;504267;26935.730642694107;27114.593281100406;27139;27543.724562754116;27586.862065689544;45840.54159458363;46085.769571151926;46951;47080.52919470761;47336.76331618457;38462.615373847024;38885.41671874981;39009.80495097506;39963.20073598454;40423;37615.43576846295;38437;38677.41983870188;39689;39738;32022.519662205552;32756;36897.44653830249;37997.43003854889;38405;31733.682663173997;32948.1762955928;34296.48555271623;34706.47940280847;34830.47754283738;25734.485310293316;25738.227853163688;25762.87118564395;26202.475950481265;26279.605805912543 09:02:38 INFO - PROCESS | 1636 | |2;jquery.html;375.09601859518557;378.4247943126446;384.82105820793333;390.7186825485649;403.98990025249367;15269;19163;19305.13126909289;19872.60254794905;20090.89954550229;11574.074074074075;11908.345041022762;11983.340916249603;11986.520539178444;12006.879931200685;4939.53074457926;5233.712145831978;5295.099833028395;5343.893122137555;5355.169948657965;3837.6737977271964;4039.333509970851;5307.469253074681;5323.707196104213;5695.601307908448;5945.227120474332;6068;6075.149479072933;6078.574499784994;6091.725872335734;4675.92986105207;4841.540053694894;5969.761209551612;5975.820725378202;6069.666168360738;5733.684647344395;5744.454276843693;5778.248827652378;5780.653160810365;5796.652200867919;11830.822537661941;12526.74946501065;13030.022748293915;13198.86801131986;14031.298435078308;1638.573970767609;1973.3093417303971;2234.530748542808;2294.3919861236786;2739.58906164075;9574.8031496063;9799.363041402286;9876.45679487628;10113.24150688694;10383.013613706686;1515.8105236845395;1800.288885890066;1816.6911625023777;1831.5878927241397;1842.843358314545;117725.056873578;122530;124186.13720794195;126397;128544;10466.691663542057;10565.577376904992;10942.343459392303;11535.250208736405;11557.88442115568;14703.55889323322;14719.264036798117;14746.852531474762;14775.852241477447;14790.186539740418;8167.550784706898;8364.079951205362;8368.497890126611;8499.702510412106;8545.914540854636;7977.521348719096;7981.281684648409;8013.278804907586;8121.5533145677555;8130.674773009132;3933.822977965941;4458.8629899375455;4466.709663871838;4542.11428771393;4606.56237657422;1567.2085596773557;1691.8392752688476;1890.3761758619623;1937;1943.3101249056665;793.8610743120047;862.2455351567379;872.7774417523618;939.0421769794907;991.6083147156833;19652.606947860964;21064;21241.256556020464;21333.67999480009;21530.569388612454;897.1387468030633;910.4127837544746;931.6290041843524;948.4972964328779;973.454865275448;10990.230683852054;11365;11419.429028548539;11438.714032149097;11466.48400821965;484701.5764921153;574119;583581;597598;611854;113209;115843.42078289723;119536;127500;128385;421.59105667502473;941.1764705882352;996.5714742660725;998.4458625462938;999.480270259461;10706.946465267623;10935.781284374109;11016.889831101586;11185.608503702331;11495.827562586735;53775;54011;54537.1819422701;54538.45461545334;55789.7210513961;58162;69134.27164320933;74309;78047;78920.60539697265;5141.974290128525;5440.401555828935;7602.619869006417;7926.524408535506;8196.590170491332;7572.8485430292185;11310.20828542027;12746.171498852917;12768.61694149177;13011.934940325238;9057.77355566116;9138.543072846198;10919.890801091888;11037.11703063769;11439.856014398827;10803.405812680377;11029.889701103208;11035.227534072537;11109.777804443704;11175.050120739828;11246.550137994716;11250;11482;11610.129240306842;11663.941680291544;8208.58957052157;8313.875291870505;8335.874961875697;8391.538465384217;8395.664173432995;8016.83966320682;10739.838902416313;11029.172812038969;11193.944030280123;11477.598284060017 09:02:38 INFO - PROCESS | 1636 | |3;mootools.html;1400.9859901400987;1435.5191011011311;1622.5499495761403;1691.42491552872;1753.2461041752058;7499.625018749068;7746.457747957631;8084.1107478177355;8529.317654587641;8565.186307300804;6524.902126468107;8247.21651443112;8267.503949763004;8422.663093476269;8434.283085937703;2288.4050146961786;2305.2738387408;2333.265021518216;2338.4270853640883;2345.2846881701075;2332.731735850382;2350.459394339303;2367.8105751539833;2368.644703294511;2380.4048987753063;2303.124812571229;2316.9652455213186;2328.37133973827;2333.7782910623464;2334.6848175496343;2287.8970446329954;2289.404754763765;2315.6989591353017;2321.5356928614174;2321.570509455756;2328.8253380996325;2343.5664402085667;2347.624380099193;2352.1297120065515;2356.0340260493285;8371.874421883676;8409.537475438849;8468.915310846874;8469.3647976402;8470.534120623362;5046.873828154289;5209.505097015777;5220.712860792655;5241.0042092002395;5258.316418865523;3217.6299725531394;3370.2753907909673;3484.5121682964404;3574.928501429983;3585.4621806728937;901.5041727050162;1042.4110377636612;1046.4988247234173;1081.1999120650742;1096.9890301096966;10869.891301087046;10907.563697452093;10977.670669879917;11006.449677516091;11010.614628487965;4524.6380289576755;4650.093231819763;4745.979614382924;4751.144793937125;4754.168020596451;9057.320700947455;9501.239900808057;9547.761305967406;9554.426734395958;9569.473678947585;5188.429272779991;5191.402988656353;5191.870203244949;5198.9740051299505;5199.66202196856;4206.579342065769;4217.34631132175;4704.1767690654;4713.175194340976;4732.929006064912;3773.2642134783455;3815.561210460777;3863.4011728182177;3896;3932.5870783567884;1065.8116200436612;1246.993765031169;1273.2615083251692;1276.0429677741695;1289.5808862119848;845.6363763581596;875.5622188905547;891.9910800892038;914.2548822709471;921.870938068671;5765.740541675635;5801.022835394825;5824.970875145597;5848.561357898175;5982.641041537521;452.671812935619;455.3670398146579;457.34599522682885;965.1844191658038;985.4481490365418;3273.9181520461702;3654.397024490929;3789.1853251551047;3843;3852.24881148179;10005.19958403326;10360.430176340373;10361.533730982124;10367;10369.688909332732;8284.8343033139;10189.082982531607;10291.43397113151;10317.019883111092;10340.27618066728;6529.738810447624;6615.305392933769;7542.245775422414;7882.290593846581;7990.960045199853;5025.698458092526;5465.617406781487;5572.582056345709;5791.160281759037;5831.7667293307795;9182.219511341611;9395.48324842113;9400.294977876823;9556.092171243721;9556.139947404767;9620.518974051409;9680.128788409076;9711.174550163316;9753.60985560598;9753.707388778348;6249.312575616769;6436.163298771317;6547.1161393211305;6722.092517510269;6877.277885821916;7725.343345815444;7998;8002.119766825528;8003.5197888126895;8010.118886922551;7087.787366379017;7241.022461967777;7245.166805817398;7275.854482910207;7334.743283985035;6999.370056694922;7059.5764254144915;7069.081019467436;7188.820279493055;7190.784276471714;6651.800445986628;6653.2016158059405;6725.226598941184;6730.293319201413;6756.966215168893;4512.503624601357;4887.731174785421;4918.852434426973;4949.6287778416045;4967.7019378837385;4182.853600123981;4762.404699412574;4846.248831431134;4861.781219845187;4971.9005619886675 09:02:38 INFO - PROCESS | 1636 | |4;prototype.html;301.1117204247469;301.9924501887453;304.57207623289287;308.87027448471633;312.8138757439323;4467.374567560536;5316;5499.890002199953;5513.476219759126;5522.889542209154;5196.454372290902;5298.496642818926;5336.706481143536;5354.651947623402;5377.516023557879;3490.354284457371;3583.8208089595546;4037.132016616427;4149.75507347796;4195.244855925932;3474.270403215328;3680.7117508871816;3837.4052021936504;4095.856645017425;4104.91790164198;4007.5190977082643;4092.976755811047;4108.096218831853;4120.237756015132;4128.091819799639;4061.512618485771;4081.2449696806184;4097.89755256118;4119.958800411988;4124.608162224584;3124.7343975762087;3606.1165014571598;3610.711143108545;3621.8551257949653;3626.1841085755495;4087.509498860126;5102.953894451665;5225.973870130625;5228.346456692913;5292.2326262691695;4134.297169481196;4637.744924029177;4735.763211839394;4742.241241401393;4754.714717116949;5152.6650767699975;5477.507024367826;5676.927921308734;5695.259616249902;5702.287214098238;3068.892588759405;3546.6098729139776;3755.2301778135416;3805.5324376808157;3998.6001399860074;5072.72100034498;5340.839774806762;5972.9701351493395;5981.8803623927715;6008.849778755566;4017.3572228443895;4033.979830100831;4039.929418704046;4051.1290072634524;4062.979685101556;3095.127447894037;3947.2697550953167;4090.570490098556;4096.73371230869;4101.384792281182;4052.777097259679;4346.673999450054;4700.529947005272;4709.505501922249;4734.621230301567;5061.974690126526;5771.59598828078;5792.710364481759;5801.419858014165;5814.186013958049;1087.5649740104022;1148.8563929508812;1223.1804690857148;1254.5044707340548;1306.784380577194;858.9392100755562;865.0830120072743;878.9912100879037;887.1306120002305;894.4499133033268;4613.354130421743;5081.974590127025;5260.316158899319;5286.97356513215;5336.946630533723;5238.97380513095;5623.578231632562;5632.662040277596;5656.236408084938;5766.567507436959;4637.721736695809;4658.650601204923;5115.539601435814;5178.4625533488315;5222.4255331913455;2628.0670362021588;2639.9076032338776;2713.503576709432;2739.383638681281;2976.9310329320915;4836.951630483721;5119.13151699627;5426.701531415731;5649.802256920988;5780.416141991992;4823.3729615150505;5345.278387417648;5523.309586301712;5681.829545113653;5793.7392817323325;4685.4143232095985;5311.628186027018;5361.906189120351;5504.972475137599;5847.356790752929;4705.482396936266;5091.92362114576;5970.253718285215;6029.78895738647;6053.03526190346;5161.612879034013;5353.046138159466;5678.864227154668;5709.885802283848;5996.8800623988145;5442.673439593637;5988.700564971647;6321.399467050623;6338.366163383625;6358.586691865014;3934.586868378779;3985.860494882665;3995.700322475884;3996.6802655786887;4004.5594984551094;5474.945250547603;5560.833175004756;5903.793367232126;6016.398360163948;6049.546284028627;4937.901541720292;5075.54320111177;5387;5552.833414997556;5622.85942851432;4239.766812825201;4849.878753031202;4892.85018020772;5170.353705786777;5494;3994.861464482725;4366.628836548929;5453.072977593898;5616.382197958303;5776.476349426763;5798.478136967693;5802.738876750641;5804.955108080586;5811.79658711943;5831.212786273799;4251.617354438115;4505.864824055284;4781.4740378557635;5304.310439642822;5718.285214348207 09:02:38 INFO - PROCESS | 1636 | |5;yui.html;661.348571656918;683.6103421050001;740.1007775552703;766.8650397411831;778.711876605656;13656.180629162245;14353.712925741478;14373.928130359347;14388.136711797299;14415.927920360384;13085.542006029791;14002.29988500576;14607.92696036521;14768.556943291691;14948.775768363483;642.4988508963016;806.2018601584433;828.3042244514606;829.1045670675655;829.999850180534;661.9801405957805;793.8729803231485;814.1207495904439;815.233680340481;815.7226542975387;856.1951765340591;857.9742607721778;858.9742307730778;860.9009963854174;861.2248975921658;843.6583183810567;855.1192271959891;855.5935930432995;856.6402111113316;857.4126723194569;633.5438484291303;633.8700566383926;634.3402861024529;634.6763150793082;635.1679300116815;14034.92982535091;14160.716785664228;14196.219207943559;14198.787018194735;14199.503017394445;10928.617498387597;12969.546065887744;13129.86870131296;13247.867521324759;13320.866791332157;10916.29044112138;11021.83467247992;11291.54833806655;11307.604233851776;11456.369899655516;3057.419090372822;3645.9453108203134;3659.7621154624862;3665.633436656313;3714.3499887519574;89503.10496895012;89918.10081899229;90107.54946225292;91215.08784912132;91340;10219.284650074573;10354.53404596795;10399.584016639401;10411.53148108337;10442.112420444195;20021.29925452602;20145.59708805845;20148.899255503628;20341.084651190733;20362.389128326173;7411.5553066816165;7415.555066696015;7416.555006699507;7488.663010164447;7565.978592889999;4059.7361171523758;4233.809478573472;4248.362745588187;4307.892302692395;4316.589923957219;3305.504174373863;3410.5736782902136;3412.6757957994446;3419.093940105874;3420.435628121382;638.288308535979;675.5406323699931;679.4835924697265;692.2696555134281;696.585531608692;562.2044806598699;609.9176611157437;630.5082036011827;649.4544582550681;663.8207683925409;8145.263314563564;8907.955460222787;9088.273228086642;9104.908950910538;9112;642.5598465051455;651.3031056769212;658.2621878241447;671.9966400167967;676.7326905872153;3598.208394153282;4159.708820382544;4194.266003449384;4489.7979590918485;4532.138893610204;88467;94789;106919;108192.4590377043;111621;96225;98513.50743246239;99816.5009174964;100773;103841.48079259555;62704.74590508164;68914.65542672254;84460.57769711236;85872.71190932141;88515;6821.590704557742;8333.95833020831;8677.26243269304;8687.739367818975;8711.433756805787;50067.74966125292;54447;55148;55745.721271393384;57018.71490642686;63099.6845015772;72063.27936720775;76126;77894.22105779096;79525.20474795409;8644.78388040304;11555;11593.6521904343;11704.239224450383;12263.202891812003;12745.10784245094;13081.607551773463;13236;13477.595672129852;14312;12905.612831615066;13125.737485250433;13464.394102265225;13790.172589644653;13895.166290022631;10828.675139745821;12778.424970876518;13099.078054720387;13233.470661173831;13767.793483097961;10396.740081498023;11958.222715523463;12298.569550065704;12863;12918.354082296035;10512.119955845663;11052.889471105185;11087;11132.721681958015;11604;9832.213422926006;11344.602938897098;11470.311781293149;11470.369129697947;11507.769844603228 09:02:38 INFO - PROCESS | 1636 | __end_tp_report 09:02:38 INFO - PROCESS | 1636 | __start_cc_report 09:02:38 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,360 09:02:38 INFO - PROCESS | 1636 | __end_cc_report 09:02:38 INFO - PROCESS | 1636 | __startTimestamp1481302958278__endTimestamp 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | ------- Summary: start ------- 09:02:38 INFO - PROCESS | 1636 | Number of tests: 6 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5659.64 Median:5800.71 stddev:670.60 (11.6%) stddev-sans-first:635.45 09:02:38 INFO - PROCESS | 1636 | Values: 2851.8 3407.7 3440.3 3462.1 3471.8 5597.1 5615.2 5633.6 5701.3 5708.7 5067.7 5270.6 5729.7 5737.3 5762.5 4027.5 5138.8 5202.3 5288.0 5342.8 5133.9 5209.4 5266.6 5333.3 5346.2 5066.3 5120.6 5135.3 5209.7 5220.8 5109.6 5127.3 5132.4 5141.5 5222.7 5132.6 5138.2 5189.4 5222.9 5228.2 5505.9 5567.3 5647.6 5669.8 5726.9 6206.6 6219.6 6237.4 6243.2 6260.0 6253.1 6262.2 6280.4 6284.1 6295.4 5621.9 5673.0 5679.6 5742.5 5817.4 6182.9 6252.1 6254.7 6256.7 6268.4 6149.9 6158.0 6162.4 6165.5 6193.2 6111.8 6258.0 6258.7 6268.5 6270.7 6007.5 6056.8 6086.0 6211.4 6268.1 6104.9 6126.2 6131.9 6198.6 6249.2 4893.9 4909.5 4931.5 4934.5 4959.2 4430.0 4432.4 4435.0 4461.1 4468.6 4587.7 5677.7 5711.7 5744.1 5794.9 6132.9 6139.0 6215.9 6235.1 6262.7 5787.4 5793.2 5800.7 5810.3 5816.1 5247.5 5351.2 5383.3 5394.2 5422.2 6251.1 6269.8 6284.2 6294.6 6303.7 6176.9 6294.0 6297.7 6297.8 6331.9 6374.6 6385.5 6392.4 6397.5 6399.3 4886.6 5271.8 5578.5 6070.4 6184.5 4948.3 5214.8 6140.5 6221.2 6253.3 5697.8 5984.7 5992.9 6023.3 6055.7 5463.5 6001.6 6030.4 6054.3 6081.1 4696.0 4731.9 5502.9 5707.0 5864.8 6111.0 6112.4 6112.9 6117.5 6130.5 5909.0 5935.6 5936.0 5954.2 5960.5 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:69127.26 Median:40626.70 stddev:149121.89 (367.1%) stddev-sans-first:149451.17 09:02:38 INFO - PROCESS | 1636 | Values: 488.4 516.3 521.6 526.8 544.1 38690.4 40579.8 40600.6 40652.8 40678.0 41586.6 42088.6 42125.0 42152.0 42219.8 9420.2 9472.7 9547.1 9563.7 9580.0 9508.3 9523.7 9548.7 9567.2 9606.4 8246.9 9557.0 9580.5 9652.8 9660.7 9509.2 9528.7 9583.6 9597.4 9605.3 9317.7 9375.0 9430.2 9456.7 9460.1 39797.6 39827.8 40158.0 40226.0 40247.6 3193.3 3344.5 3491.3 3728.9 3764.5 27606.4 29098.7 29200.9 29293.7 30026.1 3142.6 3184.5 3219.1 3235.8 3236.3 497068.0 500208.0 504997.0 505988.0 506071.0 64165.7 65396.3 65672.3 65684.0 65711.0 67570.0 69010.7 69235.7 69324.6 69438.0 16412.0 16443.7 16619.8 16630.8 16639.3 12652.8 12733.9 12773.5 12786.7 12797.0 19144.8 20140.0 20203.0 20249.9 20263.0 7151.8 8077.6 8389.0 8392.0 8410.3 785.7 835.8 863.5 864.8 877.8 26764.5 28513.7 28982.2 30152.7 30695.4 923.5 986.0 1008.8 1022.5 1026.3 13762.4 15399.3 15777.8 15889.9 16127.9 506923.5 686246.0 687172.0 687390.0 688905.0 57826.0 57886.4 58535.0 58630.4 58681.0 1362.5 1370.4 1393.6 1395.9 1398.9 28851.1 29708.7 29828.1 29835.3 30374.0 51013.0 51154.7 51290.5 51394.2 51404.0 500265.0 501904.5 502045.0 504022.0 504267.0 26935.7 27114.6 27139.0 27543.7 27586.9 45840.5 46085.8 46951.0 47080.5 47336.8 38462.6 38885.4 39009.8 39963.2 40423.0 37615.4 38437.0 38677.4 39689.0 39738.0 32022.5 32756.0 36897.4 37997.4 38405.0 31733.7 32948.2 34296.5 34706.5 34830.5 25734.5 25738.2 25762.9 26202.5 26279.6 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:33084.59 Median:2111.25 stddev:95855.61 (4540.2%) stddev-sans-first:96093.05 09:02:38 INFO - PROCESS | 1636 | Values: 375.1 378.4 384.8 390.7 404.0 15269.0 19163.0 19305.1 19872.6 20090.9 11574.1 11908.3 11983.3 11986.5 12006.9 4939.5 5233.7 5295.1 5343.9 5355.2 3837.7 4039.3 5307.5 5323.7 5695.6 5945.2 6068.0 6075.1 6078.6 6091.7 4675.9 4841.5 5969.8 5975.8 6069.7 5733.7 5744.5 5778.2 5780.7 5796.7 11830.8 12526.7 13030.0 13198.9 14031.3 1638.6 1973.3 2234.5 2294.4 2739.6 9574.8 9799.4 9876.5 10113.2 10383.0 1515.8 1800.3 1816.7 1831.6 1842.8 117725.1 122530.0 124186.1 126397.0 128544.0 10466.7 10565.6 10942.3 11535.3 11557.9 14703.6 14719.3 14746.9 14775.9 14790.2 8167.6 8364.1 8368.5 8499.7 8545.9 7977.5 7981.3 8013.3 8121.6 8130.7 3933.8 4458.9 4466.7 4542.1 4606.6 1567.2 1691.8 1890.4 1937.0 1943.3 793.9 862.2 872.8 939.0 991.6 19652.6 21064.0 21241.3 21333.7 21530.6 897.1 910.4 931.6 948.5 973.5 10990.2 11365.0 11419.4 11438.7 11466.5 484701.6 574119.0 583581.0 597598.0 611854.0 113209.0 115843.4 119536.0 127500.0 128385.0 421.6 941.2 996.6 998.4 999.5 10706.9 10935.8 11016.9 11185.6 11495.8 53775.0 54011.0 54537.2 54538.5 55789.7 58162.0 69134.3 74309.0 78047.0 78920.6 5142.0 5440.4 7602.6 7926.5 8196.6 7572.8 11310.2 12746.2 12768.6 13011.9 9057.8 9138.5 10919.9 11037.1 11439.9 10803.4 11029.9 11035.2 11109.8 11175.1 11246.6 11250.0 11482.0 11610.1 11663.9 8208.6 8313.9 8335.9 8391.5 8395.7 8016.8 10739.8 11029.2 11193.9 11477.6 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5434.87 Median:4998.80 stddev:3004.65 (60.1%) stddev-sans-first:2997.78 09:02:38 INFO - PROCESS | 1636 | Values: 1401.0 1435.5 1622.5 1691.4 1753.2 7499.6 7746.5 8084.1 8529.3 8565.2 6524.9 8247.2 8267.5 8422.7 8434.3 2288.4 2305.3 2333.3 2338.4 2345.3 2332.7 2350.5 2367.8 2368.6 2380.4 2303.1 2317.0 2328.4 2333.8 2334.7 2287.9 2289.4 2315.7 2321.5 2321.6 2328.8 2343.6 2347.6 2352.1 2356.0 8371.9 8409.5 8468.9 8469.4 8470.5 5046.9 5209.5 5220.7 5241.0 5258.3 3217.6 3370.3 3484.5 3574.9 3585.5 901.5 1042.4 1046.5 1081.2 1097.0 10869.9 10907.6 10977.7 11006.4 11010.6 4524.6 4650.1 4746.0 4751.1 4754.2 9057.3 9501.2 9547.8 9554.4 9569.5 5188.4 5191.4 5191.9 5199.0 5199.7 4206.6 4217.3 4704.2 4713.2 4732.9 3773.3 3815.6 3863.4 3896.0 3932.6 1065.8 1247.0 1273.3 1276.0 1289.6 845.6 875.6 892.0 914.3 921.9 5765.7 5801.0 5825.0 5848.6 5982.6 452.7 455.4 457.3 965.2 985.4 3273.9 3654.4 3789.2 3843.0 3852.2 10005.2 10360.4 10361.5 10367.0 10369.7 8284.8 10189.1 10291.4 10317.0 10340.3 6529.7 6615.3 7542.2 7882.3 7991.0 5025.7 5465.6 5572.6 5791.2 5831.8 9182.2 9395.5 9400.3 9556.1 9556.1 9620.5 9680.1 9711.2 9753.6 9753.7 6249.3 6436.2 6547.1 6722.1 6877.3 7725.3 7998.0 8002.1 8003.5 8010.1 7087.8 7241.0 7245.2 7275.9 7334.7 6999.4 7059.6 7069.1 7188.8 7190.8 6651.8 6653.2 6725.2 6730.3 6757.0 4512.5 4887.7 4918.9 4949.6 4967.7 4182.9 4762.4 4846.2 4861.8 4971.9 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4513.25 Median:5078.76 stddev:1405.86 (27.7%) stddev-sans-first:1373.80 09:02:38 INFO - PROCESS | 1636 | Values: 301.1 302.0 304.6 308.9 312.8 4467.4 5316.0 5499.9 5513.5 5522.9 5196.5 5298.5 5336.7 5354.7 5377.5 3490.4 3583.8 4037.1 4149.8 4195.2 3474.3 3680.7 3837.4 4095.9 4104.9 4007.5 4093.0 4108.1 4120.2 4128.1 4061.5 4081.2 4097.9 4120.0 4124.6 3124.7 3606.1 3610.7 3621.9 3626.2 4087.5 5103.0 5226.0 5228.3 5292.2 4134.3 4637.7 4735.8 4742.2 4754.7 5152.7 5477.5 5676.9 5695.3 5702.3 3068.9 3546.6 3755.2 3805.5 3998.6 5072.7 5340.8 5973.0 5981.9 6008.8 4017.4 4034.0 4039.9 4051.1 4063.0 3095.1 3947.3 4090.6 4096.7 4101.4 4052.8 4346.7 4700.5 4709.5 4734.6 5062.0 5771.6 5792.7 5801.4 5814.2 1087.6 1148.9 1223.2 1254.5 1306.8 858.9 865.1 879.0 887.1 894.4 4613.4 5082.0 5260.3 5287.0 5336.9 5239.0 5623.6 5632.7 5656.2 5766.6 4637.7 4658.7 5115.5 5178.5 5222.4 2628.1 2639.9 2713.5 2739.4 2976.9 4837.0 5119.1 5426.7 5649.8 5780.4 4823.4 5345.3 5523.3 5681.8 5793.7 4685.4 5311.6 5361.9 5505.0 5847.4 4705.5 5091.9 5970.3 6029.8 6053.0 5161.6 5353.0 5678.9 5709.9 5996.9 5442.7 5988.7 6321.4 6338.4 6358.6 3934.6 3985.9 3995.7 3996.7 4004.6 5474.9 5560.8 5903.8 6016.4 6049.5 4937.9 5075.5 5387.0 5552.8 5622.9 4239.8 4849.9 4892.9 5170.4 5494.0 3994.9 4366.6 5453.1 5616.4 5776.5 5798.5 5802.7 5805.0 5811.8 5831.2 4251.6 4505.9 4781.5 5304.3 5718.3 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20202.03 Median:4403.19 stddev:29574.07 (671.7%) stddev-sans-first:29620.64 09:02:38 INFO - PROCESS | 1636 | Values: 661.3 683.6 740.1 766.9 778.7 13656.2 14353.7 14373.9 14388.1 14415.9 13085.5 14002.3 14607.9 14768.6 14948.8 642.5 806.2 828.3 829.1 830.0 662.0 793.9 814.1 815.2 815.7 856.2 858.0 859.0 860.9 861.2 843.7 855.1 855.6 856.6 857.4 633.5 633.9 634.3 634.7 635.2 14034.9 14160.7 14196.2 14198.8 14199.5 10928.6 12969.5 13129.9 13247.9 13320.9 10916.3 11021.8 11291.5 11307.6 11456.4 3057.4 3645.9 3659.8 3665.6 3714.3 89503.1 89918.1 90107.5 91215.1 91340.0 10219.3 10354.5 10399.6 10411.5 10442.1 20021.3 20145.6 20148.9 20341.1 20362.4 7411.6 7415.6 7416.6 7488.7 7566.0 4059.7 4233.8 4248.4 4307.9 4316.6 3305.5 3410.6 3412.7 3419.1 3420.4 638.3 675.5 679.5 692.3 696.6 562.2 609.9 630.5 649.5 663.8 8145.3 8908.0 9088.3 9104.9 9112.0 642.6 651.3 658.3 672.0 676.7 3598.2 4159.7 4194.3 4489.8 4532.1 88467.0 94789.0 106919.0 108192.5 111621.0 96225.0 98513.5 99816.5 100773.0 103841.5 62704.7 68914.7 84460.6 85872.7 88515.0 6821.6 8334.0 8677.3 8687.7 8711.4 50067.7 54447.0 55148.0 55745.7 57018.7 63099.7 72063.3 76126.0 77894.2 79525.2 8644.8 11555.0 11593.7 11704.2 12263.2 12745.1 13081.6 13236.0 13477.6 14312.0 12905.6 13125.7 13464.4 13790.2 13895.2 10828.7 12778.4 13099.1 13233.5 13767.8 10396.7 11958.2 12298.6 12863.0 12918.4 10512.1 11052.9 11087.0 11132.7 11604.0 9832.2 11344.6 11470.3 11470.4 11507.8 09:02:38 INFO - PROCESS | 1636 | -------- Summary: end -------- 09:02:38 INFO - PROCESS | 1636 | 09:02:38 INFO - TEST-INFO | 1636: exit 0 09:02:38 INFO - TEST-OK | dromaeo_css | took 1117204ms 09:02:38 INFO - TEST-START | kraken 09:02:38 INFO - operating with platform_type : mac_ 09:02:38 INFO - Initialising browser for kraken test... 09:02:38 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpZ3D8J1/profile http://localhost:49235/getInfo.html) 09:02:41 INFO - PROCESS | 1648 | 2016-12-09 09:02:41.800 plugin-container[1649:10037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 09:02:41 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:41 INFO - PROCESS | 1648 | 2016-12-09 09:02:41.803 plugin-container[1649:10037] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 09:02:41 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:41 INFO - PROCESS | 1648 | 2016-12-09 09:02:41.803 plugin-container[1649:10037] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:02:41 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 09:02:41 INFO - PROCESS | 1648 | colorDepth:24 09:02:41 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 09:02:41 INFO - PROCESS | 1648 | __metrics 09:02:41 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:02:42 INFO - TEST-INFO | 1648: exit 0 09:02:42 INFO - Browser initialized. 09:02:42 INFO - Running cycle 1/1 for kraken test... 09:02:42 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpZ3D8J1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:02:45 INFO - PROCESS | 1650 | 2016-12-09 09:02:45.593 plugin-container[1651:10166] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 09:02:45 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:45 INFO - PROCESS | 1650 | 2016-12-09 09:02:45.594 plugin-container[1651:10166] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 09:02:45 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:02:45 INFO - PROCESS | 1650 | 2016-12-09 09:02:45.594 plugin-container[1651:10166] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:02:55 INFO - PROCESS | 1650 | RSS: Main: 229482496 09:02:55 INFO - PROCESS | 1650 | 09:02:58 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 09:02:59 INFO - PROCESS | 1650 | RSS: Main: 241602560 09:02:59 INFO - PROCESS | 1650 | 09:03:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 09:03:02 INFO - PROCESS | 1650 | RSS: Main: 249233408 09:03:02 INFO - PROCESS | 1650 | 09:03:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 09:03:06 INFO - PROCESS | 1650 | RSS: Main: 252837888 09:03:06 INFO - PROCESS | 1650 | 09:03:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 09:03:09 INFO - PROCESS | 1650 | RSS: Main: 254328832 09:03:09 INFO - PROCESS | 1650 | 09:03:11 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:03:11 INFO - PROCESS | 1650 | RSS: Main: 254550016 09:03:11 INFO - PROCESS | 1650 | 09:03:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 09:03:15 INFO - PROCESS | 1650 | RSS: Main: 259112960 09:03:15 INFO - PROCESS | 1650 | 09:03:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 09:03:19 INFO - PROCESS | 1650 | RSS: Main: 258101248 09:03:19 INFO - PROCESS | 1650 | 09:03:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 09:03:22 INFO - PROCESS | 1650 | RSS: Main: 258199552 09:03:22 INFO - PROCESS | 1650 | 09:03:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:03:24 INFO - PROCESS | 1650 | RSS: Main: 257368064 09:03:24 INFO - PROCESS | 1650 | 09:03:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:03:27 INFO - PROCESS | 1650 | RSS: Main: 257376256 09:03:27 INFO - PROCESS | 1650 | 09:03:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:03:30 INFO - PROCESS | 1650 | RSS: Main: 257482752 09:03:30 INFO - PROCESS | 1650 | 09:03:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:03:34 INFO - PROCESS | 1650 | RSS: Main: 257839104 09:03:34 INFO - PROCESS | 1650 | 09:03:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:03:37 INFO - PROCESS | 1650 | RSS: Main: 257585152 09:03:37 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 09:03:39 INFO - PROCESS | 1650 | RSS: Main: 259182592 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | __start_tp_report 09:03:39 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 09:03:39 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 09:03:39 INFO - PROCESS | 1650 | |i|pagename|runs| 09:03:39 INFO - PROCESS | 1650 | |0;ai-astar;138;176;168;119;120;102;151;173;172;117 09:03:39 INFO - PROCESS | 1650 | |1;audio-beat-detection;155;119;116;151;119;117;117;121;146;120 09:03:39 INFO - PROCESS | 1650 | |2;audio-dft;197;201;207;278;198;196;198;198;198;199 09:03:39 INFO - PROCESS | 1650 | |3;audio-fft;82;59;59;89;61;62;60;62;99;50 09:03:39 INFO - PROCESS | 1650 | |4;audio-oscillator;89;58;115;59;73;91;122;88;96;123 09:03:39 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;79;132;135;102;88;86;90;90;93;117 09:03:39 INFO - PROCESS | 1650 | |6;imaging-darkroom;97;96;97;96;98;97;96;97;128;107 09:03:39 INFO - PROCESS | 1650 | |7;imaging-desaturate;94;125;85;86;88;81;131;87;135;135 09:03:39 INFO - PROCESS | 1650 | |8;json-parse-financial;57;59;57;60;62;60;62;61;60;62 09:03:39 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;80;61;74;107;117;113;107;72;73;74 09:03:39 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;86;82;86;87;86;88;84;89;88;85 09:03:39 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;148;156;163;148;155;185;165;157;210;152 09:03:39 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;172;175;169;168;172;171;165;173;172;169 09:03:39 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;70;69;68;70;72;68;69;69;69;71 09:03:39 INFO - PROCESS | 1650 | __end_tp_report 09:03:39 INFO - PROCESS | 1650 | __start_cc_report 09:03:39 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,995 09:03:39 INFO - PROCESS | 1650 | __end_cc_report 09:03:39 INFO - PROCESS | 1650 | __startTimestamp1481303019660__endTimestamp 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | ------- Summary: start ------- 09:03:39 INFO - PROCESS | 1650 | Number of tests: 14 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:143.60 Median:159.50 stddev:27.89 (17.5%) stddev-sans-first:29.51 09:03:39 INFO - PROCESS | 1650 | Values: 138.0 176.0 168.0 119.0 120.0 102.0 151.0 173.0 172.0 117.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:128.10 Median:120.50 stddev:15.79 (13.1%) stddev-sans-first:13.41 09:03:39 INFO - PROCESS | 1650 | Values: 155.0 119.0 116.0 151.0 119.0 117.0 117.0 121.0 146.0 120.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:207.00 Median:198.50 stddev:25.14 (12.7%) stddev-sans-first:26.40 09:03:39 INFO - PROCESS | 1650 | Values: 197.0 201.0 207.0 278.0 198.0 196.0 198.0 198.0 198.0 199.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:68.30 Median:62.00 stddev:15.87 (25.6%) stddev-sans-first:16.05 09:03:39 INFO - PROCESS | 1650 | Values: 82.0 59.0 59.0 89.0 61.0 62.0 60.0 62.0 99.0 50.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:91.40 Median:80.50 stddev:23.65 (29.4%) stddev-sans-first:25.07 09:03:39 INFO - PROCESS | 1650 | Values: 89.0 58.0 115.0 59.0 73.0 91.0 122.0 88.0 96.0 123.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:87.00 stddev:19.88 (22.9%) stddev-sans-first:19.40 09:03:39 INFO - PROCESS | 1650 | Values: 79.0 132.0 135.0 102.0 88.0 86.0 90.0 90.0 93.0 117.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:100.90 Median:97.00 stddev:10.07 (10.4%) stddev-sans-first:10.58 09:03:39 INFO - PROCESS | 1650 | Values: 97.0 96.0 97.0 96.0 98.0 97.0 96.0 97.0 128.0 107.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:104.70 Median:85.50 stddev:23.44 (27.4%) stddev-sans-first:24.54 09:03:39 INFO - PROCESS | 1650 | Values: 94.0 125.0 85.0 86.0 88.0 81.0 131.0 87.0 135.0 135.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:60.50 stddev:1.89 (3.1%) stddev-sans-first:1.66 09:03:39 INFO - PROCESS | 1650 | Values: 57.0 59.0 57.0 60.0 62.0 60.0 62.0 61.0 60.0 62.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:87.80 Median:72.50 stddev:20.69 (28.5%) stddev-sans-first:21.75 09:03:39 INFO - PROCESS | 1650 | Values: 80.0 61.0 74.0 107.0 117.0 113.0 107.0 72.0 73.0 74.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.50 stddev:2.08 (2.4%) stddev-sans-first:2.20 09:03:39 INFO - PROCESS | 1650 | Values: 86.0 82.0 86.0 87.0 86.0 88.0 84.0 89.0 88.0 85.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:163.90 Median:160.00 stddev:19.46 (12.2%) stddev-sans-first:19.77 09:03:39 INFO - PROCESS | 1650 | Values: 148.0 156.0 163.0 148.0 155.0 185.0 165.0 157.0 210.0 152.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.60 Median:172.00 stddev:2.88 (1.7%) stddev-sans-first:3.00 09:03:39 INFO - PROCESS | 1650 | Values: 172.0 175.0 169.0 168.0 172.0 171.0 165.0 173.0 172.0 169.0 09:03:39 INFO - PROCESS | 1650 | 09:03:39 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.50 stddev:1.27 (1.8%) stddev-sans-first:1.33 09:03:39 INFO - PROCESS | 1650 | Values: 70.0 69.0 68.0 70.0 72.0 68.0 69.0 69.0 69.0 71.0 09:03:39 INFO - PROCESS | 1650 | -------- Summary: end -------- 09:03:39 INFO - PROCESS | 1650 | 09:03:40 INFO - TEST-INFO | 1650: exit 0 09:03:40 INFO - TEST-OK | kraken | took 61789ms 09:03:40 INFO - SUITE-END | took 1178s 09:03:40 INFO - Completed test suite (00:19:39) 09:03:40 INFO - javascript benchmark 09:03:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7078.75022096319, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2851.8431486268255, 3407.710344620707, 3440.260344026034, 3462.0825481247466, 3471.75697701161, 5597.132444471104, 5615.241942337783, 5633.577481688874, 5701.315842098954, 5708.65748055117, 5067.670601410915, 5270.552003079733, 5729.713514324288, 5737.25415695959, 5762.510186634131, 4027.496562929634, 5138.768755405997, 5202.349706286724, 5287.973560132214, 5342.786288548454, 5133.948660513403, 5209.400918894319, 5266.631335806495, 5333.306670132897, 5346.224797404374, 5066.341375621182, 5120.615953803443, 5135.3324067870935, 5209.661372010845, 5220.791168353261, 5109.565686916617, 5127.333446651949, 5132.40977287613, 5141.485851414866, 5222.686638801684, 5132.56373208274, 5138.152204886189, 5189.403218629868, 5222.8694282642855, 5228.163493841005, 5505.889882202334, 5567.331920169565, 5647.604667673265, 5669.829905102853, 5726.942730572682, 6206.565540412173, 6219.564630475868, 6237.376262373772, 6243.157173781572, 6260.0, 6253.062040693887, 6262.185915830913, 6280.434760871498, 6284.057391391282, 6295.370462953668, 5621.915671264935, 5672.971635141798, 5679.574031947538, 5742.54059675225, 5817.38917413674, 6182.907256391158, 6252.093446450239, 6254.74981000764, 6256.718447669866, 6268.373162683695, 6149.877002459926, 6158.0, 6162.352952939967, 6165.5375846810775, 6193.163922870445, 6111.8472038198515, 6257.9687101564205, 6258.687065646791, 6268.529860260499, 6270.749170033238, 6007.549433792396, 6056.818295451144, 6086.0, 6211.409916057967, 6268.059791031291, 6104.8836088941825, 6126.151269885539, 6131.866048757567, 6198.566100372931, 6249.187605611241, 4893.926591101136, 4909.533594308535, 4931.5315045070665, 4934.506549345037, 4959.231319145538, 4430.025394413224, 4432.357308190294, 4434.955650443454, 4461.13007963444, 4468.597826195658, 4587.7017993830295, 5677.744501497443, 5711.657300561979, 5744.080947048452, 5794.855128621818, 6132.936844521141, 6139.032198454696, 6215.9378406216265, 6235.064740288903, 6262.718177681924, 5787.392323806024, 5793.2179155814265, 5800.709964501758, 5810.331811841692, 5816.098504731774, 5247.501487358695, 5351.170568561879, 5383.327084114486, 5394.163904594794, 5422.213779002022, 6251.093591429309, 6269.780557680459, 6284.151639528605, 6294.590851594632, 6303.716332765129, 6176.9073463897175, 6294.0, 6297.748090076346, 6297.779577714758, 6331.873362532816, 6374.553781235269, 6385.45723613498, 6392.3927226913365, 6397.4562162216735, 6399.2640846303275, 4886.560209581155, 5271.78912843482, 5578.470045345685, 6070.423309785563, 6184.5052395809125, 4948.307236986897, 5214.7914083436235, 6140.508759299335, 6221.222347206599, 6253.343398943228, 5697.800576979786, 5984.700764961822, 5992.850178745566, 6023.27720673522, 6055.697215139137, 5463.48096930791, 6001.639901605918, 6030.427109424598, 6054.273487181566, 6081.1486391906055, 4696.0, 4731.858044258678, 5502.944970550243, 5707.029804933254, 5864.794732184353, 6111.014442317695, 6112.381899552462, 6112.898653789679, 6117.462039585462, 6130.459285710702, 5908.970455147697, 5935.5548333875995, 5936.0, 5954.19618351517, 5960.493358064613], "value": 5618.087728708315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.4041469407323, 516.3184596332842, 521.5644936478042, 526.807715183958, 544.0859356281445, 38690.41964370537, 40579.79710101449, 40600.59399406005, 40652.79673601628, 40677.98305042372, 41586.58413415865, 42088.57911420885, 42125.0, 42152.0, 42219.78890105553, 9420.152186303249, 9472.715818525456, 9547.093026162504, 9563.713088607317, 9579.994100619404, 9508.334416590842, 9523.666671666493, 9548.665796697116, 9567.23462123032, 9606.423614583147, 8246.876296855522, 9557.0, 9580.473073980927, 9652.806943861153, 9660.71017869465, 9509.239260859114, 9528.714138575853, 9583.568739406743, 9597.424154550748, 9605.279604029725, 9317.72046838596, 9375.0, 9430.151286384189, 9456.669016584387, 9460.101290377403, 39797.60202397968, 39827.80516584507, 40158.0, 40226.0, 40247.59752402468, 3193.3294008258295, 3344.515045318415, 3491.2668339648703, 3728.850845966158, 3764.5106136202116, 27606.43613831685, 29098.70901290981, 29200.853995729885, 29293.707062929312, 30026.099217023526, 3142.591463109804, 3184.4904815229684, 3219.098652377338, 3235.773495855291, 3236.304194598149, 497068.0, 500208.0, 504997.0, 505988.0, 506071.0, 64165.716685666026, 65396.34603653903, 65672.34327656662, 65684.0, 65711.0, 67570.0, 69010.65494672494, 69235.65382173158, 69324.61350772956, 69438.0, 16412.015279083054, 16443.671126577403, 16619.750703739453, 16630.75053874193, 16639.33442662304, 12652.81020784689, 12733.936330318475, 12773.489060437665, 12786.680332991564, 12797.0, 19144.808551914582, 20140.0, 20203.0, 20249.898750506152, 20262.986850657704, 7151.7854464366155, 8077.555734434663, 8388.958055209807, 8392.0, 8410.32717382608, 785.6700185921926, 835.841190173865, 863.5466380150472, 864.8054187807693, 877.7849426890452, 26764.464710706066, 28513.71486285152, 28982.225857288147, 30152.69847301543, 30695.38609227803, 923.533615524156, 985.975350616226, 1008.8284991551454, 1022.4989755020135, 1026.343140390151, 13762.380692868845, 15399.307031183624, 15777.842221577866, 15889.920550397173, 16127.919360403357, 506923.4653826781, 686246.0, 687172.0, 687390.0, 688905.0, 57826.0, 57886.42113578894, 58535.0, 58630.413695863346, 58681.0, 1362.5026865194134, 1370.4312710225145, 1393.5540626999264, 1395.9092658977338, 1398.9370478328499, 28851.134465966054, 29708.702912971457, 29828.10515684533, 29835.254118647208, 30374.0, 51013.0, 51154.721131972, 51290.48709513006, 51394.22908656448, 51404.0, 500265.0, 501904.4904775453, 502045.0, 504022.0, 504267.0, 26935.730642694107, 27114.593281100406, 27139.0, 27543.724562754116, 27586.862065689544, 45840.54159458363, 46085.769571151926, 46951.0, 47080.52919470761, 47336.76331618457, 38462.615373847024, 38885.41671874981, 39009.80495097506, 39963.20073598454, 40423.0, 37615.43576846295, 38437.0, 38677.41983870188, 39689.0, 39738.0, 32022.519662205552, 32756.0, 36897.44653830249, 37997.43003854889, 38405.0, 31733.682663173997, 32948.1762955928, 34296.48555271623, 34706.47940280847, 34830.47754283738, 25734.485310293316, 25738.227853163688, 25762.87118564395, 26202.475950481265, 26279.605805912543], "value": 19944.4929013129, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.09601859518557, 378.4247943126446, 384.82105820793333, 390.7186825485649, 403.98990025249367, 15269.0, 19163.0, 19305.13126909289, 19872.60254794905, 20090.89954550229, 11574.074074074075, 11908.345041022762, 11983.340916249603, 11986.520539178444, 12006.879931200685, 4939.53074457926, 5233.712145831978, 5295.099833028395, 5343.893122137555, 5355.169948657965, 3837.6737977271964, 4039.333509970851, 5307.469253074681, 5323.707196104213, 5695.601307908448, 5945.227120474332, 6068.0, 6075.149479072933, 6078.574499784994, 6091.725872335734, 4675.92986105207, 4841.540053694894, 5969.761209551612, 5975.820725378202, 6069.666168360738, 5733.684647344395, 5744.454276843693, 5778.248827652378, 5780.653160810365, 5796.652200867919, 11830.822537661941, 12526.74946501065, 13030.022748293915, 13198.86801131986, 14031.298435078308, 1638.573970767609, 1973.3093417303971, 2234.530748542808, 2294.3919861236786, 2739.58906164075, 9574.8031496063, 9799.363041402286, 9876.45679487628, 10113.24150688694, 10383.013613706686, 1515.8105236845395, 1800.288885890066, 1816.6911625023777, 1831.5878927241397, 1842.843358314545, 117725.056873578, 122530.0, 124186.13720794195, 126397.0, 128544.0, 10466.691663542057, 10565.577376904992, 10942.343459392303, 11535.250208736405, 11557.88442115568, 14703.55889323322, 14719.264036798117, 14746.852531474762, 14775.852241477447, 14790.186539740418, 8167.550784706898, 8364.079951205362, 8368.497890126611, 8499.702510412106, 8545.914540854636, 7977.521348719096, 7981.281684648409, 8013.278804907586, 8121.5533145677555, 8130.674773009132, 3933.822977965941, 4458.8629899375455, 4466.709663871838, 4542.11428771393, 4606.56237657422, 1567.2085596773557, 1691.8392752688476, 1890.3761758619623, 1937.0, 1943.3101249056665, 793.8610743120047, 862.2455351567379, 872.7774417523618, 939.0421769794907, 991.6083147156833, 19652.606947860964, 21064.0, 21241.256556020464, 21333.67999480009, 21530.569388612454, 897.1387468030633, 910.4127837544746, 931.6290041843524, 948.4972964328779, 973.454865275448, 10990.230683852054, 11365.0, 11419.429028548539, 11438.714032149097, 11466.48400821965, 484701.5764921153, 574119.0, 583581.0, 597598.0, 611854.0, 113209.0, 115843.42078289723, 119536.0, 127500.0, 128385.0, 421.59105667502473, 941.1764705882352, 996.5714742660725, 998.4458625462938, 999.480270259461, 10706.946465267623, 10935.781284374109, 11016.889831101586, 11185.608503702331, 11495.827562586735, 53775.0, 54011.0, 54537.1819422701, 54538.45461545334, 55789.7210513961, 58162.0, 69134.27164320933, 74309.0, 78047.0, 78920.60539697265, 5141.974290128525, 5440.401555828935, 7602.619869006417, 7926.524408535506, 8196.590170491332, 7572.8485430292185, 11310.20828542027, 12746.171498852917, 12768.61694149177, 13011.934940325238, 9057.77355566116, 9138.543072846198, 10919.890801091888, 11037.11703063769, 11439.856014398827, 10803.405812680377, 11029.889701103208, 11035.227534072537, 11109.777804443704, 11175.050120739828, 11246.550137994716, 11250.0, 11482.0, 11610.129240306842, 11663.941680291544, 8208.58957052157, 8313.875291870505, 8335.874961875697, 8391.538465384217, 8395.664173432995, 8016.83966320682, 10739.838902416313, 11029.172812038969, 11193.944030280123, 11477.598284060017], "value": 8807.758365062757, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.9859901400987, 1435.5191011011311, 1622.5499495761403, 1691.42491552872, 1753.2461041752058, 7499.625018749068, 7746.457747957631, 8084.1107478177355, 8529.317654587641, 8565.186307300804, 6524.902126468107, 8247.21651443112, 8267.503949763004, 8422.663093476269, 8434.283085937703, 2288.4050146961786, 2305.2738387408, 2333.265021518216, 2338.4270853640883, 2345.2846881701075, 2332.731735850382, 2350.459394339303, 2367.8105751539833, 2368.644703294511, 2380.4048987753063, 2303.124812571229, 2316.9652455213186, 2328.37133973827, 2333.7782910623464, 2334.6848175496343, 2287.8970446329954, 2289.404754763765, 2315.6989591353017, 2321.5356928614174, 2321.570509455756, 2328.8253380996325, 2343.5664402085667, 2347.624380099193, 2352.1297120065515, 2356.0340260493285, 8371.874421883676, 8409.537475438849, 8468.915310846874, 8469.3647976402, 8470.534120623362, 5046.873828154289, 5209.505097015777, 5220.712860792655, 5241.0042092002395, 5258.316418865523, 3217.6299725531394, 3370.2753907909673, 3484.5121682964404, 3574.928501429983, 3585.4621806728937, 901.5041727050162, 1042.4110377636612, 1046.4988247234173, 1081.1999120650742, 1096.9890301096966, 10869.891301087046, 10907.563697452093, 10977.670669879917, 11006.449677516091, 11010.614628487965, 4524.6380289576755, 4650.093231819763, 4745.979614382924, 4751.144793937125, 4754.168020596451, 9057.320700947455, 9501.239900808057, 9547.761305967406, 9554.426734395958, 9569.473678947585, 5188.429272779991, 5191.402988656353, 5191.870203244949, 5198.9740051299505, 5199.66202196856, 4206.579342065769, 4217.34631132175, 4704.1767690654, 4713.175194340976, 4732.929006064912, 3773.2642134783455, 3815.561210460777, 3863.4011728182177, 3896.0, 3932.5870783567884, 1065.8116200436612, 1246.993765031169, 1273.2615083251692, 1276.0429677741695, 1289.5808862119848, 845.6363763581596, 875.5622188905547, 891.9910800892038, 914.2548822709471, 921.870938068671, 5765.740541675635, 5801.022835394825, 5824.970875145597, 5848.561357898175, 5982.641041537521, 452.671812935619, 455.3670398146579, 457.34599522682885, 965.1844191658038, 985.4481490365418, 3273.9181520461702, 3654.397024490929, 3789.1853251551047, 3843.0, 3852.24881148179, 10005.19958403326, 10360.430176340373, 10361.533730982124, 10367.0, 10369.688909332732, 8284.8343033139, 10189.082982531607, 10291.43397113151, 10317.019883111092, 10340.27618066728, 6529.738810447624, 6615.305392933769, 7542.245775422414, 7882.290593846581, 7990.960045199853, 5025.698458092526, 5465.617406781487, 5572.582056345709, 5791.160281759037, 5831.7667293307795, 9182.219511341611, 9395.48324842113, 9400.294977876823, 9556.092171243721, 9556.139947404767, 9620.518974051409, 9680.128788409076, 9711.174550163316, 9753.60985560598, 9753.707388778348, 6249.312575616769, 6436.163298771317, 6547.1161393211305, 6722.092517510269, 6877.277885821916, 7725.343345815444, 7998.0, 8002.119766825528, 8003.5197888126895, 8010.118886922551, 7087.787366379017, 7241.022461967777, 7245.166805817398, 7275.854482910207, 7334.743283985035, 6999.370056694922, 7059.5764254144915, 7069.081019467436, 7188.820279493055, 7190.784276471714, 6651.800445986628, 6653.2016158059405, 6725.226598941184, 6730.293319201413, 6756.966215168893, 4512.503624601357, 4887.731174785421, 4918.852434426973, 4949.6287778416045, 4967.7019378837385, 4182.853600123981, 4762.404699412574, 4846.248831431134, 4861.781219845187, 4971.9005619886675], "value": 4372.980449759185, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.1117204247469, 301.9924501887453, 304.57207623289287, 308.87027448471633, 312.8138757439323, 4467.374567560536, 5316.0, 5499.890002199953, 5513.476219759126, 5522.889542209154, 5196.454372290902, 5298.496642818926, 5336.706481143536, 5354.651947623402, 5377.516023557879, 3490.354284457371, 3583.8208089595546, 4037.132016616427, 4149.75507347796, 4195.244855925932, 3474.270403215328, 3680.7117508871816, 3837.4052021936504, 4095.856645017425, 4104.91790164198, 4007.5190977082643, 4092.976755811047, 4108.096218831853, 4120.237756015132, 4128.091819799639, 4061.512618485771, 4081.2449696806184, 4097.89755256118, 4119.958800411988, 4124.608162224584, 3124.7343975762087, 3606.1165014571598, 3610.711143108545, 3621.8551257949653, 3626.1841085755495, 4087.509498860126, 5102.953894451665, 5225.973870130625, 5228.346456692913, 5292.2326262691695, 4134.297169481196, 4637.744924029177, 4735.763211839394, 4742.241241401393, 4754.714717116949, 5152.6650767699975, 5477.507024367826, 5676.927921308734, 5695.259616249902, 5702.287214098238, 3068.892588759405, 3546.6098729139776, 3755.2301778135416, 3805.5324376808157, 3998.6001399860074, 5072.72100034498, 5340.839774806762, 5972.9701351493395, 5981.8803623927715, 6008.849778755566, 4017.3572228443895, 4033.979830100831, 4039.929418704046, 4051.1290072634524, 4062.979685101556, 3095.127447894037, 3947.2697550953167, 4090.570490098556, 4096.73371230869, 4101.384792281182, 4052.777097259679, 4346.673999450054, 4700.529947005272, 4709.505501922249, 4734.621230301567, 5061.974690126526, 5771.59598828078, 5792.710364481759, 5801.419858014165, 5814.186013958049, 1087.5649740104022, 1148.8563929508812, 1223.1804690857148, 1254.5044707340548, 1306.784380577194, 858.9392100755562, 865.0830120072743, 878.9912100879037, 887.1306120002305, 894.4499133033268, 4613.354130421743, 5081.974590127025, 5260.316158899319, 5286.97356513215, 5336.946630533723, 5238.97380513095, 5623.578231632562, 5632.662040277596, 5656.236408084938, 5766.567507436959, 4637.721736695809, 4658.650601204923, 5115.539601435814, 5178.4625533488315, 5222.4255331913455, 2628.0670362021588, 2639.9076032338776, 2713.503576709432, 2739.383638681281, 2976.9310329320915, 4836.951630483721, 5119.13151699627, 5426.701531415731, 5649.802256920988, 5780.416141991992, 4823.3729615150505, 5345.278387417648, 5523.309586301712, 5681.829545113653, 5793.7392817323325, 4685.4143232095985, 5311.628186027018, 5361.906189120351, 5504.972475137599, 5847.356790752929, 4705.482396936266, 5091.92362114576, 5970.253718285215, 6029.78895738647, 6053.03526190346, 5161.612879034013, 5353.046138159466, 5678.864227154668, 5709.885802283848, 5996.8800623988145, 5442.673439593637, 5988.700564971647, 6321.399467050623, 6338.366163383625, 6358.586691865014, 3934.586868378779, 3985.860494882665, 3995.700322475884, 3996.6802655786887, 4004.5594984551094, 5474.945250547603, 5560.833175004756, 5903.793367232126, 6016.398360163948, 6049.546284028627, 4937.901541720292, 5075.54320111177, 5387.0, 5552.833414997556, 5622.85942851432, 4239.766812825201, 4849.878753031202, 4892.85018020772, 5170.353705786777, 5494.0, 3994.861464482725, 4366.628836548929, 5453.072977593898, 5616.382197958303, 5776.476349426763, 5798.478136967693, 5802.738876750641, 5804.955108080586, 5811.79658711943, 5831.212786273799, 4251.617354438115, 4505.864824055284, 4781.4740378557635, 5304.310439642822, 5718.285214348207], "value": 4063.2334714857802, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.348571656918, 683.6103421050001, 740.1007775552703, 766.8650397411831, 778.711876605656, 13656.180629162245, 14353.712925741478, 14373.928130359347, 14388.136711797299, 14415.927920360384, 13085.542006029791, 14002.29988500576, 14607.92696036521, 14768.556943291691, 14948.775768363483, 642.4988508963016, 806.2018601584433, 828.3042244514606, 829.1045670675655, 829.999850180534, 661.9801405957805, 793.8729803231485, 814.1207495904439, 815.233680340481, 815.7226542975387, 856.1951765340591, 857.9742607721778, 858.9742307730778, 860.9009963854174, 861.2248975921658, 843.6583183810567, 855.1192271959891, 855.5935930432995, 856.6402111113316, 857.4126723194569, 633.5438484291303, 633.8700566383926, 634.3402861024529, 634.6763150793082, 635.1679300116815, 14034.92982535091, 14160.716785664228, 14196.219207943559, 14198.787018194735, 14199.503017394445, 10928.617498387597, 12969.546065887744, 13129.86870131296, 13247.867521324759, 13320.866791332157, 10916.29044112138, 11021.83467247992, 11291.54833806655, 11307.604233851776, 11456.369899655516, 3057.419090372822, 3645.9453108203134, 3659.7621154624862, 3665.633436656313, 3714.3499887519574, 89503.10496895012, 89918.10081899229, 90107.54946225292, 91215.08784912132, 91340.0, 10219.284650074573, 10354.53404596795, 10399.584016639401, 10411.53148108337, 10442.112420444195, 20021.29925452602, 20145.59708805845, 20148.899255503628, 20341.084651190733, 20362.389128326173, 7411.5553066816165, 7415.555066696015, 7416.555006699507, 7488.663010164447, 7565.978592889999, 4059.7361171523758, 4233.809478573472, 4248.362745588187, 4307.892302692395, 4316.589923957219, 3305.504174373863, 3410.5736782902136, 3412.6757957994446, 3419.093940105874, 3420.435628121382, 638.288308535979, 675.5406323699931, 679.4835924697265, 692.2696555134281, 696.585531608692, 562.2044806598699, 609.9176611157437, 630.5082036011827, 649.4544582550681, 663.8207683925409, 8145.263314563564, 8907.955460222787, 9088.273228086642, 9104.908950910538, 9112.0, 642.5598465051455, 651.3031056769212, 658.2621878241447, 671.9966400167967, 676.7326905872153, 3598.208394153282, 4159.708820382544, 4194.266003449384, 4489.7979590918485, 4532.138893610204, 88467.0, 94789.0, 106919.0, 108192.4590377043, 111621.0, 96225.0, 98513.50743246239, 99816.5009174964, 100773.0, 103841.48079259555, 62704.74590508164, 68914.65542672254, 84460.57769711236, 85872.71190932141, 88515.0, 6821.590704557742, 8333.95833020831, 8677.26243269304, 8687.739367818975, 8711.433756805787, 50067.74966125292, 54447.0, 55148.0, 55745.721271393384, 57018.71490642686, 63099.6845015772, 72063.27936720775, 76126.0, 77894.22105779096, 79525.20474795409, 8644.78388040304, 11555.0, 11593.6521904343, 11704.239224450383, 12263.202891812003, 12745.10784245094, 13081.607551773463, 13236.0, 13477.595672129852, 14312.0, 12905.612831615066, 13125.737485250433, 13464.394102265225, 13790.172589644653, 13895.166290022631, 10828.675139745821, 12778.424970876518, 13099.078054720387, 13233.470661173831, 13767.793483097961, 10396.740081498023, 11958.222715523463, 12298.569550065704, 12863.0, 12918.354082296035, 10512.119955845663, 11052.889471105185, 11087.0, 11132.721681958015, 11604.0, 9832.213422926006, 11344.602938897098, 11470.311781293149, 11470.369129697947, 11507.769844603228], "value": 7174.081619995263, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 176.0, 168.0, 119.0, 120.0, 102.0, 151.0, 173.0, 172.0, 117.0], "value": 143.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 119.0, 116.0, 151.0, 119.0, 117.0, 117.0, 121.0, 146.0, 120.0], "value": 128.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 201.0, 207.0, 278.0, 198.0, 196.0, 198.0, 198.0, 198.0, 199.0], "value": 207.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 59.0, 89.0, 61.0, 62.0, 60.0, 62.0, 99.0, 50.0], "value": 68.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 58.0, 115.0, 59.0, 73.0, 91.0, 122.0, 88.0, 96.0, 123.0], "value": 91.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 132.0, 135.0, 102.0, 88.0, 86.0, 90.0, 90.0, 93.0, 117.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 96.0, 98.0, 97.0, 96.0, 97.0, 128.0, 107.0], "value": 100.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 125.0, 85.0, 86.0, 88.0, 81.0, 131.0, 87.0, 135.0, 135.0], "value": 104.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 60.0, 62.0, 60.0, 62.0, 61.0, 60.0, 62.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 74.0, 107.0, 117.0, 113.0, 107.0, 72.0, 73.0, 74.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 86.0, 87.0, 86.0, 88.0, 84.0, 89.0, 88.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 156.0, 163.0, 148.0, 155.0, 185.0, 165.0, 157.0, 210.0, 152.0], "value": 163.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 175.0, 169.0, 168.0, 172.0, 171.0, 165.0, 173.0, 172.0, 169.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 68.0, 70.0, 72.0, 68.0, 69.0, 69.0, 69.0, 71.0], "value": 69.5, "unit": "score"}]}]} 09:03:40 INFO - Return code: 0 09:03:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:03:40 ERROR - # TBPL SUCCESS # 09:03:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:40 INFO - 2016-12-09 09:03:40.982 screenresolution[1655:10558] starting screenresolution argv=screenresolution get 09:03:40 INFO - 2016-12-09 09:03:40.986 screenresolution[1655:10558] Display 0: 1600x1200x32@60 09:03:40 INFO - 2016-12-09 09:03:40.992 screenresolution[1656:10561] starting screenresolution argv=screenresolution list 09:03:40 INFO - Available Modes on Display 0 09:03:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:41 INFO - Intel Iris: 09:03:41 INFO - Chipset Model: Intel Iris 09:03:41 INFO - Type: GPU 09:03:41 INFO - Bus: Built-In 09:03:41 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:41 INFO - Vendor: Intel (0x8086) 09:03:41 INFO - Device ID: 0x0a2e 09:03:41 INFO - Revision ID: 0x0009 09:03:41 INFO - Displays: 09:03:41 INFO - Display: 09:03:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:41 INFO - Display Serial Number: 1600x1200 60 09:03:41 INFO - Main Display: Yes 09:03:41 INFO - Mirror: Off 09:03:41 INFO - Online: Yes 09:03:41 INFO - Rotation: Supported 09:03:41 INFO - Return code: 0 09:03:41 INFO - Running post-action listener: _resource_record_post_action 09:03:41 INFO - [mozharness: 2016-12-09 17:03:41.233261Z] Finished run-tests step (success) 09:03:41 INFO - Running post-run listener: _resource_record_post_run 09:03:41 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38562304; Write bytes: 660237312; Read time: 247; Write time: 1903 09:03:41 INFO - TinderboxPrint: CPU usage
54.3% 09:03:41 INFO - TinderboxPrint: I/O read bytes / time
38,562,304 / 247 09:03:41 INFO - TinderboxPrint: I/O write bytes / time
660,237,312 / 1,903 09:03:41 INFO - TinderboxPrint: CPU idle
2,215.9 (45.7%) 09:03:41 INFO - TinderboxPrint: CPU system
978.9 (20.2%) 09:03:41 INFO - TinderboxPrint: CPU user
1,650.9 (34.1%) 09:03:41 INFO - TinderboxPrint: Swap in / out
387,493,888 / 0 09:03:41 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 344855040; Write bytes: 345683456; Read time: 26920; Write time: 701 09:03:41 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36551168; Write bytes: 307578368; Read time: 228; Write time: 1173 09:03:41 INFO - Running post-run listener: _upload_blobber_files 09:03:41 INFO - Blob upload gear active. 09:03:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:03:41 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 09:03:41 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'] 09:03:41 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 09:03:42 INFO - (blobuploader) - INFO - Open directory for files ... 09:03:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:03:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:03:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:43 INFO - (blobuploader) - INFO - Done attempting. 09:03:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:03:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:03:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:44 INFO - (blobuploader) - INFO - Done attempting. 09:03:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:03:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:03:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:03:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:03:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:03:47 INFO - (blobuploader) - INFO - Done attempting. 09:03:47 INFO - (blobuploader) - INFO - Iteration through files over. 09:03:47 INFO - Return code: 0 09:03:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:03:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:03:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/865db9043a80dbff1c553321a0c50f8f8063e69c11e576e0e18fec7fcbbfb46427cf0a138631a0e7166bcdf58533ed5dbb42b2f5bdb4d47c9c490b2f5054b907", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e99628398b8aec1dfab35aa07071213d6f11667d3dbe75839481e514a744e0cc13e3d879b7eb1ccc33adadd515751572a8fd74bcafa1da8055d340dda65e92e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0afb8430b8ca4617349e29bc5909cc6f033a73320ef31b43b97b9875bf8f5709ca4d27b2ee8f11ea4888534c54d4caad5e65e0cdcea6e4428d45646e3701620e"} 09:03:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:03:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:03:47 INFO - Contents: 09:03:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/865db9043a80dbff1c553321a0c50f8f8063e69c11e576e0e18fec7fcbbfb46427cf0a138631a0e7166bcdf58533ed5dbb42b2f5bdb4d47c9c490b2f5054b907", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e99628398b8aec1dfab35aa07071213d6f11667d3dbe75839481e514a744e0cc13e3d879b7eb1ccc33adadd515751572a8fd74bcafa1da8055d340dda65e92e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0afb8430b8ca4617349e29bc5909cc6f033a73320ef31b43b97b9875bf8f5709ca4d27b2ee8f11ea4888534c54d4caad5e65e0cdcea6e4428d45646e3701620e"} 09:03:47 INFO - Running post-run listener: copy_logs_to_upload_dir 09:03:47 INFO - Copying logs to upload dir... 09:03:47 INFO - mkdir: /builds/slave/test/build/upload/logs 09:03:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.100079 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-12-09 09:03:47.111943) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 09:03:47.115238) ========= 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.tAHcANkG6q/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.jfsGQXjOpx/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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/865db9043a80dbff1c553321a0c50f8f8063e69c11e576e0e18fec7fcbbfb46427cf0a138631a0e7166bcdf58533ed5dbb42b2f5bdb4d47c9c490b2f5054b907", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e99628398b8aec1dfab35aa07071213d6f11667d3dbe75839481e514a744e0cc13e3d879b7eb1ccc33adadd515751572a8fd74bcafa1da8055d340dda65e92e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0afb8430b8ca4617349e29bc5909cc6f033a73320ef31b43b97b9875bf8f5709ca4d27b2ee8f11ea4888534c54d4caad5e65e0cdcea6e4428d45646e3701620e"} build_url:https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010996 build_url: 'https://queue.taskcluster.net/v1/task/fcbr17zYRw6cDapY9dQGBA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/865db9043a80dbff1c553321a0c50f8f8063e69c11e576e0e18fec7fcbbfb46427cf0a138631a0e7166bcdf58533ed5dbb42b2f5bdb4d47c9c490b2f5054b907", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6e99628398b8aec1dfab35aa07071213d6f11667d3dbe75839481e514a744e0cc13e3d879b7eb1ccc33adadd515751572a8fd74bcafa1da8055d340dda65e92e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0afb8430b8ca4617349e29bc5909cc6f033a73320ef31b43b97b9875bf8f5709ca4d27b2ee8f11ea4888534c54d4caad5e65e0cdcea6e4428d45646e3701620e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-09 09:03:47.150236) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 09:03:47.150617) ========= 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.tAHcANkG6q/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.jfsGQXjOpx/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.005166 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-09 09:03:47.200391) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 09:03:47.200821) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-09 09:03:47.201188) ========= ========= Total master_lag: 0.18 =========