builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0233 starttime: 1483807738.74 results: success (0) buildid: 20170107063756 builduid: 7922d12289e54cae8367033373c24d57 revision: 58e5b3c7bdaf6e60a50c7a59c7105a0eb5dd0c2d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 08:48:58.743478) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 08:48:58.744286) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 08:48:58.744567) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 08:48:58.772246) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:48:58.772507) ========= 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.8f7U4jDr0V/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.YaELjQJv5H/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-07 08:48:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 200M=0s 2017-01-07 08:48:59 (200 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.579459 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:48:59.375715) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:48:59.376017) ========= 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.8f7U4jDr0V/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.YaELjQJv5H/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.024479 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 08:48:59.424719) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 08:48:59.425059) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 58e5b3c7bdaf6e60a50c7a59c7105a0eb5dd0c2d --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 58e5b3c7bdaf6e60a50c7a59c7105a0eb5dd0c2d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.8f7U4jDr0V/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.YaELjQJv5H/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-07 08:48:59,521 truncating revision to first 12 chars 2017-01-07 08:48:59,521 Setting DEBUG logging. 2017-01-07 08:48:59,521 attempt 1/10 2017-01-07 08:48:59,521 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/58e5b3c7bdaf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 08:49:00,648 unpacking tar archive at: mozilla-aurora-58e5b3c7bdaf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.393417 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 08:49:00.842151) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 08:49:00.842470) ========= script_repo_revision: 58e5b3c7bdaf6e60a50c7a59c7105a0eb5dd0c2d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 08:49:00.842894) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 08:49:00.843178) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 08:49:00.863284) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-07 08:49:00.863586) ========= /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.8f7U4jDr0V/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.YaELjQJv5H/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:01 INFO - MultiFileLogger online at 20170107 08:49:01 in /builds/slave/test 08:49:01 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:49:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:49:01 INFO - {'append_to_log': False, 08:49:01 INFO - 'base_work_dir': '/builds/slave/test', 08:49:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:49:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:49:01 INFO - 'branch': 'Mozilla-Aurora', 08:49:01 INFO - 'buildbot_json_path': 'buildprops.json', 08:49:01 INFO - 'config_files': ('talos/mac_config.py',), 08:49:01 INFO - 'default_actions': ('clobber', 08:49:01 INFO - 'read-buildbot-config', 08:49:01 INFO - 'download-and-extract', 08:49:01 INFO - 'populate-webroot', 08:49:01 INFO - 'create-virtualenv', 08:49:01 INFO - 'install', 08:49:01 INFO - 'run-tests'), 08:49:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:49:01 INFO - 'download_minidump_stackwalk': True, 08:49:01 INFO - 'download_symbols': 'ondemand', 08:49:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:49:01 INFO - 'tooltool.py': '/tools/tooltool.py', 08:49:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:49:01 INFO - '/tools/misc-python/virtualenv.py')}, 08:49:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:49:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:49:01 INFO - 'installer_path': 'installer.exe', 08:49:01 INFO - 'log_level': 'info', 08:49:01 INFO - 'log_name': 'talos', 08:49:01 INFO - 'log_to_console': True, 08:49:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:49:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:49:01 INFO - 'opt_config_files': (), 08:49:01 INFO - 'pip_index': False, 08:49:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:49:01 INFO - 'cmd': ('bash', 08:49:01 INFO - '-c', 08:49:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:49:01 INFO - 'enabled': True, 08:49:01 INFO - 'halt_on_failure': False, 08:49:01 INFO - 'name': 'check_screen_resolution'},), 08:49:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:49:01 INFO - 'cmd': ('bash', 08:49:01 INFO - '-c', 08:49:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:49:01 INFO - 'enabled': True, 08:49:01 INFO - 'halt_on_failure': False, 08:49:01 INFO - 'name': 'check_screen_resolution'},), 08:49:01 INFO - 'run_cmd_checks_enabled': True, 08:49:01 INFO - 'sps_profile': False, 08:49:01 INFO - 'sps_profile_interval': 0, 08:49:01 INFO - 'suite': 'dromaeojs-e10s', 08:49:01 INFO - 'system_bits': '32', 08:49:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:49:01 INFO - 'title': 't-yosemite-r7-0233', 08:49:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:49:01 INFO - 'use_talos_json': True, 08:49:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:49:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:49:01 INFO - 'work_dir': 'build'} 08:49:01 INFO - [mozharness: 2017-01-07 16:49:01.063355Z] Running clobber step. 08:49:01 INFO - Running pre-action listener: _resource_record_pre_action 08:49:01 INFO - Running main action method: clobber 08:49:01 INFO - rmtree: /builds/slave/test/build 08:49:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:49:03 INFO - Running post-action listener: _resource_record_post_action 08:49:03 INFO - [mozharness: 2017-01-07 16:49:03.421667Z] Finished clobber step (success) 08:49:03 INFO - [mozharness: 2017-01-07 16:49:03.421849Z] Running read-buildbot-config step. 08:49:03 INFO - Running pre-action listener: _resource_record_pre_action 08:49:03 INFO - Running main action method: read_buildbot_config 08:49:03 INFO - Using buildbot properties: 08:49:03 INFO - { 08:49:03 INFO - "project": "", 08:49:03 INFO - "product": "firefox", 08:49:03 INFO - "script_repo_revision": "production", 08:49:03 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:49:03 INFO - "repository": "", 08:49:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:49:03 INFO - "buildid": "20170107063756", 08:49:03 INFO - "pgo_build": "False", 08:49:03 INFO - "basedir": "/builds/slave/test", 08:49:03 INFO - "buildnumber": 11, 08:49:03 INFO - "slavename": "t-yosemite-r7-0233", 08:49:03 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:49:03 INFO - "platform": "yosemite_r7", 08:49:03 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:49:03 INFO - "branch": "mozilla-aurora", 08:49:03 INFO - "repo_path": "releases/mozilla-aurora", 08:49:03 INFO - "revision": "58e5b3c7bdaf6e60a50c7a59c7105a0eb5dd0c2d", 08:49:03 INFO - "stage_platform": "macosx64", 08:49:03 INFO - "builduid": "7922d12289e54cae8367033373c24d57", 08:49:03 INFO - "slavebuilddir": "test" 08:49:03 INFO - } 08:49:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:49:03 INFO - Running post-action listener: _resource_record_post_action 08:49:03 INFO - [mozharness: 2017-01-07 16:49:03.425311Z] Finished read-buildbot-config step (success) 08:49:03 INFO - [mozharness: 2017-01-07 16:49:03.425451Z] Running download-and-extract step. 08:49:03 INFO - Running pre-action listener: _resource_record_pre_action 08:49:03 INFO - Running main action method: download_and_extract 08:49:03 INFO - mkdir: /builds/slave/test/build/tests 08:49:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:03 INFO - trying https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:49:03 INFO - Downloading https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/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:49:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/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:49:04 INFO - Downloaded 1199 bytes. 08:49:04 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:49:04 INFO - Using the following test package requirements: 08:49:04 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:49:04 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:49:04 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:49:04 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:49:04 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:49:04 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:49:04 INFO - u'jsshell-mac.zip'], 08:49:04 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:49:04 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:49:04 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:49:04 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:49:04 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:49:04 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:49:04 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:49:04 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:49:04 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:49:04 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:49:04 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:49:04 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:49:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:49:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:49:04 INFO - Fetch https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:49:05 INFO - Http code: 200 08:49:05 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 08:49:05 INFO - x-amz-cf-id: oyoP7MeX7EIUInNLMpiz40B2CAVY2OhAqD6Lv6mVV-M5RLsm5HZkZA== 08:49:05 INFO - x-amz-version-id: PdWluMzIkAey5fBssz4ZemiVrdkmFp.. 08:49:05 INFO - Expected file size: 16269568 08:49:05 INFO - Obtained file size: 16269568 08:49:11 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:49:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:49:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:49:11 INFO - Fetch https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:49:12 INFO - Http code: 200 08:49:12 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 08:49:12 INFO - x-amz-cf-id: xKLa3QBCPwR3mBn_MIHQXB2gqSVUjc4u6JxRl1lZ0Gzrp7tfA9l2cw== 08:49:12 INFO - x-amz-version-id: PdWluMzIkAey5fBssz4ZemiVrdkmFp.. 08:49:12 INFO - Expected file size: 16269568 08:49:12 INFO - Obtained file size: 16269568 08:49:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:49:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:49:18 INFO - Fetch https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:49:19 INFO - Http code: 200 08:49:19 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 08:49:19 INFO - x-amz-cf-id: S4sLpUStVX7-idcTxfbBw1JUDus8-gbDBq8uOL8SbVybGSIC19WX2w== 08:49:19 INFO - x-amz-version-id: BPH48p.UZo7AV.7fNJ5gQxua.xZeJXIo 08:49:20 INFO - Expected file size: 14409038 08:49:20 INFO - Obtained file size: 14409038 08:49:20 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:49:20 INFO - trying https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:20 INFO - Downloading https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:49:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/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:49:23 INFO - Downloaded 109864708 bytes. 08:49:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:23 INFO - mkdir: /builds/slave/test/properties 08:49:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:49:23 INFO - Writing to file /builds/slave/test/properties/build_url 08:49:23 INFO - Contents: 08:49:23 INFO - build_url:https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:24 INFO - Running post-action listener: _resource_record_post_action 08:49:24 INFO - Running post-action listener: set_extra_try_arguments 08:49:24 INFO - [mozharness: 2017-01-07 16:49:24.238171Z] Finished download-and-extract step (success) 08:49:24 INFO - [mozharness: 2017-01-07 16:49:24.238326Z] Running populate-webroot step. 08:49:24 INFO - Running pre-action listener: _resource_record_pre_action 08:49:24 INFO - Running main action method: populate_webroot 08:49:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:24 INFO - '--tppagecycles', 08:49:24 INFO - '7'], 08:49:24 INFO - 'tests': ['tsvgm']}}, 08:49:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:49:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:49:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:49:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:24 INFO - 'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:24 INFO - 'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['damp', 'tps']}, 08:49:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:24 INFO - 'tests': ['damp', 'tps']}, 08:49:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['dromaeo_dom']}, 08:49:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['basic_compositor_video']}, 08:49:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:49:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['a11yr', 08:49:24 INFO - 'ts_paint', 08:49:24 INFO - 'tpaint', 08:49:24 INFO - 'sessionrestore', 08:49:24 INFO - 'sessionrestore_no_auto_restore', 08:49:24 INFO - 'tabpaint']}, 08:49:24 INFO - 'other-e10s': {'tests': ['a11yr', 08:49:24 INFO - 'ts_paint', 08:49:24 INFO - 'tpaint', 08:49:24 INFO - 'sessionrestore', 08:49:24 INFO - 'sessionrestore_no_auto_restore', 08:49:24 INFO - 'tabpaint']}, 08:49:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:49:24 INFO - 'ts_paint', 08:49:24 INFO - 'tpaint', 08:49:24 INFO - 'sessionrestore', 08:49:24 INFO - 'sessionrestore_no_auto_restore', 08:49:24 INFO - 'tabpaint']}, 08:49:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:49:24 INFO - 'ts_paint', 08:49:24 INFO - 'tpaint', 08:49:24 INFO - 'sessionrestore', 08:49:24 INFO - 'sessionrestore_no_auto_restore', 08:49:24 INFO - 'tabpaint']}, 08:49:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['a11yr', 08:49:24 INFO - 'ts_paint', 08:49:24 INFO - 'tpaint', 08:49:24 INFO - 'sessionrestore', 08:49:24 INFO - 'sessionrestore_no_auto_restore', 08:49:24 INFO - 'tabpaint']}, 08:49:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['a11yr', 08:49:24 INFO - 'ts_paint', 08:49:24 INFO - 'tpaint', 08:49:24 INFO - 'sessionrestore', 08:49:24 INFO - 'sessionrestore_no_auto_restore', 08:49:24 INFO - 'tabpaint']}, 08:49:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['tsvgx', 08:49:24 INFO - 'tsvgr_opacity', 08:49:24 INFO - 'tart', 08:49:24 INFO - 'tscrollx', 08:49:24 INFO - 'cart']}, 08:49:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:49:24 INFO - 'tsvgr_opacity', 08:49:24 INFO - 'tart', 08:49:24 INFO - 'tscrollx', 08:49:24 INFO - 'cart']}, 08:49:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:24 INFO - 'talos_options': ['--disable-e10s'], 08:49:24 INFO - 'tests': ['tp5o']}, 08:49:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:24 INFO - 'tests': ['tp5o']}, 08:49:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:24 INFO - 'talos_options': ['--disable-e10s', 08:49:24 INFO - '--xperf_path', 08:49:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:24 INFO - 'tests': ['tp5n']}, 08:49:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:24 INFO - 'talos_options': ['--xperf_path', 08:49:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:24 INFO - 'tests': ['tp5n']}}, 08:49:24 INFO - 'talos.zip': {'path': '', 08:49:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:24 INFO - Running post-action listener: _resource_record_post_action 08:49:24 INFO - [mozharness: 2017-01-07 16:49:24.257207Z] Finished populate-webroot step (success) 08:49:24 INFO - [mozharness: 2017-01-07 16:49:24.257303Z] Running create-virtualenv step. 08:49:24 INFO - Running pre-action listener: _resource_record_pre_action 08:49:24 INFO - Running main action method: create_virtualenv 08:49:24 INFO - Creating virtualenv /builds/slave/test/build/venv 08:49:24 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:49:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:49:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:24 INFO - Using real prefix '/tools/python27' 08:49:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:49:25 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:27 INFO - Installing pip.................done. 08:49:27 INFO - Return code: 0 08:49:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:49:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:49:28 INFO - Reading from file tmpfile_stdout 08:49:28 INFO - Output received: 08:49:28 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:49:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:49: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:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:28 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:28 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:49:28 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:49:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:28 INFO - 'HOME': '/Users/cltbld', 08:49:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:28 INFO - 'LOGNAME': 'cltbld', 08:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:28 INFO - 'MOZ_NO_REMOTE': '1', 08:49:28 INFO - 'NO_EM_RESTART': '1', 08:49:28 INFO - 'PAGER': '/bin/cat', 08:49:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:28 INFO - 'PWD': '/builds/slave/test', 08:49:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:28 INFO - 'SHELL': '/bin/bash', 08:49:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:28 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:28 INFO - 'USER': 'cltbld', 08:49:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:28 INFO - 'XPC_FLAGS': '0x0', 08:49:28 INFO - 'XPC_SERVICE_NAME': '0', 08:49:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:28 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:49:28 INFO - Cleaning up... 08:49:28 INFO - Return code: 0 08:49:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:49: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:49:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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:49:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:28 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:28 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:49:28 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:49:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:28 INFO - 'HOME': '/Users/cltbld', 08:49:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:28 INFO - 'LOGNAME': 'cltbld', 08:49:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:28 INFO - 'MOZ_NO_REMOTE': '1', 08:49:28 INFO - 'NO_EM_RESTART': '1', 08:49:28 INFO - 'PAGER': '/bin/cat', 08:49:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:28 INFO - 'PWD': '/builds/slave/test', 08:49:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:28 INFO - 'SHELL': '/bin/bash', 08:49:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:28 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:28 INFO - 'USER': 'cltbld', 08:49:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:28 INFO - 'XPC_FLAGS': '0x0', 08:49:28 INFO - 'XPC_SERVICE_NAME': '0', 08:49:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:28 INFO - Downloading/unpacking psutil>=3.1.1 08:49:28 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:49:28 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:49:28 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:49:28 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:49:28 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:49:28 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:49:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:32 INFO - Installing collected packages: psutil 08:49:32 INFO - Running setup.py install for psutil 08:49:32 INFO - building 'psutil._psutil_osx' extension 08:49:32 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:49:33 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:49:33 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:49:33 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:49:33 INFO - building 'psutil._psutil_posix' extension 08:49:33 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:49:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:49:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:49:33 INFO - ^ 08:49:33 INFO - 1 warning generated. 08:49:33 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:49:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:33 INFO - Successfully installed psutil 08:49:33 INFO - Cleaning up... 08:49:33 INFO - Return code: 0 08:49:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:49:33 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:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:33 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:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:33 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:33 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:49:33 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:49:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:33 INFO - 'HOME': '/Users/cltbld', 08:49:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:33 INFO - 'LOGNAME': 'cltbld', 08:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:33 INFO - 'MOZ_NO_REMOTE': '1', 08:49:33 INFO - 'NO_EM_RESTART': '1', 08:49:33 INFO - 'PAGER': '/bin/cat', 08:49:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:33 INFO - 'PWD': '/builds/slave/test', 08:49:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:33 INFO - 'SHELL': '/bin/bash', 08:49:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:33 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:33 INFO - 'USER': 'cltbld', 08:49:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:33 INFO - 'XPC_FLAGS': '0x0', 08:49:33 INFO - 'XPC_SERVICE_NAME': '0', 08:49:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:36 INFO - Installing collected packages: mozsystemmonitor 08:49:36 INFO - Running setup.py install for mozsystemmonitor 08:49:37 INFO - Successfully installed mozsystemmonitor 08:49:37 INFO - Cleaning up... 08:49:37 INFO - Return code: 0 08:49:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:49:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:37 INFO - 'HOME': '/Users/cltbld', 08:49:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:37 INFO - 'LOGNAME': 'cltbld', 08:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:37 INFO - 'MOZ_NO_REMOTE': '1', 08:49:37 INFO - 'NO_EM_RESTART': '1', 08:49:37 INFO - 'PAGER': '/bin/cat', 08:49:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:37 INFO - 'PWD': '/builds/slave/test', 08:49:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:37 INFO - 'SHELL': '/bin/bash', 08:49:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:37 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:37 INFO - 'USER': 'cltbld', 08:49:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:37 INFO - 'XPC_FLAGS': '0x0', 08:49:37 INFO - 'XPC_SERVICE_NAME': '0', 08:49:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:37 INFO - Downloading/unpacking jsonschema==2.5.1 08:49:37 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:49:37 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:49:37 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:49:37 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:49:37 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:49:37 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:49:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:40 INFO - warning: no files found matching '*.txt' 08:49:40 INFO - no previously-included directories found matching 'build' 08:49:40 INFO - no previously-included directories found matching 'dist' 08:49:40 INFO - no previously-included directories found matching '.git*' 08:49:40 INFO - Installing collected packages: jsonschema, functools32 08:49:40 INFO - Running setup.py install for functools32 08:49:40 INFO - warning: no files found matching '*.txt' 08:49:40 INFO - no previously-included directories found matching 'build' 08:49:40 INFO - no previously-included directories found matching 'dist' 08:49:40 INFO - no previously-included directories found matching '.git*' 08:49:40 INFO - Successfully installed jsonschema functools32 08:49:40 INFO - Cleaning up... 08:49:40 INFO - Return code: 0 08:49:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49:40 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:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:40 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:49:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:40 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:40 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:49:40 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:49:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:40 INFO - 'HOME': '/Users/cltbld', 08:49:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:40 INFO - 'LOGNAME': 'cltbld', 08:49:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:40 INFO - 'MOZ_NO_REMOTE': '1', 08:49:40 INFO - 'NO_EM_RESTART': '1', 08:49:40 INFO - 'PAGER': '/bin/cat', 08:49:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:40 INFO - 'PWD': '/builds/slave/test', 08:49:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:40 INFO - 'SHELL': '/bin/bash', 08:49:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:40 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:40 INFO - 'USER': 'cltbld', 08:49:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:40 INFO - 'XPC_FLAGS': '0x0', 08:49:40 INFO - 'XPC_SERVICE_NAME': '0', 08:49:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:41 INFO - Cleaning up... 08:49:41 INFO - Return code: 0 08:49:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:41 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:41 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:49:41 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:49:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:41 INFO - 'HOME': '/Users/cltbld', 08:49:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:41 INFO - 'LOGNAME': 'cltbld', 08:49:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:41 INFO - 'MOZ_NO_REMOTE': '1', 08:49:41 INFO - 'NO_EM_RESTART': '1', 08:49:41 INFO - 'PAGER': '/bin/cat', 08:49:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:41 INFO - 'PWD': '/builds/slave/test', 08:49:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:41 INFO - 'SHELL': '/bin/bash', 08:49:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:41 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:41 INFO - 'USER': 'cltbld', 08:49:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:41 INFO - 'XPC_FLAGS': '0x0', 08:49:41 INFO - 'XPC_SERVICE_NAME': '0', 08:49:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:41 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:41 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:49:41 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:49:41 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:49:41 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:49:41 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:49:41 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:49:45 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49:45 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:49:45 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:49:45 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:49:45 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:49:45 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:49:45 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:49:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:45 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:49:45 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:49:45 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:49:45 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:49:45 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:49:45 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:49:46 INFO - Downloading docopt-0.6.1.tar.gz 08:49:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:46 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:46 INFO - Running setup.py install for blobuploader 08:49:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:46 INFO - Running setup.py install for requests 08:49:47 INFO - Running setup.py install for docopt 08:49:47 INFO - Successfully installed blobuploader requests docopt 08:49:47 INFO - Cleaning up... 08:49:47 INFO - Return code: 0 08:49:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:47 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:47 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:49:47 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:49:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:47 INFO - 'HOME': '/Users/cltbld', 08:49:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:47 INFO - 'LOGNAME': 'cltbld', 08:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:47 INFO - 'MOZ_NO_REMOTE': '1', 08:49:47 INFO - 'NO_EM_RESTART': '1', 08:49:47 INFO - 'PAGER': '/bin/cat', 08:49:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:47 INFO - 'PWD': '/builds/slave/test', 08:49:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:47 INFO - 'SHELL': '/bin/bash', 08:49:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:47 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:47 INFO - 'USER': 'cltbld', 08:49:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:47 INFO - 'XPC_FLAGS': '0x0', 08:49:47 INFO - 'XPC_SERVICE_NAME': '0', 08:49:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Hvpa2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-fAnTJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-nULNE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:47 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-iB9Rhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-hInSO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-NfJ6MR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-OHlfXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-LyZzRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-QP8TZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-H8txu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-8HNxGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-He3d_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-KLhYQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:48 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-7iQFl_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-b5qqh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ln3RkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:49 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-nEjaHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:49 INFO - Running setup.py install for manifestparser 08:49:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:49 INFO - Running setup.py install for mozcrash 08:49:49 INFO - Running setup.py install for mozdebug 08:49:49 INFO - Running setup.py install for mozdevice 08:49:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:49 INFO - Running setup.py install for mozfile 08:49:50 INFO - Running setup.py install for mozhttpd 08:49:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:50 INFO - Running setup.py install for mozinfo 08:49:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:50 INFO - Running setup.py install for mozInstall 08:49:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:50 INFO - Running setup.py install for mozleak 08:49:50 INFO - Running setup.py install for mozlog 08:49:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:50 INFO - Running setup.py install for moznetwork 08:49:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:50 INFO - Running setup.py install for mozprocess 08:49:51 INFO - Running setup.py install for mozprofile 08:49:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:51 INFO - Running setup.py install for mozrunner 08:49:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:51 INFO - Running setup.py install for mozscreenshot 08:49:51 INFO - Running setup.py install for moztest 08:49:51 INFO - Running setup.py install for mozversion 08:49:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:51 INFO - Cleaning up... 08:49:51 INFO - Return code: 0 08:49:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:51 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:51 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:49:51 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:49:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:51 INFO - 'HOME': '/Users/cltbld', 08:49:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:51 INFO - 'LOGNAME': 'cltbld', 08:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:51 INFO - 'MOZ_NO_REMOTE': '1', 08:49:51 INFO - 'NO_EM_RESTART': '1', 08:49:51 INFO - 'PAGER': '/bin/cat', 08:49:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:51 INFO - 'PWD': '/builds/slave/test', 08:49:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:51 INFO - 'SHELL': '/bin/bash', 08:49:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:51 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:51 INFO - 'USER': 'cltbld', 08:49:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:51 INFO - 'XPC_FLAGS': '0x0', 08:49:51 INFO - 'XPC_SERVICE_NAME': '0', 08:49:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ajv30t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:52 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:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-2K51li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:52 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:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-9XpS4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:52 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:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-uMfXX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:52 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:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-R1S9OG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:52 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:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-_lqwjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:52 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:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-cT9CVG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:52 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:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:52 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bEvILJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-qPAn2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-CvB44D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-7jSWQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-xzTpZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-wmaHA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-XIn8j2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-5JZaOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:53 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:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-XHCaZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:53 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-XI5aJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:56 INFO - Downloading blessings-1.6.tar.gz 08:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:56 INFO - Installing collected packages: blessings 08:49:56 INFO - Running setup.py install for blessings 08:49:56 INFO - Successfully installed blessings 08:49:56 INFO - Cleaning up... 08:49:56 INFO - Return code: 0 08:49:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:57 INFO - Reading from file tmpfile_stdout 08:49:57 INFO - Current package versions: 08:49:57 INFO - blessings == 1.6 08:49:57 INFO - blobuploader == 1.2.4 08:49:57 INFO - docopt == 0.6.1 08:49:57 INFO - functools32 == 3.2.3-2 08:49:57 INFO - jsonschema == 2.5.1 08:49:57 INFO - manifestparser == 1.1 08:49:57 INFO - mozInstall == 1.12 08:49:57 INFO - mozcrash == 1.0 08:49:57 INFO - mozdebug == 0.1 08:49:57 INFO - mozdevice == 0.48 08:49:57 INFO - mozfile == 1.2 08:49:57 INFO - mozhttpd == 0.7 08:49:57 INFO - mozinfo == 0.9 08:49:57 INFO - mozleak == 0.1 08:49:57 INFO - mozlog == 3.4 08:49:57 INFO - moznetwork == 0.27 08:49:57 INFO - mozprocess == 0.23 08:49:57 INFO - mozprofile == 0.28 08:49:57 INFO - mozrunner == 6.13 08:49:57 INFO - mozscreenshot == 0.1 08:49:57 INFO - mozsystemmonitor == 0.3 08:49:57 INFO - moztest == 0.8 08:49:57 INFO - mozversion == 1.4 08:49:57 INFO - psutil == 3.1.1 08:49:57 INFO - requests == 1.2.3 08:49:57 INFO - wsgiref == 0.1.2 08:49:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:57 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:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:57 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:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:57 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:57 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:49:57 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:49:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:57 INFO - 'HOME': '/Users/cltbld', 08:49:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:57 INFO - 'LOGNAME': 'cltbld', 08:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:57 INFO - 'MOZ_NO_REMOTE': '1', 08:49:57 INFO - 'NO_EM_RESTART': '1', 08:49:57 INFO - 'PAGER': '/bin/cat', 08:49:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:57 INFO - 'PWD': '/builds/slave/test', 08:49:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:57 INFO - 'SHELL': '/bin/bash', 08:49:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:57 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:57 INFO - 'USER': 'cltbld', 08:49:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:57 INFO - 'XPC_FLAGS': '0x0', 08:49:57 INFO - 'XPC_SERVICE_NAME': '0', 08:49:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:57 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:49:57 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:49:57 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:49:57 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:49:57 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:49:57 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:49:57 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:49:57 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:49:57 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:49:57 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:49:57 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:49:57 INFO - Cleaning up... 08:49:57 INFO - Return code: 0 08:49:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49:57 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:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:57 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:49:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:57 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 0x10d9ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d975570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4c8cb8550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, '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.8f7U4jDr0V/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.YaELjQJv5H/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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:49:57 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:49:57 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:49:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:49:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:57 INFO - 'HOME': '/Users/cltbld', 08:49:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:57 INFO - 'LOGNAME': 'cltbld', 08:49:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:57 INFO - 'MOZ_NO_REMOTE': '1', 08:49:57 INFO - 'NO_EM_RESTART': '1', 08:49:57 INFO - 'PAGER': '/bin/cat', 08:49:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:57 INFO - 'PWD': '/builds/slave/test', 08:49:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:57 INFO - 'SHELL': '/bin/bash', 08:49:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:49:57 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:49:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:57 INFO - 'USER': 'cltbld', 08:49:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:57 INFO - 'XPC_FLAGS': '0x0', 08:49:57 INFO - 'XPC_SERVICE_NAME': '0', 08:49:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:57 INFO - Cleaning up... 08:49:57 INFO - Return code: 0 08:49:57 INFO - Running post-action listener: _resource_record_post_action 08:49:57 INFO - Running post-action listener: _start_resource_monitoring 08:49:57 INFO - Starting resource monitoring. 08:49:57 INFO - [mozharness: 2017-01-07 16:49:57.764410Z] Finished create-virtualenv step (success) 08:49:57 INFO - [mozharness: 2017-01-07 16:49:57.765396Z] Running install step. 08:49:57 INFO - Running pre-action listener: _resource_record_pre_action 08:49:57 INFO - Running main action method: install 08:49:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:58 INFO - Reading from file tmpfile_stdout 08:49:58 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:58 INFO - Reading from file tmpfile_stdout 08:49:58 INFO - Output received: 08:49:58 INFO - Usage: mozinstall [options] installer 08:49:58 INFO - Options: 08:49:58 INFO - -h, --help show this help message and exit 08:49:58 INFO - -d DEST, --destination=DEST 08:49:58 INFO - Directory to install application into. [default: 08:49:58 INFO - "/builds/slave/test"] 08:49:58 INFO - --app=APP Application being installed. [default: firefox] 08:49:58 INFO - mkdir: /builds/slave/test/build/application 08:49:58 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:49:58 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:50:29 INFO - Reading from file tmpfile_stdout 08:50:29 INFO - Output received: 08:50:29 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:50:29 INFO - Running post-action listener: _resource_record_post_action 08:50:29 INFO - [mozharness: 2017-01-07 16:50:29.304270Z] Finished install step (success) 08:50:29 INFO - [mozharness: 2017-01-07 16:50:29.304409Z] Running run-tests step. 08:50:29 INFO - Running pre-action listener: _resource_record_pre_action 08:50:29 INFO - Running main action method: run_tests 08:50:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:50:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:50:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:50:29 INFO - 2017-01-07 08:50:29.318 screenresolution[1630:9276] starting screenresolution argv=screenresolution get 08:50:29 INFO - 2017-01-07 08:50:29.321 screenresolution[1630:9276] Display 0: 1600x1200x32@60 08:50:29 INFO - 2017-01-07 08:50:29.327 screenresolution[1631:9279] starting screenresolution argv=screenresolution list 08:50:29 INFO - Available Modes on Display 0 08:50:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:50:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:50:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:50:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:50:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:50:29 INFO - Intel Iris: 08:50:29 INFO - Chipset Model: Intel Iris 08:50:29 INFO - Type: GPU 08:50:29 INFO - Bus: Built-In 08:50:29 INFO - VRAM (Dynamic, Max): 1536 MB 08:50:29 INFO - Vendor: Intel (0x8086) 08:50:29 INFO - Device ID: 0x0a2e 08:50:29 INFO - Revision ID: 0x0009 08:50:29 INFO - Displays: 08:50:29 INFO - Display: 08:50:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:50:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:50:29 INFO - Display Serial Number: 1600x1200 60 08:50:29 INFO - Main Display: Yes 08:50:29 INFO - Mirror: Off 08:50:29 INFO - Online: Yes 08:50:29 INFO - Rotation: Supported 08:50:29 INFO - Return code: 0 08:50:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:50:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:50:29 INFO - Python 2.7.3 08:50:29 INFO - Return code: 0 08:50:29 INFO - grabbing minidump binary from tooltool 08:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:29 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 0x10d813d30>, '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 0x10d679db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d80bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:50:29 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:50:29 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:50:29 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:50:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:50:29 INFO - Return code: 0 08:50:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:50:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:50:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:50:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:50:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:50:29 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/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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:50:29 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/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --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:50:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.8f7U4jDr0V/Render', 08:50:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:29 INFO - 'HOME': '/Users/cltbld', 08:50:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:29 INFO - 'LOGNAME': 'cltbld', 08:50:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:50:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:50:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:29 INFO - 'MOZ_NO_REMOTE': '1', 08:50:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:50:29 INFO - 'NO_EM_RESTART': '1', 08:50:29 INFO - 'PAGER': '/bin/cat', 08:50:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:29 INFO - 'PWD': '/builds/slave/test', 08:50:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:50:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:29 INFO - 'SHELL': '/bin/bash', 08:50:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YaELjQJv5H/Listeners', 08:50:29 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 08:50:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:29 INFO - 'USER': 'cltbld', 08:50:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:29 INFO - 'XPC_FLAGS': '0x0', 08:50:29 INFO - 'XPC_SERVICE_NAME': '0', 08:50:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:29 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/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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:50:29 INFO - mozversion application_buildid: 20170107063756 08:50:29 INFO - mozversion application_changeset: 58e5b3c7bdaf6e60a50c7a59c7105a0eb5dd0c2d 08:50:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:50:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:50:29 INFO - mozversion application_name: Firefox 08:50:29 INFO - mozversion application_remotingname: firefox-dev 08:50:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:29 INFO - mozversion application_vendor: Mozilla 08:50:29 INFO - mozversion application_version: 52.0a2 08:50:29 INFO - mozversion platform_buildid: 20170107063756 08:50:29 INFO - mozversion platform_changeset: 58e5b3c7bdaf6e60a50c7a59c7105a0eb5dd0c2d 08:50:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:29 INFO - mozversion platform_version: 52.0a2 08:50:29 INFO - using testdate: 1483807829 08:50:29 INFO - actual date: 1483807829 08:50:29 INFO - starting webserver on 'localhost:49248' 08:50:29 INFO - SUITE-START | Running 2 tests 08:50:29 INFO - TEST-START | dromaeo_css 08:50:29 INFO - operating with platform_type : mac_ 08:50:29 INFO - Initialising browser for dromaeo_css test... 08:50:29 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpQwBZQ7/profile http://localhost:49248/getInfo.html) 08:50:33 INFO - PROCESS | 1641 | 2017-01-07 08:50:33.308 plugin-container[1643:9454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c37, name = 'com.apple.tsm.portname' 08:50:33 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:33 INFO - PROCESS | 1641 | 2017-01-07 08:50:33.311 plugin-container[1643:9454] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b07, name = 'com.apple.CFPasteboardClient' 08:50:33 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:33 INFO - PROCESS | 1641 | 2017-01-07 08:50:33.311 plugin-container[1643:9454] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:33 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 08:50:33 INFO - PROCESS | 1641 | colorDepth:24 08:50:33 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 08:50:33 INFO - PROCESS | 1641 | __metrics 08:50:33 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:50:34 INFO - TEST-INFO | 1641: exit 0 08:50:34 INFO - Browser initialized. 08:50:34 INFO - Running cycle 1/1 for dromaeo_css test... 08:50:34 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpQwBZQ7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:37 INFO - PROCESS | 1646 | 2017-01-07 08:50:37.194 plugin-container[1647:9710] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 08:50:37 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:37 INFO - PROCESS | 1646 | 2017-01-07 08:50:37.195 plugin-container[1647:9710] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 08:50:37 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:50:37 INFO - PROCESS | 1646 | 2017-01-07 08:50:37.195 plugin-container[1647:9710] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:50:46 INFO - PROCESS | 1646 | RSS: Main: 229490688 08:50:46 INFO - PROCESS | 1646 | 08:53:49 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 08:53:51 INFO - PROCESS | 1646 | RSS: Main: 234369024 08:53:51 INFO - PROCESS | 1646 | 08:56:53 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 08:56:54 INFO - PROCESS | 1646 | RSS: Main: 243703808 08:56:54 INFO - PROCESS | 1646 | 08:59:56 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 08:59:57 INFO - PROCESS | 1646 | RSS: Main: 247328768 08:59:57 INFO - PROCESS | 1646 | 09:02:58 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 09:02:59 INFO - PROCESS | 1646 | RSS: Main: 246640640 09:02:59 INFO - PROCESS | 1646 | 09:06:02 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 09:06:03 INFO - PROCESS | 1646 | RSS: Main: 250044416 09:06:03 INFO - PROCESS | 1646 | 09:09:05 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 09:09:06 INFO - PROCESS | 1646 | RSS: Main: 251322368 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - PROCESS | 1646 | __start_tp_report 09:09:06 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 09:09:06 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 09:09:06 INFO - PROCESS | 1646 | |i|pagename|runs| 09:09:06 INFO - PROCESS | 1646 | |0;dojo.html;3258.136593802643;3279.86880524779;3325;3325.584301962254;3330.2340461693793;5528.391876893538;5546.168074788784;5571.052921003434;5617.775288988436;5623.26897503325;4949.901001979958;5096.369161868203;5571.609987300891;5627.859303517414;5640.717964101799;4251.298535741599;5101.872453188663;5141;5197.324347834795;5237.921431178535;5117.334746482952;5135.383753949532;5179.352580927384;5189.455107213745;5198.818041368553;5047.64666473347;5072.289879416885;5101.464346243628;5120.052790233819;5154.845354639367;4027.8388864445387;4283.1433713257475;4531.82172635114;5013.548780609726;5065.265536497224;3682.9263414731554;3771.773693578394;4992.976439829826;5091.821786237464;5115.539601435851;4065.6340929316502;5370.516653501204;5480.890382192374;5621.072523033695;5638.351589567212;4871.8294859679745;6072.240969878765;6109.7690694183475;6116.061877111559;6162.506999440079;6158.6612736299485;6181.567290289681;6189.381061893775;6212.161358216628;6212.689365531705;4883.949950760603;5608.46719561641;5733.684647344395;5747.913781293284;5756;6106.358832322631;6132.936844521141;6135.538906164978;6164.506839452833;6205.720742566596;6019.187409699722;6049.364816694272;6065.727042283108;6094;6094.695265236721;6125.509959203253;6153.876922461525;6153.907691384633;6154.846128846725;6161.199044124235;5941.435563621449;6057.454829065317;6063.757449701962;6081.878362432815;6131.233595800525;5544.77820887168;5823.4467725566;6120.816375508742;6130.448259656563;6210.813675589739;4864.318995340655;4875.561199492063;4883.609311255091;4893.09477746618;4896.387951506062;4270.35944608305;4276.914461710749;4304.526502084768;4343.4136391586735;4386.385905973167;5325.813596524103;5755.136729490526;5756;5765.625234359753;5784.508316793035;6154.7230374633245;6196.3803619638575;6198.318184999646;6211.503079753609;6218.973869311603;5592.3009623797025;5628.690422026746;5645.379008309083;5690.174924635988;5726.226959360433;5284.969430960926;5333.280007199056;5335;5373.274607927958;5398.352197736218;6175.3515880832765;6182.443580077635;6190.164327815777;6225.284092329259;6242.219722534774;6108.664023478574;6112.989854784201;6186.412290832363;6210.223722034746;6213.130161777445;6108.358622344767;6164.8767024658355;6170.938290617037;6205.441510264098;6227.844303892439;5374.11327131019;5898.174255604133;6137.447629713347;6138.048602466625;6164.321924588381;5521.420250873601;6050.2437195350585;6200.410960958701;6202.81391558254;6250.062490626442;5891.086881533369;5995.250593675791;6012.939870601238;6026.975414179512;6039.969800150971;6096.359882212304;6122.448979591858;6124.356942520971;6126.111713801385;6131.785387511416;4735.455422626304;4926.3842019747535;5862.765489380377;5939.67331796738;5961.254843144607;5882.735276912635;5993.910091348546;6003.909941350796;6074.90887636677;6104.542159337979;5825.796097136749;5851.21008663825;5860.677662728421;5864.354920958776;5864.530837533072 09:09:06 INFO - PROCESS | 1646 | |1;ext.html;472.6313475489118;474.62978876476336;501.11553108763064;518.551452993161;531.3066448284986;33462.66537334626;38400.423993640085;39927;40038.799806001;40139;40674.38988415176;41107.38338924918;41178.58821411785;41180.38229426553;41239.17521649565;8682;8951;9274.02622724616;9354.017828128051;9483.33616646835;8707.303415726758;8849.115088491164;9302.441853488812;9377.812443751121;9433.76415589609;8725.99651040129;9330.860037099448;9352.953235233814;9395.34232603718;9420;8866.113388661115;9253.35226534143;9397.966223715386;9401.952990235039;9419.858702119474;7335.926640733578;9273.72178834636;9280.02559731232;9377.015413381565;9706.951465242699;33772.662273377195;37563.62436375656;38821.61178388208;39108.80445597782;39172.60827391718;3299.785513941586;3542.3977923753023;3719.6652301292743;3890.7860067696265;3952.1898010907694;24300.149494767596;29524.70475295241;29589;29837;30553.54169687434;3112.32472618917;3155.936881262362;3189.5534625152495;3231.4506533889294;3246.107320486861;449795;459250;460412;460858;465377;57896.71051644715;58022;58323;58371;58382;52439;60367;60893.69553152294;60942;61090.38909610936;15847.762283565755;15980.280887360097;16007.519774406786;16017.759733604005;16042.75935860963;12318.630441086782;12348.444320005621;12374;12387.69030774238;12422.440990155465;15705.68588628221;16018.679626407407;16078.115703636178;16137.596560085856;16182.948108372919;6046.3046749624355;6046.3349031606485;6046.3651316612;6089.817305480843;6111.480524155497;839.630562552475;841.1966571923798;885.1281487734576;913.6391125505388;924.4083786376724;28827.56758648622;28987.13038608845;28991.855040724662;29120.41759164805;29216.41567168687;858.8153546987428;996.0885789502669;1040.1262939131166;1079.1636481726568;1135.1713249328036;14517.78223326651;14877.256137193313;15012.12348456443;15109.942304038832;15212.315445804965;630373;632755;635986;636049;636070;55481;55487.722561387745;55598.888022240135;55706;55946.720266398406;1323.5499930023645;1342.9059965802496;1352.8579499152645;1355.5865461034282;1359.7416490866703;26054.60918086268;26703;28666.713332866406;29343.413131737674;29352.412951740418;47763.76118119387;48265.276020860416;48342;48355.03289934111;48417.75791121163;484220;485133;485273;490133;490442;37772.811135945245;38077.42883856689;38812.6118738809;38882.2223555533;38996;43772.34341484817;43824.90437739082;43993.340099899164;45050;45461.86345341393;32966.50550241796;36875.63124368722;36935.261294773416;37531.43702844405;38373.61626383812;32625.836870815496;36240.00679962627;36812.63187368092;36842.8157859209;37775.866723998326;35610.75362362305;36070;36579.634203657624;36708.632913670524;36746.448803268504;31129.53305700371;32572.022839314857;33290.33419331552;33617.83191084029;33848.32303353969;22927.58119916525;25026.74973250317;25037.374065647775;25078.749212507642;25382.111626093 09:09:06 INFO - PROCESS | 1646 | |2;jquery.html;369.6876139661986;370.97403181777275;382.89087610031146;386.3412881037831;399.32115403813503;16062.357505699772;18250;19902.303419380325;20200.797992020074;20428;18717.064146792673;19101.23595056196;19410.320638777644;20069.59860802783;20124;5075.086484432801;5334.706591137496;5519;5529.225908372831;5935.080062590306;5954.970225148868;6070.301915279733;6071.483923866476;6073.392660733935;6086.17836592061;5714.51426628737;5950.9107363389585;5984.611000284989;6034;6037.305709843358;5620.269364982567;6018.217631707894;6024.126501657257;6029.939700603026;6043.516518678495;5171.974140129313;5198.792048318062;5331.1470164773455;5335.67985920846;5349.866253343658;10753.78492430147;13172.275524846129;13698.630136986321;18578.90710546452;24961.87519062393;1848.6672398968315;2320.501092265171;2433.0875921529428;2433.829631925766;2699.892004319825;9561.474118923456;10268.07587317145;10275.48622568876;12612.369381530978;14467.638309042253;1594.840863698238;1820.1536285627114;1823.6808558502341;1884.5382881194057;1896.3647178195322;115331.2700309496;115806.84193158045;120828.39585802014;128887;128939.13182604537;9127.178553930178;9909.504524773876;10748.731281718019;11719.296842189497;11774.411279435993;14748.557543273719;14778.926105369404;14803.703925921422;14841.96106272572;14850.183239921693;8309.709160179365;8616.569171541398;8631;8654.826903461895;8676.436031657922;8442.662293508314;8494.915050849537;8499.192576705203;8574.670926006478;8636.870446943301;4510.458744950627;4564.497905230422;4568.908621827545;4580.862574122782;4639.698419602715;1744.3981826269917;1916.4606895863037;1963.9214431422583;1969.2615269274022;1976.575036367188;686.8460985544234;869.6304070769897;945.177695405002;954.5895265036102;1041.7760181561;16421.91789041047;21048.579028419346;21106.683399749018;21247.575048498944;21288.36134915955;808.8382323535317;881.6429346114834;942.5475771629656;952.9190018848476;1002.9147522460672;7998.64006119726;8597.140285971478;10639.202059845542;11056.170787190993;11068.446577671084;516918.40030397906;520204;547836;567081;567962;77700;119966;122105;128293.35853320673;129573.35213324061;904.7738065483629;919.9402038867452;923.1875949164827;929.1544694328264;964.1804466203671;16059.357625694842;17687.93872367662;18131.184096715417;18888.05559722036;19184.712229316294;53574;54007.91984160216;54517;54634.7268263672;55265.72367138138;73235;74222.62888685522;77097.61451192708;77921.61039194767;78145.60927195555;7874.1338452769005;7927.484713493604;7989.880151797844;8179.345652347679;8224.177582241728;9812.65655702047;12232.449539770909;12480.627131015763;12535.93732031334;12653.177543459646;9133.77165570866;10184.185265178916;10352;10473.895261047292;10673.306235094695;8762;10667;10788.838167427652;10930.289531180766;11166.497507612014;11189.776204476027;11277.661670149908;11434.371109589052;11540.192186546861;11600.94199528997;7898.763037108896;7937.8412431752195;8050.591146549447;8431.988161420668;8440.95779521123;8176.182381762014;9616.471094089893;10463.529141188817;10945;11139.443027848738 09:09:06 INFO - PROCESS | 1646 | |3;mootools.html;1360.8503064662884;1413.4346261495407;1427.1294510348685;1710.6236627941864;1750.4836073358358;7529.435292353081;7803.726869559567;8049;8528.275096616788;8532.70135545256;8037.718679846206;8302.501849888995;8330.20863018014;8412.327013838894;8420.536870472122;2034.7863474335204;2265.546890621882;2324.93025209244;2347.4014126397747;2352.8588284702887;1923.9230430782752;1942.9216784684495;2045.0490521907311;2077.356019633911;2338.286822519131;2322.512272422793;2324.32594547581;2337.602607556711;2349.8707571083582;2353.4351755578623;1913.9808601913942;2307.284741730069;2325.4070212095808;2329.860208387502;2331.8484298520543;2338.9298321050223;2361.0791791201445;2369.644553316999;2372.3950392649936;2383.654370116341;8321.126281740451;8345.707900223524;8415.158484151598;8425.831483370359;8441.155884411572;4554.908901821978;5096.464871188509;5241.056609810233;5260.789568417259;5262.473752624707;3286.802791832498;3375.4093033719246;3530.958367281658;3537.557805274341;3544.85820567177;1049.2602715085845;1062.7980683670078;1088.2219213262476;1101.8210514749221;1116.0402054233352;10920.617778377798;10927.945360273226;10977.341359518454;11008.064314533274;11018.173636977179;4564.931526027112;4619.745913974696;4709;4714.198586240347;4815.903681926342;9371.34400591952;9445.433274003444;9452.90547094534;9456.763580910394;9466.952665236768;5236.0575096483;5240.187770895517;5240.737963101829;5243.554297884646;5249.580033597303;4078.5513593505284;4742.644301677388;4752.310914917387;4794.161021821167;4802.327674125625;3807.8857634271017;3817.5037245158505;3902.9219415612097;3909.980450097788;3919.412088186795;1244.0731654917145;1251.4118364368915;1256.5539233571951;1257.0383656502784;1262.236347010064;767.9616019199018;788.2629741192004;858.536390349217;892.5983307511646;924.371427429341;5627.268455100892;5715.371309155989;5763.510101641324;5812.244408226903;5935.821925342247;383.8809968909591;418.58769112424847;427.68351419949636;428.1180767618715;439.4287426345738;3912.217556488714;3914.5106861642294;3932.8230229639735;3965.524137103566;3984.2629113614075;9826.164775993977;9921.454320012468;10158.441285729208;10241;10273.075423211798;8431.620577074047;8771.429857059398;10130.986901309958;10167.847482287772;10173.033561811617;7750.651220695082;7764.650590723318;7834.960825195952;7887.763367098996;7922.207779222147;5551.195076713934;5656.688882111359;5690.573207009408;5734;5803.100519419497;9239.307051971264;9242.983271840227;9271.397359171633;9342.299327550325;9397.671081512115;7552.584607846401;9472.384295020802;9563.234941204548;9582.233421326138;9584.76038099053;6583.769568065095;6782.423494003065;6987.860242795217;7123.501354905107;7162.2121566628675;7884.093329267208;7986.2013798622;8002.83994320122;8021.959890200512;8028.799280017813;7036.226015138228;7148.714051438093;7164.749233776793;7178.138623365229;7267.563946163247;6927.930720692729;7011.894821577578;7033.050538177281;7109.395701365442;7141.321574450419;6770.729170833309;6804.081449004321;6829;6833.624150671761;6840.452763778985;4918.778654960463;4937.23472861707;4962.082014827269;4973.602111831117;5002.124628189979;3960.702947278908;4351.499577548496;4839.104765618372;4915.97542012302;5047.596192304681 09:09:06 INFO - PROCESS | 1646 | |4;prototype.html;250.77430312718556;300.6572507341631;300.9413164432935;309.35036423510616;315.99368012639746;4462.955370446294;4889.242167464049;4962.7915577568;5059.266406371074;5411.621186516941;5237.292965449673;5338.439463856297;5391.487808658181;5399.325084364455;5416.4312747161475;4113.670906327501;4119.794010299481;4122.299209134454;4122.917541649165;4132.669386449092;3845.711571632125;4062.9796851015703;4101.876943691694;4138.97930510347;4168.749875007494;3399.252164523813;3500.7549471537004;3726.7577607455564;4052.8581499647516;4071.43749219433;3526.5188620779336;3869.7616762635967;4078.449409329732;4106.322456794625;4113.239050775586;2973.8810447582073;3560.893173204808;3564.1624218308675;3566;3576.515745965421;5046.697198168122;5191.610629202825;5216.374035115772;5237.3764133118475;5314.627976041678;4103.4870641169855;4560.019595786922;4582.564656357641;4682.250839865639;4699.483056863743;5370.556396839429;5672.362351506528;5708.144852922816;5730.541556675456;5730.914036289459;3298.208429976812;3578.6779189873037;3805.1818858945185;3853.537575490931;3886.319894018564;5381.677099374011;5485.670859748428;5678.346990096151;5964.761409543657;5971.70141492928;3461.567304086989;3535.9745673754323;3907.3943538751537;3914.727713493069;3917.7061720371084;3584.211473475831;3753.9249215016093;3941.8765651789104;3967.9286592619833;3992.4210989406956;4103.425520427142;4596.47140578831;4655.580997710223;4664.86005419838;4670.673052886334;5644.939597047049;5753.079507278899;5807.767689292466;5818.738156782955;5830.970845145832;1170.9004734597484;1264.6648638110908;1280.507004803139;1289.9806502902277;1294.851092124399;845.4420082745359;867.7440155154245;882.056199866137;883.4301875290402;887.3699673231948;4637.95362046382;4791.688540244873;4868.659193856396;5104.770285337168;5105.310783044316;4662.650301227353;5577.135543990688;5604.842135777673;5613.747381367848;5623.662580245198;4487.932681009787;4540.296254080623;5050.570701490415;5127.79488820443;5140.897182056413;2495.803692750368;2852.87162077707;2890.9329829172243;2961.9633128404885;2996.445657553316;5351.010063138332;5595.796903665731;5857.648541087548;5860.208871802337;5874.412558744177;5105.846824595268;5731.111677689964;5904.557158213065;6055.909161362583;6137.754489820357;5089.363829521384;5253.422123566328;5749.91375129365;6016.127661489059;6071.878562428727;5170.5346518813485;5647.198987316605;5782.7686892523825;5964;6010.939890601213;4894.853154405374;5092.872678183075;5839.810071223291;5907.586468947133;5919;6045.486133678686;6055.485283750931;6063.878722425438;6083.452489275986;6097.329293777592;3618.7105031597935;3835.5972622874197;4046.433499310158;4073.79631018442;4105.91788164241;5684.886302273849;5923.229980102731;5927.8221653350465;5936.554758393051;5958.285005799332;4982.352294201731;5458.2904222450825;5593.524550413261;5655.802046928337;5703.515201207943;5118.206677964921;5473.014857325719;5598.972005140111;5617.32592088952;5801.564882633904;4990.727364522024;5384.507802041973;5636;5638.096380364033;5751.641826850971;5847.561432892465;5851.1515830205235;5878.912401205791;5879.911801323069;5883.735231914488;5299.49654782795;5677.375488696158;5707.258056452634;5717.7141142941855;5745.396733343106 09:09:06 INFO - PROCESS | 1646 | |5;yui.html;682.7985744205458;712.5510928115286;735.0481126940612;737.513241260768;770.730244414455;13452.7309453811;13799.172049677027;13818.93090534546;13831.515896943596;13850.445982160714;11827.645170644873;13087;13197.27414992175;14646.194459304734;14650.853491465083;780.4848799792153;793.4802704228711;802.8956235689352;803.4857691077715;805.1023109233192;695.6104581434413;812.1107387410776;816.0656048824093;816.3346872299087;818.6848063495568;847.8643417053273;862.5687156421789;863.7106569299261;865.6148014133681;866.2723312417568;847.449158047268;853.9914600853974;857.2199298638241;858.1332853817627;860.1484530315006;627.6955676496896;628.8427893026743;637.2162240444234;638.9201349831271;640.9871802563922;13337.86662133376;13370;13409.396577154052;13411.530596429078;13422.395992180376;13073.346332683328;13324.800127998087;13546.864531354659;13617.387217575233;13723.176609403468;11124.276922000045;11124.61063862769;11308.604198853001;11314.547418103266;11372.829407558893;3567.571891373052;3588.318219538279;3602.585702644177;3623.565172179355;3634.3821550336506;69876.65061674659;76523;76612.23387766107;91668.54165729128;93764;10382.013708697812;10451;10555.577776888991;10558.947205264078;10596.417197054236;19560.804391956182;19601.31395401154;19647.115879785444;19756.802431975782;19762.604747904963;7417.221191774786;7554.055743032121;7586.658600362997;7650.1202361728;7776.688932442639;3958.524977002778;4055.168690418457;4149.232392007489;4278.122984788111;4289.592488713567;3360.7311415086733;3421.5894092709036;3473.6873681368797;3474.7915125092577;3478.1826270826336;555.4057158840083;558.9077802162678;570.0053406805151;640.7565125252468;649.701137476766;498.91019616468657;504.83845169545395;569.2997612936111;570.5948776368824;607.401709316323;8711.91101112361;9086.727398178065;9241.306901982378;9365.812683746288;9585.329026968186;613.1446631948407;617.5430181665625;629.8662407666183;679.239252037711;683.6889215406978;3710.777353358807;3777.471154038437;4524.615407690383;4530.139273538017;4615.100055489214;96117.51941240249;107688;107779;108579.45710271556;112431;64776.35223647797;82050.71797128048;86558;100974;102279.46580801294;82393;82748.17251827526;84036.57981710053;84067;85468.7179692305;9398.248140148908;9877.308588398742;9887.110160085627;9896.505174741378;9905;51582;53171.40485785433;53781.84017071354;54870;56128;64229.678851607314;67001;75494.24505755091;77277.61361193158;78385.432291355;12094.213876098025;12403.503859845505;12416.255024698547;12557;12899.613011609667;11087.390193539433;13562.321883905568;14309.499167529086;14384.928075359556;15098.39606415775;13069.869301306866;13157.60527184186;13454.798178027142;13850.930745346208;14036.368363423466;12183.939080304543;12822.615321540368;13824.032317737661;13851;14301.64245893861;10425.63510277167;11703;12408.75182496363;12587.622371328875;13657.248851312876;10535.315204511682;10693.984071513194;11444.771104577696;11563.595274165364;11681.897258493553;10524.736881578021;10577.206709496664;11326.037286830382;11491.597794077166;11559.653210403701 09:09:06 INFO - PROCESS | 1646 | __end_tp_report 09:09:06 INFO - PROCESS | 1646 | __start_cc_report 09:09:06 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,376 09:09:06 INFO - PROCESS | 1646 | __end_cc_report 09:09:06 INFO - PROCESS | 1646 | __startTimestamp1483808946039__endTimestamp 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - PROCESS | 1646 | ------- Summary: start ------- 09:09:06 INFO - PROCESS | 1646 | Number of tests: 6 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5573.22 Median:5825.80 stddev:713.82 (12.3%) stddev-sans-first:692.52 09:09:06 INFO - PROCESS | 1646 | Values: 3258.1 3279.9 3325.0 3325.6 3330.2 5528.4 5546.2 5571.1 5617.8 5623.3 4949.9 5096.4 5571.6 5627.9 5640.7 4251.3 5101.9 5141.0 5197.3 5237.9 5117.3 5135.4 5179.4 5189.5 5198.8 5047.6 5072.3 5101.5 5120.1 5154.8 4027.8 4283.1 4531.8 5013.5 5065.3 3682.9 3771.8 4993.0 5091.8 5115.5 4065.6 5370.5 5480.9 5621.1 5638.4 4871.8 6072.2 6109.8 6116.1 6162.5 6158.7 6181.6 6189.4 6212.2 6212.7 4883.9 5608.5 5733.7 5747.9 5756.0 6106.4 6132.9 6135.5 6164.5 6205.7 6019.2 6049.4 6065.7 6094.0 6094.7 6125.5 6153.9 6153.9 6154.8 6161.2 5941.4 6057.5 6063.8 6081.9 6131.2 5544.8 5823.4 6120.8 6130.4 6210.8 4864.3 4875.6 4883.6 4893.1 4896.4 4270.4 4276.9 4304.5 4343.4 4386.4 5325.8 5755.1 5756.0 5765.6 5784.5 6154.7 6196.4 6198.3 6211.5 6219.0 5592.3 5628.7 5645.4 5690.2 5726.2 5285.0 5333.3 5335.0 5373.3 5398.4 6175.4 6182.4 6190.2 6225.3 6242.2 6108.7 6113.0 6186.4 6210.2 6213.1 6108.4 6164.9 6170.9 6205.4 6227.8 5374.1 5898.2 6137.4 6138.0 6164.3 5521.4 6050.2 6200.4 6202.8 6250.1 5891.1 5995.3 6012.9 6027.0 6040.0 6096.4 6122.4 6124.4 6126.1 6131.8 4735.5 4926.4 5862.8 5939.7 5961.3 5882.7 5993.9 6003.9 6074.9 6104.5 5825.8 5851.2 5860.7 5864.4 5864.5 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:65996.11 Median:39982.90 stddev:142148.94 (355.5%) stddev-sans-first:142462.57 09:09:06 INFO - PROCESS | 1646 | Values: 472.6 474.6 501.1 518.6 531.3 33462.7 38400.4 39927.0 40038.8 40139.0 40674.4 41107.4 41178.6 41180.4 41239.2 8682.0 8951.0 9274.0 9354.0 9483.3 8707.3 8849.1 9302.4 9377.8 9433.8 8726.0 9330.9 9353.0 9395.3 9420.0 8866.1 9253.4 9398.0 9402.0 9419.9 7335.9 9273.7 9280.0 9377.0 9707.0 33772.7 37563.6 38821.6 39108.8 39172.6 3299.8 3542.4 3719.7 3890.8 3952.2 24300.1 29524.7 29589.0 29837.0 30553.5 3112.3 3155.9 3189.6 3231.5 3246.1 449795.0 459250.0 460412.0 460858.0 465377.0 57896.7 58022.0 58323.0 58371.0 58382.0 52439.0 60367.0 60893.7 60942.0 61090.4 15847.8 15980.3 16007.5 16017.8 16042.8 12318.6 12348.4 12374.0 12387.7 12422.4 15705.7 16018.7 16078.1 16137.6 16182.9 6046.3 6046.3 6046.4 6089.8 6111.5 839.6 841.2 885.1 913.6 924.4 28827.6 28987.1 28991.9 29120.4 29216.4 858.8 996.1 1040.1 1079.2 1135.2 14517.8 14877.3 15012.1 15109.9 15212.3 630373.0 632755.0 635986.0 636049.0 636070.0 55481.0 55487.7 55598.9 55706.0 55946.7 1323.5 1342.9 1352.9 1355.6 1359.7 26054.6 26703.0 28666.7 29343.4 29352.4 47763.8 48265.3 48342.0 48355.0 48417.8 484220.0 485133.0 485273.0 490133.0 490442.0 37772.8 38077.4 38812.6 38882.2 38996.0 43772.3 43824.9 43993.3 45050.0 45461.9 32966.5 36875.6 36935.3 37531.4 38373.6 32625.8 36240.0 36812.6 36842.8 37775.9 35610.8 36070.0 36579.6 36708.6 36746.4 31129.5 32572.0 33290.3 33617.8 33848.3 22927.6 25026.7 25037.4 25078.7 25382.1 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:32819.11 Median:2454.89 stddev:91302.86 (3719.2%) stddev-sans-first:91526.48 09:09:06 INFO - PROCESS | 1646 | Values: 369.7 371.0 382.9 386.3 399.3 16062.4 18250.0 19902.3 20200.8 20428.0 18717.1 19101.2 19410.3 20069.6 20124.0 5075.1 5334.7 5519.0 5529.2 5935.1 5955.0 6070.3 6071.5 6073.4 6086.2 5714.5 5950.9 5984.6 6034.0 6037.3 5620.3 6018.2 6024.1 6029.9 6043.5 5172.0 5198.8 5331.1 5335.7 5349.9 10753.8 13172.3 13698.6 18578.9 24961.9 1848.7 2320.5 2433.1 2433.8 2699.9 9561.5 10268.1 10275.5 12612.4 14467.6 1594.8 1820.2 1823.7 1884.5 1896.4 115331.3 115806.8 120828.4 128887.0 128939.1 9127.2 9909.5 10748.7 11719.3 11774.4 14748.6 14778.9 14803.7 14842.0 14850.2 8309.7 8616.6 8631.0 8654.8 8676.4 8442.7 8494.9 8499.2 8574.7 8636.9 4510.5 4564.5 4568.9 4580.9 4639.7 1744.4 1916.5 1963.9 1969.3 1976.6 686.8 869.6 945.2 954.6 1041.8 16421.9 21048.6 21106.7 21247.6 21288.4 808.8 881.6 942.5 952.9 1002.9 7998.6 8597.1 10639.2 11056.2 11068.4 516918.4 520204.0 547836.0 567081.0 567962.0 77700.0 119966.0 122105.0 128293.4 129573.4 904.8 919.9 923.2 929.2 964.2 16059.4 17687.9 18131.2 18888.1 19184.7 53574.0 54007.9 54517.0 54634.7 55265.7 73235.0 74222.6 77097.6 77921.6 78145.6 7874.1 7927.5 7989.9 8179.3 8224.2 9812.7 12232.4 12480.6 12535.9 12653.2 9133.8 10184.2 10352.0 10473.9 10673.3 8762.0 10667.0 10788.8 10930.3 11166.5 11189.8 11277.7 11434.4 11540.2 11600.9 7898.8 7937.8 8050.6 8432.0 8441.0 8176.2 9616.5 10463.5 10945.0 11139.4 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5427.35 Median:5024.86 stddev:2995.43 (59.6%) stddev-sans-first:2988.24 09:09:06 INFO - PROCESS | 1646 | Values: 1360.9 1413.4 1427.1 1710.6 1750.5 7529.4 7803.7 8049.0 8528.3 8532.7 8037.7 8302.5 8330.2 8412.3 8420.5 2034.8 2265.5 2324.9 2347.4 2352.9 1923.9 1942.9 2045.0 2077.4 2338.3 2322.5 2324.3 2337.6 2349.9 2353.4 1914.0 2307.3 2325.4 2329.9 2331.8 2338.9 2361.1 2369.6 2372.4 2383.7 8321.1 8345.7 8415.2 8425.8 8441.2 4554.9 5096.5 5241.1 5260.8 5262.5 3286.8 3375.4 3531.0 3537.6 3544.9 1049.3 1062.8 1088.2 1101.8 1116.0 10920.6 10927.9 10977.3 11008.1 11018.2 4564.9 4619.7 4709.0 4714.2 4815.9 9371.3 9445.4 9452.9 9456.8 9467.0 5236.1 5240.2 5240.7 5243.6 5249.6 4078.6 4742.6 4752.3 4794.2 4802.3 3807.9 3817.5 3902.9 3910.0 3919.4 1244.1 1251.4 1256.6 1257.0 1262.2 768.0 788.3 858.5 892.6 924.4 5627.3 5715.4 5763.5 5812.2 5935.8 383.9 418.6 427.7 428.1 439.4 3912.2 3914.5 3932.8 3965.5 3984.3 9826.2 9921.5 10158.4 10241.0 10273.1 8431.6 8771.4 10131.0 10167.8 10173.0 7750.7 7764.7 7835.0 7887.8 7922.2 5551.2 5656.7 5690.6 5734.0 5803.1 9239.3 9243.0 9271.4 9342.3 9397.7 7552.6 9472.4 9563.2 9582.2 9584.8 6583.8 6782.4 6987.9 7123.5 7162.2 7884.1 7986.2 8002.8 8022.0 8028.8 7036.2 7148.7 7164.7 7178.1 7267.6 6927.9 7011.9 7033.1 7109.4 7141.3 6770.7 6804.1 6829.0 6833.6 6840.5 4918.8 4937.2 4962.1 4973.6 5002.1 3960.7 4351.5 4839.1 4916.0 5047.6 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4583.19 Median:5123.00 stddev:1440.37 (28.1%) stddev-sans-first:1407.23 09:09:06 INFO - PROCESS | 1646 | Values: 250.8 300.7 300.9 309.4 316.0 4463.0 4889.2 4962.8 5059.3 5411.6 5237.3 5338.4 5391.5 5399.3 5416.4 4113.7 4119.8 4122.3 4122.9 4132.7 3845.7 4063.0 4101.9 4139.0 4168.7 3399.3 3500.8 3726.8 4052.9 4071.4 3526.5 3869.8 4078.4 4106.3 4113.2 2973.9 3560.9 3564.2 3566.0 3576.5 5046.7 5191.6 5216.4 5237.4 5314.6 4103.5 4560.0 4582.6 4682.3 4699.5 5370.6 5672.4 5708.1 5730.5 5730.9 3298.2 3578.7 3805.2 3853.5 3886.3 5381.7 5485.7 5678.3 5964.8 5971.7 3461.6 3536.0 3907.4 3914.7 3917.7 3584.2 3753.9 3941.9 3967.9 3992.4 4103.4 4596.5 4655.6 4664.9 4670.7 5644.9 5753.1 5807.8 5818.7 5831.0 1170.9 1264.7 1280.5 1290.0 1294.9 845.4 867.7 882.1 883.4 887.4 4638.0 4791.7 4868.7 5104.8 5105.3 4662.7 5577.1 5604.8 5613.7 5623.7 4487.9 4540.3 5050.6 5127.8 5140.9 2495.8 2852.9 2890.9 2962.0 2996.4 5351.0 5595.8 5857.6 5860.2 5874.4 5105.8 5731.1 5904.6 6055.9 6137.8 5089.4 5253.4 5749.9 6016.1 6071.9 5170.5 5647.2 5782.8 5964.0 6010.9 4894.9 5092.9 5839.8 5907.6 5919.0 6045.5 6055.5 6063.9 6083.5 6097.3 3618.7 3835.6 4046.4 4073.8 4105.9 5684.9 5923.2 5927.8 5936.6 5958.3 4982.4 5458.3 5593.5 5655.8 5703.5 5118.2 5473.0 5599.0 5617.3 5801.6 4990.7 5384.5 5636.0 5638.1 5751.6 5847.6 5851.2 5878.9 5879.9 5883.7 5299.5 5677.4 5707.3 5717.7 5745.4 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:19920.66 Median:4213.68 stddev:28773.59 (682.9%) stddev-sans-first:28818.05 09:09:06 INFO - PROCESS | 1646 | Values: 682.8 712.6 735.0 737.5 770.7 13452.7 13799.2 13818.9 13831.5 13850.4 11827.6 13087.0 13197.3 14646.2 14650.9 780.5 793.5 802.9 803.5 805.1 695.6 812.1 816.1 816.3 818.7 847.9 862.6 863.7 865.6 866.3 847.4 854.0 857.2 858.1 860.1 627.7 628.8 637.2 638.9 641.0 13337.9 13370.0 13409.4 13411.5 13422.4 13073.3 13324.8 13546.9 13617.4 13723.2 11124.3 11124.6 11308.6 11314.5 11372.8 3567.6 3588.3 3602.6 3623.6 3634.4 69876.7 76523.0 76612.2 91668.5 93764.0 10382.0 10451.0 10555.6 10558.9 10596.4 19560.8 19601.3 19647.1 19756.8 19762.6 7417.2 7554.1 7586.7 7650.1 7776.7 3958.5 4055.2 4149.2 4278.1 4289.6 3360.7 3421.6 3473.7 3474.8 3478.2 555.4 558.9 570.0 640.8 649.7 498.9 504.8 569.3 570.6 607.4 8711.9 9086.7 9241.3 9365.8 9585.3 613.1 617.5 629.9 679.2 683.7 3710.8 3777.5 4524.6 4530.1 4615.1 96117.5 107688.0 107779.0 108579.5 112431.0 64776.4 82050.7 86558.0 100974.0 102279.5 82393.0 82748.2 84036.6 84067.0 85468.7 9398.2 9877.3 9887.1 9896.5 9905.0 51582.0 53171.4 53781.8 54870.0 56128.0 64229.7 67001.0 75494.2 77277.6 78385.4 12094.2 12403.5 12416.3 12557.0 12899.6 11087.4 13562.3 14309.5 14384.9 15098.4 13069.9 13157.6 13454.8 13850.9 14036.4 12183.9 12822.6 13824.0 13851.0 14301.6 10425.6 11703.0 12408.8 12587.6 13657.2 10535.3 10694.0 11444.8 11563.6 11681.9 10524.7 10577.2 11326.0 11491.6 11559.7 09:09:06 INFO - PROCESS | 1646 | -------- Summary: end -------- 09:09:06 INFO - PROCESS | 1646 | 09:09:06 INFO - TEST-INFO | 1646: exit 0 09:09:06 INFO - TEST-OK | dromaeo_css | took 1116794ms 09:09:06 INFO - TEST-START | kraken 09:09:06 INFO - operating with platform_type : mac_ 09:09:06 INFO - Initialising browser for kraken test... 09:09:06 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpeR6o7Q/profile http://localhost:49248/getInfo.html) 09:09:09 INFO - PROCESS | 1653 | 2017-01-07 09:09:09.621 plugin-container[1654:11866] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 09:09:09 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:09:09 INFO - PROCESS | 1653 | 2017-01-07 09:09:09.624 plugin-container[1654:11866] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:09:09 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:09:09 INFO - PROCESS | 1653 | 2017-01-07 09:09:09.624 plugin-container[1654:11866] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:09:09 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 09:09:09 INFO - PROCESS | 1653 | colorDepth:24 09:09:09 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 09:09:09 INFO - PROCESS | 1653 | __metrics 09:09:09 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 09:09:10 INFO - TEST-INFO | 1653: exit 0 09:09:10 INFO - Browser initialized. 09:09:10 INFO - Running cycle 1/1 for kraken test... 09:09:10 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpeR6o7Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:13 INFO - PROCESS | 1655 | 2017-01-07 09:09:13.487 plugin-container[1656:11983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8437, name = 'com.apple.tsm.portname' 09:09:13 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:09:13 INFO - PROCESS | 1655 | 2017-01-07 09:09:13.487 plugin-container[1656:11983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8617, name = 'com.apple.CFPasteboardClient' 09:09:13 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:09:13 INFO - PROCESS | 1655 | 2017-01-07 09:09:13.487 plugin-container[1656:11983] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:09:23 INFO - PROCESS | 1655 | RSS: Main: 229097472 09:09:23 INFO - PROCESS | 1655 | 09:09:25 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 09:09:26 INFO - PROCESS | 1655 | RSS: Main: 241393664 09:09:26 INFO - PROCESS | 1655 | 09:09:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 09:09:30 INFO - PROCESS | 1655 | RSS: Main: 248954880 09:09:30 INFO - PROCESS | 1655 | 09:09:33 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 09:09:33 INFO - PROCESS | 1655 | RSS: Main: 252981248 09:09:33 INFO - PROCESS | 1655 | 09:09:35 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 09:09:36 INFO - PROCESS | 1655 | RSS: Main: 252928000 09:09:36 INFO - PROCESS | 1655 | 09:09:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:39 INFO - PROCESS | 1655 | RSS: Main: 253026304 09:09:39 INFO - PROCESS | 1655 | 09:09:42 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:42 INFO - PROCESS | 1655 | RSS: Main: 258678784 09:09:42 INFO - PROCESS | 1655 | 09:09:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:46 INFO - PROCESS | 1655 | RSS: Main: 257769472 09:09:46 INFO - PROCESS | 1655 | 09:09:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 09:09:50 INFO - PROCESS | 1655 | RSS: Main: 258064384 09:09:50 INFO - PROCESS | 1655 | 09:09:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:52 INFO - PROCESS | 1655 | RSS: Main: 256897024 09:09:52 INFO - PROCESS | 1655 | 09:09:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:55 INFO - PROCESS | 1655 | RSS: Main: 256753664 09:09:55 INFO - PROCESS | 1655 | 09:09:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:57 INFO - PROCESS | 1655 | RSS: Main: 257486848 09:09:57 INFO - PROCESS | 1655 | 09:10:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:10:01 INFO - PROCESS | 1655 | RSS: Main: 257191936 09:10:01 INFO - PROCESS | 1655 | 09:10:04 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:10:05 INFO - PROCESS | 1655 | RSS: Main: 257400832 09:10:05 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 09:10:07 INFO - PROCESS | 1655 | RSS: Main: 258842624 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | __start_tp_report 09:10:07 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:10:07 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:10:07 INFO - PROCESS | 1655 | |i|pagename|runs| 09:10:07 INFO - PROCESS | 1655 | |0;ai-astar;116;118;116;119;136;167;120;121;119;120 09:10:07 INFO - PROCESS | 1655 | |1;audio-beat-detection;147;111;117;149;142;149;104;133;175;147 09:10:07 INFO - PROCESS | 1655 | |2;audio-dft;196;199;196;200;197;194;189;196;199;195 09:10:07 INFO - PROCESS | 1655 | |3;audio-fft;84;58;62;85;62;60;61;59;87;72 09:10:07 INFO - PROCESS | 1655 | |4;audio-oscillator;86;71;90;73;90;101;119;105;74;87 09:10:07 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;124;86;90;86;88;86;104;84;77;131 09:10:07 INFO - PROCESS | 1655 | |6;imaging-darkroom;151;96;95;127;151;101;97;151;93;96 09:10:07 INFO - PROCESS | 1655 | |7;imaging-desaturate;91;134;140;116;87;90;90;92;121;117 09:10:07 INFO - PROCESS | 1655 | |8;json-parse-financial;58;57;58;60;60;62;60;60;68;79 09:10:07 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;71;79;70;75;71;61;59;80;99;101 09:10:07 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;92;95;85;82;86;84;84;86;84;83 09:10:07 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;145;146;143;162;161;154;163;146;289;172 09:10:07 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;166;194;229;160;222;210;169;167;170;169 09:10:07 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;67;74;72;89;65;83;67;64;83;94 09:10:07 INFO - PROCESS | 1655 | __end_tp_report 09:10:07 INFO - PROCESS | 1655 | __start_cc_report 09:10:07 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,982 09:10:07 INFO - PROCESS | 1655 | __end_cc_report 09:10:07 INFO - PROCESS | 1655 | __startTimestamp1483809007431__endTimestamp 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:10:07 INFO - PROCESS | 1655 | Number of tests: 14 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:125.20 Median:120.00 stddev:15.75 (13.1%) stddev-sans-first:16.35 09:10:07 INFO - PROCESS | 1655 | Values: 116.0 118.0 116.0 119.0 136.0 167.0 120.0 121.0 119.0 120.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:147.00 stddev:21.44 (14.6%) stddev-sans-first:22.46 09:10:07 INFO - PROCESS | 1655 | Values: 147.0 111.0 117.0 149.0 142.0 149.0 104.0 133.0 175.0 147.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:196.10 Median:196.50 stddev:3.14 (1.6%) stddev-sans-first:3.33 09:10:07 INFO - PROCESS | 1655 | Values: 196.0 199.0 196.0 200.0 197.0 194.0 189.0 196.0 199.0 195.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:69.00 Median:67.00 stddev:11.92 (17.8%) stddev-sans-first:11.34 09:10:07 INFO - PROCESS | 1655 | Values: 84.0 58.0 62.0 85.0 62.0 60.0 61.0 59.0 87.0 72.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:80.00 stddev:15.33 (19.2%) stddev-sans-first:16.21 09:10:07 INFO - PROCESS | 1655 | Values: 86.0 71.0 90.0 73.0 90.0 101.0 119.0 105.0 74.0 87.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:86.00 stddev:18.19 (21.1%) stddev-sans-first:16.13 09:10:07 INFO - PROCESS | 1655 | Values: 124.0 86.0 90.0 86.0 88.0 86.0 104.0 84.0 77.0 131.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:115.80 Median:94.00 stddev:26.14 (27.8%) stddev-sans-first:24.43 09:10:07 INFO - PROCESS | 1655 | Values: 151.0 96.0 95.0 127.0 151.0 101.0 97.0 151.0 93.0 96.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:107.80 Median:88.50 stddev:20.13 (22.7%) stddev-sans-first:20.41 09:10:07 INFO - PROCESS | 1655 | Values: 91.0 134.0 140.0 116.0 87.0 90.0 90.0 92.0 121.0 117.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:60.00 stddev:6.65 (11.1%) stddev-sans-first:6.87 09:10:07 INFO - PROCESS | 1655 | Values: 58.0 57.0 58.0 60.0 60.0 62.0 60.0 60.0 68.0 79.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:76.60 Median:73.00 stddev:14.05 (19.2%) stddev-sans-first:14.75 09:10:07 INFO - PROCESS | 1655 | Values: 71.0 79.0 70.0 75.0 71.0 61.0 59.0 80.0 99.0 101.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:85.50 stddev:4.15 (4.9%) stddev-sans-first:3.81 09:10:07 INFO - PROCESS | 1655 | Values: 92.0 95.0 85.0 82.0 86.0 84.0 84.0 86.0 84.0 83.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:168.10 Median:161.50 stddev:43.57 (27.0%) stddev-sans-first:45.40 09:10:07 INFO - PROCESS | 1655 | Values: 145.0 146.0 143.0 162.0 161.0 154.0 163.0 146.0 289.0 172.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:182.00 stddev:25.94 (14.3%) stddev-sans-first:26.52 09:10:07 INFO - PROCESS | 1655 | Values: 166.0 194.0 229.0 160.0 222.0 210.0 169.0 167.0 170.0 169.0 09:10:07 INFO - PROCESS | 1655 | 09:10:07 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:78.50 stddev:10.74 (13.7%) stddev-sans-first:10.91 09:10:07 INFO - PROCESS | 1655 | Values: 67.0 74.0 72.0 89.0 65.0 83.0 67.0 64.0 83.0 94.0 09:10:07 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:10:07 INFO - PROCESS | 1655 | 09:10:08 INFO - TEST-INFO | 1655: exit 0 09:10:08 INFO - TEST-OK | kraken | took 61759ms 09:10:08 INFO - SUITE-END | took 1178s 09:10:08 INFO - Completed test suite (00:19:38) 09:10:08 INFO - javascript benchmark 09:10:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7068.01310094299, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3258.136593802643, 3279.86880524779, 3325.0, 3325.584301962254, 3330.2340461693793, 5528.391876893538, 5546.168074788784, 5571.052921003434, 5617.775288988436, 5623.26897503325, 4949.901001979958, 5096.369161868203, 5571.609987300891, 5627.859303517414, 5640.717964101799, 4251.298535741599, 5101.872453188663, 5141.0, 5197.324347834795, 5237.921431178535, 5117.334746482952, 5135.383753949532, 5179.352580927384, 5189.455107213745, 5198.818041368553, 5047.64666473347, 5072.289879416885, 5101.464346243628, 5120.052790233819, 5154.845354639367, 4027.8388864445387, 4283.1433713257475, 4531.82172635114, 5013.548780609726, 5065.265536497224, 3682.9263414731554, 3771.773693578394, 4992.976439829826, 5091.821786237464, 5115.539601435851, 4065.6340929316502, 5370.516653501204, 5480.890382192374, 5621.072523033695, 5638.351589567212, 4871.8294859679745, 6072.240969878765, 6109.7690694183475, 6116.061877111559, 6162.506999440079, 6158.6612736299485, 6181.567290289681, 6189.381061893775, 6212.161358216628, 6212.689365531705, 4883.949950760603, 5608.46719561641, 5733.684647344395, 5747.913781293284, 5756.0, 6106.358832322631, 6132.936844521141, 6135.538906164978, 6164.506839452833, 6205.720742566596, 6019.187409699722, 6049.364816694272, 6065.727042283108, 6094.0, 6094.695265236721, 6125.509959203253, 6153.876922461525, 6153.907691384633, 6154.846128846725, 6161.199044124235, 5941.435563621449, 6057.454829065317, 6063.757449701962, 6081.878362432815, 6131.233595800525, 5544.77820887168, 5823.4467725566, 6120.816375508742, 6130.448259656563, 6210.813675589739, 4864.318995340655, 4875.561199492063, 4883.609311255091, 4893.09477746618, 4896.387951506062, 4270.35944608305, 4276.914461710749, 4304.526502084768, 4343.4136391586735, 4386.385905973167, 5325.813596524103, 5755.136729490526, 5756.0, 5765.625234359753, 5784.508316793035, 6154.7230374633245, 6196.3803619638575, 6198.318184999646, 6211.503079753609, 6218.973869311603, 5592.3009623797025, 5628.690422026746, 5645.379008309083, 5690.174924635988, 5726.226959360433, 5284.969430960926, 5333.280007199056, 5335.0, 5373.274607927958, 5398.352197736218, 6175.3515880832765, 6182.443580077635, 6190.164327815777, 6225.284092329259, 6242.219722534774, 6108.664023478574, 6112.989854784201, 6186.412290832363, 6210.223722034746, 6213.130161777445, 6108.358622344767, 6164.8767024658355, 6170.938290617037, 6205.441510264098, 6227.844303892439, 5374.11327131019, 5898.174255604133, 6137.447629713347, 6138.048602466625, 6164.321924588381, 5521.420250873601, 6050.2437195350585, 6200.410960958701, 6202.81391558254, 6250.062490626442, 5891.086881533369, 5995.250593675791, 6012.939870601238, 6026.975414179512, 6039.969800150971, 6096.359882212304, 6122.448979591858, 6124.356942520971, 6126.111713801385, 6131.785387511416, 4735.455422626304, 4926.3842019747535, 5862.765489380377, 5939.67331796738, 5961.254843144607, 5882.735276912635, 5993.910091348546, 6003.909941350796, 6074.90887636677, 6104.542159337979, 5825.796097136749, 5851.21008663825, 5860.677662728421, 5864.354920958776, 5864.530837533072], "value": 5527.054452067337, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [472.6313475489118, 474.62978876476336, 501.11553108763064, 518.551452993161, 531.3066448284986, 33462.66537334626, 38400.423993640085, 39927.0, 40038.799806001, 40139.0, 40674.38988415176, 41107.38338924918, 41178.58821411785, 41180.38229426553, 41239.17521649565, 8682.0, 8951.0, 9274.02622724616, 9354.017828128051, 9483.33616646835, 8707.303415726758, 8849.115088491164, 9302.441853488812, 9377.812443751121, 9433.76415589609, 8725.99651040129, 9330.860037099448, 9352.953235233814, 9395.34232603718, 9420.0, 8866.113388661115, 9253.35226534143, 9397.966223715386, 9401.952990235039, 9419.858702119474, 7335.926640733578, 9273.72178834636, 9280.02559731232, 9377.015413381565, 9706.951465242699, 33772.662273377195, 37563.62436375656, 38821.61178388208, 39108.80445597782, 39172.60827391718, 3299.785513941586, 3542.3977923753023, 3719.6652301292743, 3890.7860067696265, 3952.1898010907694, 24300.149494767596, 29524.70475295241, 29589.0, 29837.0, 30553.54169687434, 3112.32472618917, 3155.936881262362, 3189.5534625152495, 3231.4506533889294, 3246.107320486861, 449795.0, 459250.0, 460412.0, 460858.0, 465377.0, 57896.71051644715, 58022.0, 58323.0, 58371.0, 58382.0, 52439.0, 60367.0, 60893.69553152294, 60942.0, 61090.38909610936, 15847.762283565755, 15980.280887360097, 16007.519774406786, 16017.759733604005, 16042.75935860963, 12318.630441086782, 12348.444320005621, 12374.0, 12387.69030774238, 12422.440990155465, 15705.68588628221, 16018.679626407407, 16078.115703636178, 16137.596560085856, 16182.948108372919, 6046.3046749624355, 6046.3349031606485, 6046.3651316612, 6089.817305480843, 6111.480524155497, 839.630562552475, 841.1966571923798, 885.1281487734576, 913.6391125505388, 924.4083786376724, 28827.56758648622, 28987.13038608845, 28991.855040724662, 29120.41759164805, 29216.41567168687, 858.8153546987428, 996.0885789502669, 1040.1262939131166, 1079.1636481726568, 1135.1713249328036, 14517.78223326651, 14877.256137193313, 15012.12348456443, 15109.942304038832, 15212.315445804965, 630373.0, 632755.0, 635986.0, 636049.0, 636070.0, 55481.0, 55487.722561387745, 55598.888022240135, 55706.0, 55946.720266398406, 1323.5499930023645, 1342.9059965802496, 1352.8579499152645, 1355.5865461034282, 1359.7416490866703, 26054.60918086268, 26703.0, 28666.713332866406, 29343.413131737674, 29352.412951740418, 47763.76118119387, 48265.276020860416, 48342.0, 48355.03289934111, 48417.75791121163, 484220.0, 485133.0, 485273.0, 490133.0, 490442.0, 37772.811135945245, 38077.42883856689, 38812.6118738809, 38882.2223555533, 38996.0, 43772.34341484817, 43824.90437739082, 43993.340099899164, 45050.0, 45461.86345341393, 32966.50550241796, 36875.63124368722, 36935.261294773416, 37531.43702844405, 38373.61626383812, 32625.836870815496, 36240.00679962627, 36812.63187368092, 36842.8157859209, 37775.866723998326, 35610.75362362305, 36070.0, 36579.634203657624, 36708.632913670524, 36746.448803268504, 31129.53305700371, 32572.022839314857, 33290.33419331552, 33617.83191084029, 33848.32303353969, 22927.58119916525, 25026.74973250317, 25037.374065647775, 25078.749212507642, 25382.111626093], "value": 19206.917381807725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.6876139661986, 370.97403181777275, 382.89087610031146, 386.3412881037831, 399.32115403813503, 16062.357505699772, 18250.0, 19902.303419380325, 20200.797992020074, 20428.0, 18717.064146792673, 19101.23595056196, 19410.320638777644, 20069.59860802783, 20124.0, 5075.086484432801, 5334.706591137496, 5519.0, 5529.225908372831, 5935.080062590306, 5954.970225148868, 6070.301915279733, 6071.483923866476, 6073.392660733935, 6086.17836592061, 5714.51426628737, 5950.9107363389585, 5984.611000284989, 6034.0, 6037.305709843358, 5620.269364982567, 6018.217631707894, 6024.126501657257, 6029.939700603026, 6043.516518678495, 5171.974140129313, 5198.792048318062, 5331.1470164773455, 5335.67985920846, 5349.866253343658, 10753.78492430147, 13172.275524846129, 13698.630136986321, 18578.90710546452, 24961.87519062393, 1848.6672398968315, 2320.501092265171, 2433.0875921529428, 2433.829631925766, 2699.892004319825, 9561.474118923456, 10268.07587317145, 10275.48622568876, 12612.369381530978, 14467.638309042253, 1594.840863698238, 1820.1536285627114, 1823.6808558502341, 1884.5382881194057, 1896.3647178195322, 115331.2700309496, 115806.84193158045, 120828.39585802014, 128887.0, 128939.13182604537, 9127.178553930178, 9909.504524773876, 10748.731281718019, 11719.296842189497, 11774.411279435993, 14748.557543273719, 14778.926105369404, 14803.703925921422, 14841.96106272572, 14850.183239921693, 8309.709160179365, 8616.569171541398, 8631.0, 8654.826903461895, 8676.436031657922, 8442.662293508314, 8494.915050849537, 8499.192576705203, 8574.670926006478, 8636.870446943301, 4510.458744950627, 4564.497905230422, 4568.908621827545, 4580.862574122782, 4639.698419602715, 1744.3981826269917, 1916.4606895863037, 1963.9214431422583, 1969.2615269274022, 1976.575036367188, 686.8460985544234, 869.6304070769897, 945.177695405002, 954.5895265036102, 1041.7760181561, 16421.91789041047, 21048.579028419346, 21106.683399749018, 21247.575048498944, 21288.36134915955, 808.8382323535317, 881.6429346114834, 942.5475771629656, 952.9190018848476, 1002.9147522460672, 7998.64006119726, 8597.140285971478, 10639.202059845542, 11056.170787190993, 11068.446577671084, 516918.40030397906, 520204.0, 547836.0, 567081.0, 567962.0, 77700.0, 119966.0, 122105.0, 128293.35853320673, 129573.35213324061, 904.7738065483629, 919.9402038867452, 923.1875949164827, 929.1544694328264, 964.1804466203671, 16059.357625694842, 17687.93872367662, 18131.184096715417, 18888.05559722036, 19184.712229316294, 53574.0, 54007.91984160216, 54517.0, 54634.7268263672, 55265.72367138138, 73235.0, 74222.62888685522, 77097.61451192708, 77921.61039194767, 78145.60927195555, 7874.1338452769005, 7927.484713493604, 7989.880151797844, 8179.345652347679, 8224.177582241728, 9812.65655702047, 12232.449539770909, 12480.627131015763, 12535.93732031334, 12653.177543459646, 9133.77165570866, 10184.185265178916, 10352.0, 10473.895261047292, 10673.306235094695, 8762.0, 10667.0, 10788.838167427652, 10930.289531180766, 11166.497507612014, 11189.776204476027, 11277.661670149908, 11434.371109589052, 11540.192186546861, 11600.94199528997, 7898.763037108896, 7937.8412431752195, 8050.591146549447, 8431.988161420668, 8440.95779521123, 8176.182381762014, 9616.471094089893, 10463.529141188817, 10945.0, 11139.443027848738], "value": 9240.709372399648, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1360.8503064662884, 1413.4346261495407, 1427.1294510348685, 1710.6236627941864, 1750.4836073358358, 7529.435292353081, 7803.726869559567, 8049.0, 8528.275096616788, 8532.70135545256, 8037.718679846206, 8302.501849888995, 8330.20863018014, 8412.327013838894, 8420.536870472122, 2034.7863474335204, 2265.546890621882, 2324.93025209244, 2347.4014126397747, 2352.8588284702887, 1923.9230430782752, 1942.9216784684495, 2045.0490521907311, 2077.356019633911, 2338.286822519131, 2322.512272422793, 2324.32594547581, 2337.602607556711, 2349.8707571083582, 2353.4351755578623, 1913.9808601913942, 2307.284741730069, 2325.4070212095808, 2329.860208387502, 2331.8484298520543, 2338.9298321050223, 2361.0791791201445, 2369.644553316999, 2372.3950392649936, 2383.654370116341, 8321.126281740451, 8345.707900223524, 8415.158484151598, 8425.831483370359, 8441.155884411572, 4554.908901821978, 5096.464871188509, 5241.056609810233, 5260.789568417259, 5262.473752624707, 3286.802791832498, 3375.4093033719246, 3530.958367281658, 3537.557805274341, 3544.85820567177, 1049.2602715085845, 1062.7980683670078, 1088.2219213262476, 1101.8210514749221, 1116.0402054233352, 10920.617778377798, 10927.945360273226, 10977.341359518454, 11008.064314533274, 11018.173636977179, 4564.931526027112, 4619.745913974696, 4709.0, 4714.198586240347, 4815.903681926342, 9371.34400591952, 9445.433274003444, 9452.90547094534, 9456.763580910394, 9466.952665236768, 5236.0575096483, 5240.187770895517, 5240.737963101829, 5243.554297884646, 5249.580033597303, 4078.5513593505284, 4742.644301677388, 4752.310914917387, 4794.161021821167, 4802.327674125625, 3807.8857634271017, 3817.5037245158505, 3902.9219415612097, 3909.980450097788, 3919.412088186795, 1244.0731654917145, 1251.4118364368915, 1256.5539233571951, 1257.0383656502784, 1262.236347010064, 767.9616019199018, 788.2629741192004, 858.536390349217, 892.5983307511646, 924.371427429341, 5627.268455100892, 5715.371309155989, 5763.510101641324, 5812.244408226903, 5935.821925342247, 383.8809968909591, 418.58769112424847, 427.68351419949636, 428.1180767618715, 439.4287426345738, 3912.217556488714, 3914.5106861642294, 3932.8230229639735, 3965.524137103566, 3984.2629113614075, 9826.164775993977, 9921.454320012468, 10158.441285729208, 10241.0, 10273.075423211798, 8431.620577074047, 8771.429857059398, 10130.986901309958, 10167.847482287772, 10173.033561811617, 7750.651220695082, 7764.650590723318, 7834.960825195952, 7887.763367098996, 7922.207779222147, 5551.195076713934, 5656.688882111359, 5690.573207009408, 5734.0, 5803.100519419497, 9239.307051971264, 9242.983271840227, 9271.397359171633, 9342.299327550325, 9397.671081512115, 7552.584607846401, 9472.384295020802, 9563.234941204548, 9582.233421326138, 9584.76038099053, 6583.769568065095, 6782.423494003065, 6987.860242795217, 7123.501354905107, 7162.2121566628675, 7884.093329267208, 7986.2013798622, 8002.83994320122, 8021.959890200512, 8028.799280017813, 7036.226015138228, 7148.714051438093, 7164.749233776793, 7178.138623365229, 7267.563946163247, 6927.930720692729, 7011.894821577578, 7033.050538177281, 7109.395701365442, 7141.321574450419, 6770.729170833309, 6804.081449004321, 6829.0, 6833.624150671761, 6840.452763778985, 4918.778654960463, 4937.23472861707, 4962.082014827269, 4973.602111831117, 5002.124628189979, 3960.702947278908, 4351.499577548496, 4839.104765618372, 4915.97542012302, 5047.596192304681], "value": 4311.45824886208, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.77430312718556, 300.6572507341631, 300.9413164432935, 309.35036423510616, 315.99368012639746, 4462.955370446294, 4889.242167464049, 4962.7915577568, 5059.266406371074, 5411.621186516941, 5237.292965449673, 5338.439463856297, 5391.487808658181, 5399.325084364455, 5416.4312747161475, 4113.670906327501, 4119.794010299481, 4122.299209134454, 4122.917541649165, 4132.669386449092, 3845.711571632125, 4062.9796851015703, 4101.876943691694, 4138.97930510347, 4168.749875007494, 3399.252164523813, 3500.7549471537004, 3726.7577607455564, 4052.8581499647516, 4071.43749219433, 3526.5188620779336, 3869.7616762635967, 4078.449409329732, 4106.322456794625, 4113.239050775586, 2973.8810447582073, 3560.893173204808, 3564.1624218308675, 3566.0, 3576.515745965421, 5046.697198168122, 5191.610629202825, 5216.374035115772, 5237.3764133118475, 5314.627976041678, 4103.4870641169855, 4560.019595786922, 4582.564656357641, 4682.250839865639, 4699.483056863743, 5370.556396839429, 5672.362351506528, 5708.144852922816, 5730.541556675456, 5730.914036289459, 3298.208429976812, 3578.6779189873037, 3805.1818858945185, 3853.537575490931, 3886.319894018564, 5381.677099374011, 5485.670859748428, 5678.346990096151, 5964.761409543657, 5971.70141492928, 3461.567304086989, 3535.9745673754323, 3907.3943538751537, 3914.727713493069, 3917.7061720371084, 3584.211473475831, 3753.9249215016093, 3941.8765651789104, 3967.9286592619833, 3992.4210989406956, 4103.425520427142, 4596.47140578831, 4655.580997710223, 4664.86005419838, 4670.673052886334, 5644.939597047049, 5753.079507278899, 5807.767689292466, 5818.738156782955, 5830.970845145832, 1170.9004734597484, 1264.6648638110908, 1280.507004803139, 1289.9806502902277, 1294.851092124399, 845.4420082745359, 867.7440155154245, 882.056199866137, 883.4301875290402, 887.3699673231948, 4637.95362046382, 4791.688540244873, 4868.659193856396, 5104.770285337168, 5105.310783044316, 4662.650301227353, 5577.135543990688, 5604.842135777673, 5613.747381367848, 5623.662580245198, 4487.932681009787, 4540.296254080623, 5050.570701490415, 5127.79488820443, 5140.897182056413, 2495.803692750368, 2852.87162077707, 2890.9329829172243, 2961.9633128404885, 2996.445657553316, 5351.010063138332, 5595.796903665731, 5857.648541087548, 5860.208871802337, 5874.412558744177, 5105.846824595268, 5731.111677689964, 5904.557158213065, 6055.909161362583, 6137.754489820357, 5089.363829521384, 5253.422123566328, 5749.91375129365, 6016.127661489059, 6071.878562428727, 5170.5346518813485, 5647.198987316605, 5782.7686892523825, 5964.0, 6010.939890601213, 4894.853154405374, 5092.872678183075, 5839.810071223291, 5907.586468947133, 5919.0, 6045.486133678686, 6055.485283750931, 6063.878722425438, 6083.452489275986, 6097.329293777592, 3618.7105031597935, 3835.5972622874197, 4046.433499310158, 4073.79631018442, 4105.91788164241, 5684.886302273849, 5923.229980102731, 5927.8221653350465, 5936.554758393051, 5958.285005799332, 4982.352294201731, 5458.2904222450825, 5593.524550413261, 5655.802046928337, 5703.515201207943, 5118.206677964921, 5473.014857325719, 5598.972005140111, 5617.32592088952, 5801.564882633904, 4990.727364522024, 5384.507802041973, 5636.0, 5638.096380364033, 5751.641826850971, 5847.561432892465, 5851.1515830205235, 5878.912401205791, 5879.911801323069, 5883.735231914488, 5299.49654782795, 5677.375488696158, 5707.258056452634, 5717.7141142941855, 5745.396733343106], "value": 4113.688645385335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.7985744205458, 712.5510928115286, 735.0481126940612, 737.513241260768, 770.730244414455, 13452.7309453811, 13799.172049677027, 13818.93090534546, 13831.515896943596, 13850.445982160714, 11827.645170644873, 13087.0, 13197.27414992175, 14646.194459304734, 14650.853491465083, 780.4848799792153, 793.4802704228711, 802.8956235689352, 803.4857691077715, 805.1023109233192, 695.6104581434413, 812.1107387410776, 816.0656048824093, 816.3346872299087, 818.6848063495568, 847.8643417053273, 862.5687156421789, 863.7106569299261, 865.6148014133681, 866.2723312417568, 847.449158047268, 853.9914600853974, 857.2199298638241, 858.1332853817627, 860.1484530315006, 627.6955676496896, 628.8427893026743, 637.2162240444234, 638.9201349831271, 640.9871802563922, 13337.86662133376, 13370.0, 13409.396577154052, 13411.530596429078, 13422.395992180376, 13073.346332683328, 13324.800127998087, 13546.864531354659, 13617.387217575233, 13723.176609403468, 11124.276922000045, 11124.61063862769, 11308.604198853001, 11314.547418103266, 11372.829407558893, 3567.571891373052, 3588.318219538279, 3602.585702644177, 3623.565172179355, 3634.3821550336506, 69876.65061674659, 76523.0, 76612.23387766107, 91668.54165729128, 93764.0, 10382.013708697812, 10451.0, 10555.577776888991, 10558.947205264078, 10596.417197054236, 19560.804391956182, 19601.31395401154, 19647.115879785444, 19756.802431975782, 19762.604747904963, 7417.221191774786, 7554.055743032121, 7586.658600362997, 7650.1202361728, 7776.688932442639, 3958.524977002778, 4055.168690418457, 4149.232392007489, 4278.122984788111, 4289.592488713567, 3360.7311415086733, 3421.5894092709036, 3473.6873681368797, 3474.7915125092577, 3478.1826270826336, 555.4057158840083, 558.9077802162678, 570.0053406805151, 640.7565125252468, 649.701137476766, 498.91019616468657, 504.83845169545395, 569.2997612936111, 570.5948776368824, 607.401709316323, 8711.91101112361, 9086.727398178065, 9241.306901982378, 9365.812683746288, 9585.329026968186, 613.1446631948407, 617.5430181665625, 629.8662407666183, 679.239252037711, 683.6889215406978, 3710.777353358807, 3777.471154038437, 4524.615407690383, 4530.139273538017, 4615.100055489214, 96117.51941240249, 107688.0, 107779.0, 108579.45710271556, 112431.0, 64776.35223647797, 82050.71797128048, 86558.0, 100974.0, 102279.46580801294, 82393.0, 82748.17251827526, 84036.57981710053, 84067.0, 85468.7179692305, 9398.248140148908, 9877.308588398742, 9887.110160085627, 9896.505174741378, 9905.0, 51582.0, 53171.40485785433, 53781.84017071354, 54870.0, 56128.0, 64229.678851607314, 67001.0, 75494.24505755091, 77277.61361193158, 78385.432291355, 12094.213876098025, 12403.503859845505, 12416.255024698547, 12557.0, 12899.613011609667, 11087.390193539433, 13562.321883905568, 14309.499167529086, 14384.928075359556, 15098.39606415775, 13069.869301306866, 13157.60527184186, 13454.798178027142, 13850.930745346208, 14036.368363423466, 12183.939080304543, 12822.615321540368, 13824.032317737661, 13851.0, 14301.64245893861, 10425.63510277167, 11703.0, 12408.75182496363, 12587.622371328875, 13657.248851312876, 10535.315204511682, 10693.984071513194, 11444.771104577696, 11563.595274165364, 11681.897258493553, 10524.736881578021, 10577.206709496664, 11326.037286830382, 11491.597794077166, 11559.653210403701], "value": 7165.150740657176, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.8999999999996, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 116.0, 119.0, 136.0, 167.0, 120.0, 121.0, 119.0, 120.0], "value": 125.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 117.0, 149.0, 142.0, 149.0, 104.0, 133.0, 175.0, 147.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 196.0, 200.0, 197.0, 194.0, 189.0, 196.0, 199.0, 195.0], "value": 196.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 58.0, 62.0, 85.0, 62.0, 60.0, 61.0, 59.0, 87.0, 72.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 90.0, 73.0, 90.0, 101.0, 119.0, 105.0, 74.0, 87.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 86.0, 90.0, 86.0, 88.0, 86.0, 104.0, 84.0, 77.0, 131.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 96.0, 95.0, 127.0, 151.0, 101.0, 97.0, 151.0, 93.0, 96.0], "value": 115.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 134.0, 140.0, 116.0, 87.0, 90.0, 90.0, 92.0, 121.0, 117.0], "value": 107.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 60.0, 60.0, 62.0, 60.0, 60.0, 68.0, 79.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 79.0, 70.0, 75.0, 71.0, 61.0, 59.0, 80.0, 99.0, 101.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 85.0, 82.0, 86.0, 84.0, 84.0, 86.0, 84.0, 83.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 143.0, 162.0, 161.0, 154.0, 163.0, 146.0, 289.0, 172.0], "value": 168.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 194.0, 229.0, 160.0, 222.0, 210.0, 169.0, 167.0, 170.0, 169.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 72.0, 89.0, 65.0, 83.0, 67.0, 64.0, 83.0, 94.0], "value": 75.8, "unit": "score"}]}]} 09:10:08 INFO - Return code: 0 09:10:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:10:08 ERROR - # TBPL SUCCESS # 09:10:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:10:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:10:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:10:08 INFO - 2017-01-07 09:10:08.709 screenresolution[1660:12379] starting screenresolution argv=screenresolution get 09:10:08 INFO - 2017-01-07 09:10:08.712 screenresolution[1660:12379] Display 0: 1600x1200x32@60 09:10:08 INFO - 2017-01-07 09:10:08.717 screenresolution[1661:12382] starting screenresolution argv=screenresolution list 09:10:08 INFO - Available Modes on Display 0 09:10:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:10:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:10:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:10:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:10:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:10:08 INFO - Intel Iris: 09:10:08 INFO - Chipset Model: Intel Iris 09:10:08 INFO - Type: GPU 09:10:08 INFO - Bus: Built-In 09:10:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:10:08 INFO - Vendor: Intel (0x8086) 09:10:08 INFO - Device ID: 0x0a2e 09:10:08 INFO - Revision ID: 0x0009 09:10:08 INFO - Displays: 09:10:08 INFO - Display: 09:10:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:10:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:10:08 INFO - Display Serial Number: 1600x1200 60 09:10:08 INFO - Main Display: Yes 09:10:08 INFO - Mirror: Off 09:10:08 INFO - Online: Yes 09:10:08 INFO - Rotation: Supported 09:10:08 INFO - Return code: 0 09:10:08 INFO - Running post-action listener: _resource_record_post_action 09:10:08 INFO - [mozharness: 2017-01-07 17:10:08.960757Z] Finished run-tests step (success) 09:10:08 INFO - Running post-run listener: _resource_record_post_run 09:10:09 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 33440256; Write bytes: 658349568; Read time: 201; Write time: 1993 09:10:09 INFO - TinderboxPrint: CPU usage
54.3% 09:10:09 INFO - TinderboxPrint: I/O read bytes / time
33,440,256 / 201 09:10:09 INFO - TinderboxPrint: I/O write bytes / time
658,349,568 / 1,993 09:10:09 INFO - TinderboxPrint: CPU idle
2,213.2 (45.7%) 09:10:09 INFO - TinderboxPrint: CPU system
976.3 (20.2%) 09:10:09 INFO - TinderboxPrint: CPU user
1,652.2 (34.1%) 09:10:09 INFO - TinderboxPrint: Swap in / out
378,736,640 / 0 09:10:09 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344742400; Write bytes: 345352192; Read time: 27003; Write time: 779 09:10:09 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31470080; Write bytes: 306120192; Read time: 184; Write time: 1186 09:10:09 INFO - Running post-run listener: _upload_blobber_files 09:10:09 INFO - Blob upload gear active. 09:10:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:10:09 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:10:09 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:10:09 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:10:09 INFO - (blobuploader) - INFO - Open directory for files ... 09:10:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:10:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:10:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:10 INFO - (blobuploader) - INFO - Done attempting. 09:10:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:10:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:10:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:11 INFO - (blobuploader) - INFO - Done attempting. 09:10:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:10:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:10:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:10:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:14 INFO - (blobuploader) - INFO - Done attempting. 09:10:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:10:14 INFO - Return code: 0 09:10:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:10:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:10:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce38335b3578997ec8a8bab796ce0237c063a58f6d6d6628b098a11922938842679f4433fcdc826c859497026a6d13d6be6fa8dabfe6b17f7a2c8046d2deeff0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17583479f5ab59d08f015f9d6bc70977b85e8366f7b890e603218e712cd66f5470dc8b96c74e9a207719e6e70c7aca306f77b27a94f72e93ef87b95ae8bba290", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3045d6d159697852bcd9ad71e3168bec9b3ed62d39cb03b3c3753146cab3c3c0ce7d6b7c40582b11bc256f14a3ca4110eec4187baaf3a2652b6575e7cdb4c06a"} 09:10:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:10:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:10:14 INFO - Contents: 09:10:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce38335b3578997ec8a8bab796ce0237c063a58f6d6d6628b098a11922938842679f4433fcdc826c859497026a6d13d6be6fa8dabfe6b17f7a2c8046d2deeff0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17583479f5ab59d08f015f9d6bc70977b85e8366f7b890e603218e712cd66f5470dc8b96c74e9a207719e6e70c7aca306f77b27a94f72e93ef87b95ae8bba290", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3045d6d159697852bcd9ad71e3168bec9b3ed62d39cb03b3c3753146cab3c3c0ce7d6b7c40582b11bc256f14a3ca4110eec4187baaf3a2652b6575e7cdb4c06a"} 09:10:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:10:14 INFO - Copying logs to upload dir... 09:10:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:10:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.203370 ========= master_lag: 0.30 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-07 09:10:14.371218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 09:10:14.375042) ========= 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.8f7U4jDr0V/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.YaELjQJv5H/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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/ce38335b3578997ec8a8bab796ce0237c063a58f6d6d6628b098a11922938842679f4433fcdc826c859497026a6d13d6be6fa8dabfe6b17f7a2c8046d2deeff0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17583479f5ab59d08f015f9d6bc70977b85e8366f7b890e603218e712cd66f5470dc8b96c74e9a207719e6e70c7aca306f77b27a94f72e93ef87b95ae8bba290", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3045d6d159697852bcd9ad71e3168bec9b3ed62d39cb03b3c3753146cab3c3c0ce7d6b7c40582b11bc256f14a3ca4110eec4187baaf3a2652b6575e7cdb4c06a"} build_url:https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/N4moMNQIRP-F-v--2V8j1w/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/ce38335b3578997ec8a8bab796ce0237c063a58f6d6d6628b098a11922938842679f4433fcdc826c859497026a6d13d6be6fa8dabfe6b17f7a2c8046d2deeff0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17583479f5ab59d08f015f9d6bc70977b85e8366f7b890e603218e712cd66f5470dc8b96c74e9a207719e6e70c7aca306f77b27a94f72e93ef87b95ae8bba290", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3045d6d159697852bcd9ad71e3168bec9b3ed62d39cb03b3c3753146cab3c3c0ce7d6b7c40582b11bc256f14a3ca4110eec4187baaf3a2652b6575e7cdb4c06a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 09:10:14.409361) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:10:14.409654) ========= 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.8f7U4jDr0V/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.YaELjQJv5H/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.005092 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 09:10:14.458352) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 09:10:14.458658) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 09:10:14.458977) ========= ========= Total master_lag: 0.44 =========