builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0365 starttime: 1480476525.24 results: success (0) buildid: 20161129165126 builduid: aa189193c32c406e8b6ce15ee60ece4e revision: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:45.237226) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:45.237724) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:45.238065) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:45.267222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:45.267614) ========= 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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-29 19:28:45-- 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% 166M=0s 2016-11-29 19:28:45 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.113044 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:45.405394) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:45.405755) ========= 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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.026544 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:45.455832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-29 19:28:45.456875) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev adcc39e3cad0f32aba0efb478cc4a023a5dfc43f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev adcc39e3cad0f32aba0efb478cc4a023a5dfc43f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lhM64jt5ZG/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.UrR79ehPZ3/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-29 19:28:45,555 truncating revision to first 12 chars 2016-11-29 19:28:45,555 Setting DEBUG logging. 2016-11-29 19:28:45,555 attempt 1/10 2016-11-29 19:28:45,555 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/adcc39e3cad0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-29 19:28:47,340 unpacking tar archive at: mozilla-central-adcc39e3cad0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.041017 ========= master_lag: 0.66 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-29 19:28:48.155689) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:48.159058) ========= script_repo_revision: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:48.159469) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:48.159732) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-29 19:28:48.915515) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-29 19:28:48.916364) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lhM64jt5ZG/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.UrR79ehPZ3/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:28:49 INFO - MultiFileLogger online at 20161129 19:28:49 in /builds/slave/test 19:28:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:28:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:28:49 INFO - {'append_to_log': False, 19:28:49 INFO - 'base_work_dir': '/builds/slave/test', 19:28:49 INFO - 'blob_upload_branch': 'Firefox', 19:28:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:28:49 INFO - 'branch': 'Firefox', 19:28:49 INFO - 'buildbot_json_path': 'buildprops.json', 19:28:49 INFO - 'config_files': ('talos/mac_config.py',), 19:28:49 INFO - 'default_actions': ('clobber', 19:28:49 INFO - 'read-buildbot-config', 19:28:49 INFO - 'download-and-extract', 19:28:49 INFO - 'populate-webroot', 19:28:49 INFO - 'create-virtualenv', 19:28:49 INFO - 'install', 19:28:49 INFO - 'run-tests'), 19:28:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:28:49 INFO - 'download_minidump_stackwalk': True, 19:28:49 INFO - 'download_symbols': 'ondemand', 19:28:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:28:49 INFO - 'tooltool.py': '/tools/tooltool.py', 19:28:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:28:49 INFO - '/tools/misc-python/virtualenv.py')}, 19:28:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:28:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:28:49 INFO - 'installer_path': 'installer.exe', 19:28:49 INFO - 'log_level': 'info', 19:28:49 INFO - 'log_name': 'talos', 19:28:49 INFO - 'log_to_console': True, 19:28:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:28:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:28:49 INFO - 'opt_config_files': (), 19:28:49 INFO - 'pip_index': False, 19:28:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:28:49 INFO - 'cmd': ('bash', 19:28:49 INFO - '-c', 19:28:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:28:49 INFO - 'enabled': True, 19:28:49 INFO - 'halt_on_failure': False, 19:28:49 INFO - 'name': 'check_screen_resolution'},), 19:28:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:28:49 INFO - 'cmd': ('bash', 19:28:49 INFO - '-c', 19:28:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:28:49 INFO - 'enabled': True, 19:28:49 INFO - 'halt_on_failure': False, 19:28:49 INFO - 'name': 'check_screen_resolution'},), 19:28:49 INFO - 'run_cmd_checks_enabled': True, 19:28:49 INFO - 'sps_profile': False, 19:28:49 INFO - 'sps_profile_interval': 0, 19:28:49 INFO - 'suite': 'dromaeojs-e10s', 19:28:49 INFO - 'system_bits': '32', 19:28:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:28:49 INFO - 'title': 't-yosemite-r7-0365', 19:28:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:28:49 INFO - 'use_talos_json': True, 19:28:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:28:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:28:49 INFO - 'work_dir': 'build'} 19:28:49 INFO - [mozharness: 2016-11-30 03:28:49.118826Z] Running clobber step. 19:28:49 INFO - Running pre-action listener: _resource_record_pre_action 19:28:49 INFO - Running main action method: clobber 19:28:49 INFO - rmtree: /builds/slave/test/build 19:28:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:28:51 INFO - Running post-action listener: _resource_record_post_action 19:28:51 INFO - [mozharness: 2016-11-30 03:28:51.087681Z] Finished clobber step (success) 19:28:51 INFO - [mozharness: 2016-11-30 03:28:51.087892Z] Running read-buildbot-config step. 19:28:51 INFO - Running pre-action listener: _resource_record_pre_action 19:28:51 INFO - Running main action method: read_buildbot_config 19:28:51 INFO - Using buildbot properties: 19:28:51 INFO - { 19:28:51 INFO - "project": "", 19:28:51 INFO - "product": "firefox", 19:28:51 INFO - "script_repo_revision": "production", 19:28:51 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:28:51 INFO - "repository": "", 19:28:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:28:51 INFO - "buildid": "20161129165126", 19:28:51 INFO - "pgo_build": "False", 19:28:51 INFO - "basedir": "/builds/slave/test", 19:28:51 INFO - "buildnumber": 3, 19:28:51 INFO - "slavename": "t-yosemite-r7-0365", 19:28:51 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:28:51 INFO - "platform": "yosemite_r7", 19:28:51 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:28:51 INFO - "branch": "mozilla-central", 19:28:51 INFO - "repo_path": "mozilla-central", 19:28:51 INFO - "revision": "adcc39e3cad0f32aba0efb478cc4a023a5dfc43f", 19:28:51 INFO - "stage_platform": "macosx64", 19:28:51 INFO - "builduid": "aa189193c32c406e8b6ce15ee60ece4e", 19:28:51 INFO - "slavebuilddir": "test" 19:28:51 INFO - } 19:28:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 19:28:51 INFO - Running post-action listener: _resource_record_post_action 19:28:51 INFO - [mozharness: 2016-11-30 03:28:51.093626Z] Finished read-buildbot-config step (success) 19:28:51 INFO - [mozharness: 2016-11-30 03:28:51.093805Z] Running download-and-extract step. 19:28:51 INFO - Running pre-action listener: _resource_record_pre_action 19:28:51 INFO - Running main action method: download_and_extract 19:28:51 INFO - mkdir: /builds/slave/test/build/tests 19:28: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')]} 19:28:51 INFO - trying https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 19:28:51 INFO - Downloading https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:28:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 19:28:51 INFO - Downloaded 1199 bytes. 19:28:51 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:28:51 INFO - Using the following test package requirements: 19:28:51 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:28:51 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:28:51 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 19:28:51 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:28:51 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 19:28:51 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:28:51 INFO - u'jsshell-mac.zip'], 19:28:51 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:28:51 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 19:28:51 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:28:51 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:28:51 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 19:28:51 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:28:51 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 19:28:51 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:28:51 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 19:28:51 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:28:51 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 19:28:51 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:28:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:28:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:28:51 INFO - Fetch https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:28:52 INFO - Http code: 200 19:28:52 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 19:28:52 INFO - x-amz-cf-id: oTDMiofuwAVjm59RVPtmkSpgRDLmlBDh4GFJ36-3KnI_3GkTfuJ9rg== 19:28:52 INFO - x-amz-version-id: 1v9gICNGhPz5kMKHwEH53FrwxHdFE4E_ 19:28:52 INFO - Expected file size: 16285181 19:28:52 INFO - Obtained file size: 16285181 19:28:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:28:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:28:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:28:59 INFO - Fetch https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:28:59 INFO - Http code: 200 19:28:59 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 19:28:59 INFO - x-amz-cf-id: -hgmYsmAvnNTCz2ySgRAvFmUekbS3JP5gLOhs-YUL3pzBAus4yjZ4w== 19:28:59 INFO - x-amz-version-id: 1v9gICNGhPz5kMKHwEH53FrwxHdFE4E_ 19:29:00 INFO - Expected file size: 16285181 19:29:00 INFO - Obtained file size: 16285181 19:29:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 19:29:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:29:06 INFO - Fetch https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 19:29:07 INFO - Http code: 200 19:29:07 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 19:29:07 INFO - x-amz-cf-id: pFwJwdPAXBC-BGvRiNhj9TBr7oUHm7-lMyC10LLpb0XMAz11_cqB_Q== 19:29:07 INFO - x-amz-version-id: utJRf5nWyICtZWr3BpXX3gdZyBGig5x7 19:29:07 INFO - Expected file size: 14409702 19:29:07 INFO - Obtained file size: 14409702 19:29:07 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')]} 19:29:07 INFO - trying https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:29:07 INFO - Downloading https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 19:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 19:29:10 INFO - Downloaded 107618884 bytes. 19:29:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:29:10 INFO - mkdir: /builds/slave/test/properties 19:29:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:29:10 INFO - Writing to file /builds/slave/test/properties/build_url 19:29:10 INFO - Contents: 19:29:10 INFO - build_url:https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:29:11 INFO - Running post-action listener: _resource_record_post_action 19:29:11 INFO - Running post-action listener: set_extra_try_arguments 19:29:11 INFO - [mozharness: 2016-11-30 03:29:11.348211Z] Finished download-and-extract step (success) 19:29:11 INFO - [mozharness: 2016-11-30 03:29:11.348353Z] Running populate-webroot step. 19:29:11 INFO - Running pre-action listener: _resource_record_pre_action 19:29:11 INFO - Running main action method: populate_webroot 19:29:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:29:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:29:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:29:11 INFO - '--tppagecycles', 19:29:11 INFO - '7'], 19:29:11 INFO - 'tests': ['tsvgm']}}, 19:29:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:29:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:29:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:29:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:29:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:29:11 INFO - 'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:29:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:29:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:29:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:29:11 INFO - 'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['damp', 'tps']}, 19:29:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:29:11 INFO - 'tests': ['damp', 'tps']}, 19:29:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['dromaeo_dom']}, 19:29:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:29:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['basic_compositor_video']}, 19:29:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:29:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['a11yr', 19:29:11 INFO - 'ts_paint', 19:29:11 INFO - 'tpaint', 19:29:11 INFO - 'sessionrestore', 19:29:11 INFO - 'sessionrestore_no_auto_restore', 19:29:11 INFO - 'tabpaint']}, 19:29:11 INFO - 'other-e10s': {'tests': ['a11yr', 19:29:11 INFO - 'ts_paint', 19:29:11 INFO - 'tpaint', 19:29:11 INFO - 'sessionrestore', 19:29:11 INFO - 'sessionrestore_no_auto_restore', 19:29:11 INFO - 'tabpaint']}, 19:29:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:29:11 INFO - 'ts_paint', 19:29:11 INFO - 'tpaint', 19:29:11 INFO - 'sessionrestore', 19:29:11 INFO - 'sessionrestore_no_auto_restore', 19:29:11 INFO - 'tabpaint']}, 19:29:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:29:11 INFO - 'ts_paint', 19:29:11 INFO - 'tpaint', 19:29:11 INFO - 'sessionrestore', 19:29:11 INFO - 'sessionrestore_no_auto_restore', 19:29:11 INFO - 'tabpaint']}, 19:29:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['a11yr', 19:29:11 INFO - 'ts_paint', 19:29:11 INFO - 'tpaint', 19:29:11 INFO - 'sessionrestore', 19:29:11 INFO - 'sessionrestore_no_auto_restore', 19:29:11 INFO - 'tabpaint']}, 19:29:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['a11yr', 19:29:11 INFO - 'ts_paint', 19:29:11 INFO - 'tpaint', 19:29:11 INFO - 'sessionrestore', 19:29:11 INFO - 'sessionrestore_no_auto_restore', 19:29:11 INFO - 'tabpaint']}, 19:29:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['tsvgx', 19:29:11 INFO - 'tsvgr_opacity', 19:29:11 INFO - 'tart', 19:29:11 INFO - 'tscrollx', 19:29:11 INFO - 'cart', 19:29:11 INFO - 'tsvg_static']}, 19:29:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:29:11 INFO - 'tsvgr_opacity', 19:29:11 INFO - 'tart', 19:29:11 INFO - 'tscrollx', 19:29:11 INFO - 'cart', 19:29:11 INFO - 'tsvg_static']}, 19:29:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:29:11 INFO - 'talos_options': ['--disable-e10s'], 19:29:11 INFO - 'tests': ['tp5o']}, 19:29:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:29:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:29:11 INFO - 'talos_options': ['--disable-e10s', 19:29:11 INFO - '--xperf_path', 19:29:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:29:11 INFO - 'tests': ['tp5n']}, 19:29:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:29:11 INFO - 'talos_options': ['--xperf_path', 19:29:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:29:11 INFO - 'tests': ['tp5n']}}, 19:29:11 INFO - 'talos.zip': {'path': '', 19:29:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:29:11 INFO - Running post-action listener: _resource_record_post_action 19:29:11 INFO - [mozharness: 2016-11-30 03:29:11.366521Z] Finished populate-webroot step (success) 19:29:11 INFO - [mozharness: 2016-11-30 03:29:11.366614Z] Running create-virtualenv step. 19:29:11 INFO - Running pre-action listener: _resource_record_pre_action 19:29:11 INFO - Running main action method: create_virtualenv 19:29:11 INFO - Creating virtualenv /builds/slave/test/build/venv 19:29:11 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 19:29:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:29:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:29:11 INFO - Using real prefix '/tools/python27' 19:29:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:29:12 INFO - Installing distribute.............................................................................................................................................................................................done. 19:29:15 INFO - Installing pip.................done. 19:29:15 INFO - Return code: 0 19:29:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:29:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:29:15 INFO - Reading from file tmpfile_stdout 19:29:15 INFO - Output received: 19:29:15 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) 19:29:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:29:15 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')]} 19:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:15 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')]} 19:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:15 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:15 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 19:29:15 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 19:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:15 INFO - 'HOME': '/Users/cltbld', 19:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:15 INFO - 'LOGNAME': 'cltbld', 19:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:15 INFO - 'MOZ_NO_REMOTE': '1', 19:29:15 INFO - 'NO_EM_RESTART': '1', 19:29:15 INFO - 'PAGER': '/bin/cat', 19:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:15 INFO - 'PWD': '/builds/slave/test', 19:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:15 INFO - 'SHELL': '/bin/bash', 19:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:15 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:15 INFO - 'USER': 'cltbld', 19:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:15 INFO - 'XPC_FLAGS': '0x0', 19:29:15 INFO - 'XPC_SERVICE_NAME': '0', 19:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:15 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 19:29:15 INFO - Cleaning up... 19:29:15 INFO - Return code: 0 19:29:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:29:15 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')]} 19:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:15 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')]} 19:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:15 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:15 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 19:29:15 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 19:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:15 INFO - 'HOME': '/Users/cltbld', 19:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:15 INFO - 'LOGNAME': 'cltbld', 19:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:15 INFO - 'MOZ_NO_REMOTE': '1', 19:29:15 INFO - 'NO_EM_RESTART': '1', 19:29:15 INFO - 'PAGER': '/bin/cat', 19:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:15 INFO - 'PWD': '/builds/slave/test', 19:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:15 INFO - 'SHELL': '/bin/bash', 19:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:15 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:15 INFO - 'USER': 'cltbld', 19:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:15 INFO - 'XPC_FLAGS': '0x0', 19:29:15 INFO - 'XPC_SERVICE_NAME': '0', 19:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:15 INFO - Downloading/unpacking psutil>=3.1.1 19:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:29:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:29:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:29:19 INFO - Installing collected packages: psutil 19:29:19 INFO - Running setup.py install for psutil 19:29:19 INFO - building 'psutil._psutil_osx' extension 19:29:19 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 19:29:19 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 19:29:20 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 19:29:20 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 19:29:20 INFO - building 'psutil._psutil_posix' extension 19:29:20 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 19:29:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:29:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:29:20 INFO - ^ 19:29:20 INFO - 1 warning generated. 19:29:20 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 19:29:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:29:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:29:20 INFO - Successfully installed psutil 19:29:20 INFO - Cleaning up... 19:29:20 INFO - Return code: 0 19:29:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:29: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')]} 19:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29: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')]} 19:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:20 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:20 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 19:29:20 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 19:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:20 INFO - 'HOME': '/Users/cltbld', 19:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:20 INFO - 'LOGNAME': 'cltbld', 19:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:20 INFO - 'MOZ_NO_REMOTE': '1', 19:29:20 INFO - 'NO_EM_RESTART': '1', 19:29:20 INFO - 'PAGER': '/bin/cat', 19:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:20 INFO - 'PWD': '/builds/slave/test', 19:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:20 INFO - 'SHELL': '/bin/bash', 19:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:20 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:20 INFO - 'USER': 'cltbld', 19:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:20 INFO - 'XPC_FLAGS': '0x0', 19:29:20 INFO - 'XPC_SERVICE_NAME': '0', 19:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:29:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:29:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:29:23 INFO - Installing collected packages: mozsystemmonitor 19:29:23 INFO - Running setup.py install for mozsystemmonitor 19:29:23 INFO - Successfully installed mozsystemmonitor 19:29:23 INFO - Cleaning up... 19:29:23 INFO - Return code: 0 19:29:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:29:23 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')]} 19:29:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:23 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')]} 19:29:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:23 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:23 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 19:29:23 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 19:29:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:23 INFO - 'HOME': '/Users/cltbld', 19:29:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:23 INFO - 'LOGNAME': 'cltbld', 19:29:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:23 INFO - 'MOZ_NO_REMOTE': '1', 19:29:23 INFO - 'NO_EM_RESTART': '1', 19:29:23 INFO - 'PAGER': '/bin/cat', 19:29:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:23 INFO - 'PWD': '/builds/slave/test', 19:29:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:23 INFO - 'SHELL': '/bin/bash', 19:29:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:23 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:23 INFO - 'USER': 'cltbld', 19:29:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:23 INFO - 'XPC_FLAGS': '0x0', 19:29:23 INFO - 'XPC_SERVICE_NAME': '0', 19:29:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:24 INFO - Downloading/unpacking jsonschema==2.5.1 19:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:29:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:26 INFO - Downloading functools32-3.2.3-2.tar.gz 19:29:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:29:26 INFO - warning: no files found matching '*.txt' 19:29:26 INFO - no previously-included directories found matching 'build' 19:29:26 INFO - no previously-included directories found matching 'dist' 19:29:26 INFO - no previously-included directories found matching '.git*' 19:29:27 INFO - Installing collected packages: jsonschema, functools32 19:29:27 INFO - Running setup.py install for functools32 19:29:27 INFO - warning: no files found matching '*.txt' 19:29:27 INFO - no previously-included directories found matching 'build' 19:29:27 INFO - no previously-included directories found matching 'dist' 19:29:27 INFO - no previously-included directories found matching '.git*' 19:29:27 INFO - Successfully installed jsonschema functools32 19:29:27 INFO - Cleaning up... 19:29:27 INFO - Return code: 0 19:29:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:27 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:27 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 19:29:27 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 19:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:27 INFO - 'HOME': '/Users/cltbld', 19:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:27 INFO - 'LOGNAME': 'cltbld', 19:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:27 INFO - 'MOZ_NO_REMOTE': '1', 19:29:27 INFO - 'NO_EM_RESTART': '1', 19:29:27 INFO - 'PAGER': '/bin/cat', 19:29:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:27 INFO - 'PWD': '/builds/slave/test', 19:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:27 INFO - 'SHELL': '/bin/bash', 19:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:27 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:27 INFO - 'USER': 'cltbld', 19:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:27 INFO - 'XPC_FLAGS': '0x0', 19:29:27 INFO - 'XPC_SERVICE_NAME': '0', 19:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:29:27 INFO - Cleaning up... 19:29:27 INFO - Return code: 0 19:29:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:27 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:27 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 19:29:27 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 19:29:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:27 INFO - 'HOME': '/Users/cltbld', 19:29:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:27 INFO - 'LOGNAME': 'cltbld', 19:29:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:27 INFO - 'MOZ_NO_REMOTE': '1', 19:29:27 INFO - 'NO_EM_RESTART': '1', 19:29:27 INFO - 'PAGER': '/bin/cat', 19:29:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:27 INFO - 'PWD': '/builds/slave/test', 19:29:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:27 INFO - 'SHELL': '/bin/bash', 19:29:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:27 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:27 INFO - 'USER': 'cltbld', 19:29:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:27 INFO - 'XPC_FLAGS': '0x0', 19:29:27 INFO - 'XPC_SERVICE_NAME': '0', 19:29:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:27 INFO - Downloading/unpacking blobuploader==1.2.4 19:29:27 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 19:29:27 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 19:29:27 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 19:29:27 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 19:29:27 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 19:29:27 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 19:29:30 INFO - Downloading blobuploader-1.2.4.tar.gz 19:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:29:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:29:30 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 19:29:30 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 19:29:30 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 19:29:30 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 19:29:30 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 19:29:30 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 19:29:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:29:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:29:31 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 19:29:31 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 19:29:31 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 19:29:31 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 19:29:31 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 19:29:31 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 19:29:31 INFO - Downloading docopt-0.6.1.tar.gz 19:29:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:29:31 INFO - Installing collected packages: blobuploader, requests, docopt 19:29:31 INFO - Running setup.py install for blobuploader 19:29:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:29:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:29:31 INFO - Running setup.py install for requests 19:29:32 INFO - Running setup.py install for docopt 19:29:32 INFO - Successfully installed blobuploader requests docopt 19:29:32 INFO - Cleaning up... 19:29:32 INFO - Return code: 0 19:29:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:32 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:32 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 19:29:32 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 19:29:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:32 INFO - 'HOME': '/Users/cltbld', 19:29:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:32 INFO - 'LOGNAME': 'cltbld', 19:29:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:32 INFO - 'MOZ_NO_REMOTE': '1', 19:29:32 INFO - 'NO_EM_RESTART': '1', 19:29:32 INFO - 'PAGER': '/bin/cat', 19:29:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:32 INFO - 'PWD': '/builds/slave/test', 19:29:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:32 INFO - 'SHELL': '/bin/bash', 19:29:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:32 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:32 INFO - 'USER': 'cltbld', 19:29:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:32 INFO - 'XPC_FLAGS': '0x0', 19:29:32 INFO - 'XPC_SERVICE_NAME': '0', 19:29:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:29:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-qlfoj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:29:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Ip5uhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:29:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:29:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-03UHCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-1YqZCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-5FWvzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-7XfurJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-5cOrDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-T0pgy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-kO3syn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-VYfyM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-w80AaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:29:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:29:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Cl1cEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:29:34 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-leP7JI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:29:34 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-XelAyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:34 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-_itWsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:29:34 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-XWSKTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:29:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:29:34 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-iNctAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:29:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:29:34 INFO - Running setup.py install for manifestparser 19:29:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:29:34 INFO - Running setup.py install for mozcrash 19:29:34 INFO - Running setup.py install for mozdebug 19:29:34 INFO - Running setup.py install for mozdevice 19:29:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:29:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:29:35 INFO - Running setup.py install for mozfile 19:29:35 INFO - Running setup.py install for mozhttpd 19:29:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:29:35 INFO - Running setup.py install for mozinfo 19:29:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:29:35 INFO - Running setup.py install for mozInstall 19:29:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:29:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:29:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:29:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:29:35 INFO - Running setup.py install for mozleak 19:29:35 INFO - Running setup.py install for mozlog 19:29:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:29:36 INFO - Running setup.py install for moznetwork 19:29:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:29:36 INFO - Running setup.py install for mozprocess 19:29:36 INFO - Running setup.py install for mozprofile 19:29:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:29:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:29:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:29:36 INFO - Running setup.py install for mozrunner 19:29:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:29:36 INFO - Running setup.py install for mozscreenshot 19:29:36 INFO - Running setup.py install for moztest 19:29:36 INFO - Running setup.py install for mozversion 19:29:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:29:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:29:37 INFO - Cleaning up... 19:29:37 INFO - Return code: 0 19:29:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:29: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')]} 19:29:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29: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')]} 19:29:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:37 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:37 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 19:29:37 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 19:29:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:37 INFO - 'HOME': '/Users/cltbld', 19:29:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:37 INFO - 'LOGNAME': 'cltbld', 19:29:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:37 INFO - 'MOZ_NO_REMOTE': '1', 19:29:37 INFO - 'NO_EM_RESTART': '1', 19:29:37 INFO - 'PAGER': '/bin/cat', 19:29:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:37 INFO - 'PWD': '/builds/slave/test', 19:29:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:37 INFO - 'SHELL': '/bin/bash', 19:29:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:37 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:37 INFO - 'USER': 'cltbld', 19:29:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:37 INFO - 'XPC_FLAGS': '0x0', 19:29:37 INFO - 'XPC_SERVICE_NAME': '0', 19:29:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:29:37 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-IINTbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:29:37 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)) 19:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:29:37 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-88I4od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:29:37 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)) 19:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:29:37 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-vszD5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:29:37 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)) 19:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:29:37 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ygcnWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:29:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:29:37 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-VPwS9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:29:37 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)) 19:29:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:29:37 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ul2ibg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-uSYV_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-mrTB1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-0tUQNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-M3OFf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-GkJ_8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-B66600-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-iZSIoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-1PqpSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-av4LvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:29:38 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)) 19:29:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:29:38 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-DDBQzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 19:29:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:29:39 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-zX0TVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:29:39 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)) 19:29:39 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)) 19:29:39 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)) 19:29:39 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)) 19:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:29:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:29:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:29:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:29:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:29:42 INFO - Downloading blessings-1.6.tar.gz 19:29:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:29:42 INFO - Installing collected packages: blessings 19:29:42 INFO - Running setup.py install for blessings 19:29:42 INFO - Successfully installed blessings 19:29:42 INFO - Cleaning up... 19:29:42 INFO - Return code: 0 19:29:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:29:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:29:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:29:42 INFO - Reading from file tmpfile_stdout 19:29:42 INFO - Current package versions: 19:29:42 INFO - blessings == 1.6 19:29:42 INFO - blobuploader == 1.2.4 19:29:42 INFO - docopt == 0.6.1 19:29:42 INFO - functools32 == 3.2.3-2 19:29:42 INFO - jsonschema == 2.5.1 19:29:42 INFO - manifestparser == 1.1 19:29:42 INFO - mozInstall == 1.12 19:29:42 INFO - mozcrash == 1.0 19:29:42 INFO - mozdebug == 0.1 19:29:42 INFO - mozdevice == 0.49 19:29:42 INFO - mozfile == 1.2 19:29:42 INFO - mozhttpd == 0.7 19:29:42 INFO - mozinfo == 0.9 19:29:42 INFO - mozleak == 0.1 19:29:42 INFO - mozlog == 3.4 19:29:42 INFO - moznetwork == 0.27 19:29:42 INFO - mozprocess == 0.23 19:29:42 INFO - mozprofile == 0.28 19:29:42 INFO - mozrunner == 6.13 19:29:42 INFO - mozscreenshot == 0.1 19:29:42 INFO - mozsystemmonitor == 0.3 19:29:42 INFO - moztest == 0.7 19:29:42 INFO - mozversion == 1.4 19:29:42 INFO - psutil == 3.1.1 19:29:42 INFO - requests == 1.2.3 19:29:42 INFO - wsgiref == 0.1.2 19:29:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:29:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:43 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')]} 19:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:43 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:43 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 19:29:43 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 19:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:43 INFO - 'HOME': '/Users/cltbld', 19:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:43 INFO - 'LOGNAME': 'cltbld', 19:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:43 INFO - 'MOZ_NO_REMOTE': '1', 19:29:43 INFO - 'NO_EM_RESTART': '1', 19:29:43 INFO - 'PAGER': '/bin/cat', 19:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:43 INFO - 'PWD': '/builds/slave/test', 19:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:43 INFO - 'SHELL': '/bin/bash', 19:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:43 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:43 INFO - 'USER': 'cltbld', 19:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:43 INFO - 'XPC_FLAGS': '0x0', 19:29:43 INFO - 'XPC_SERVICE_NAME': '0', 19:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 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)) 19:29:43 INFO - Cleaning up... 19:29:43 INFO - Return code: 0 19:29:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:29:43 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')]} 19:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:43 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')]} 19:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:29:43 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 0x1012d33f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101249570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00847c530>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, '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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 19:29:43 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 19:29:43 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 19:29:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:29:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:29:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:29:43 INFO - 'HOME': '/Users/cltbld', 19:29:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:29:43 INFO - 'LOGNAME': 'cltbld', 19:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:29:43 INFO - 'MOZ_NO_REMOTE': '1', 19:29:43 INFO - 'NO_EM_RESTART': '1', 19:29:43 INFO - 'PAGER': '/bin/cat', 19:29:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:29:43 INFO - 'PWD': '/builds/slave/test', 19:29:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:29:43 INFO - 'SHELL': '/bin/bash', 19:29:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:29:43 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:29:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:29:43 INFO - 'USER': 'cltbld', 19:29:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:29:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:29:43 INFO - 'XPC_FLAGS': '0x0', 19:29:43 INFO - 'XPC_SERVICE_NAME': '0', 19:29:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:29:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:29:43 INFO - Cleaning up... 19:29:43 INFO - Return code: 0 19:29:43 INFO - Running post-action listener: _resource_record_post_action 19:29:43 INFO - Running post-action listener: _start_resource_monitoring 19:29:43 INFO - Starting resource monitoring. 19:29:43 INFO - [mozharness: 2016-11-30 03:29:43.735956Z] Finished create-virtualenv step (success) 19:29:43 INFO - [mozharness: 2016-11-30 03:29:43.736958Z] Running install step. 19:29:43 INFO - Running pre-action listener: _resource_record_pre_action 19:29:43 INFO - Running main action method: install 19:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:29:43 INFO - Reading from file tmpfile_stdout 19:29:43 INFO - Detecting whether we're running mozinstall >=1.0... 19:29:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:29:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:29:44 INFO - Reading from file tmpfile_stdout 19:29:44 INFO - Output received: 19:29:44 INFO - Usage: mozinstall [options] installer 19:29:44 INFO - Options: 19:29:44 INFO - -h, --help show this help message and exit 19:29:44 INFO - -d DEST, --destination=DEST 19:29:44 INFO - Directory to install application into. [default: 19:29:44 INFO - "/builds/slave/test"] 19:29:44 INFO - --app=APP Application being installed. [default: firefox] 19:29:44 INFO - mkdir: /builds/slave/test/build/application 19:29:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:29:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:30:16 INFO - Reading from file tmpfile_stdout 19:30:16 INFO - Output received: 19:30:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:30:16 INFO - Running post-action listener: _resource_record_post_action 19:30:16 INFO - [mozharness: 2016-11-30 03:30:16.757904Z] Finished install step (success) 19:30:16 INFO - [mozharness: 2016-11-30 03:30:16.758029Z] Running run-tests step. 19:30:16 INFO - Running pre-action listener: _resource_record_pre_action 19:30:16 INFO - Running main action method: run_tests 19:30:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:30:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:30:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:30:16 INFO - 2016-11-29 19:30:16.772 screenresolution[1630:7297] starting screenresolution argv=screenresolution get 19:30:16 INFO - 2016-11-29 19:30:16.775 screenresolution[1630:7297] Display 0: 1600x1200x32@60 19:30:16 INFO - 2016-11-29 19:30:16.781 screenresolution[1631:7299] starting screenresolution argv=screenresolution list 19:30:16 INFO - Available Modes on Display 0 19:30:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:30:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:30:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:30:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:30:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:30:17 INFO - Intel Iris: 19:30:17 INFO - Chipset Model: Intel Iris 19:30:17 INFO - Type: GPU 19:30:17 INFO - Bus: Built-In 19:30:17 INFO - VRAM (Dynamic, Max): 1536 MB 19:30:17 INFO - Vendor: Intel (0x8086) 19:30:17 INFO - Device ID: 0x0a2e 19:30:17 INFO - Revision ID: 0x0009 19:30:17 INFO - Displays: 19:30:17 INFO - Display: 19:30:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:30:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:30:17 INFO - Display Serial Number: 1600x1200 60 19:30:17 INFO - Main Display: Yes 19:30:17 INFO - Mirror: Off 19:30:17 INFO - Online: Yes 19:30:17 INFO - Rotation: Supported 19:30:17 INFO - Return code: 0 19:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:30:17 INFO - Python 2.7.3 19:30:17 INFO - Return code: 0 19:30:17 INFO - grabbing minidump binary from tooltool 19:30:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:17 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 0x1010e9d30>, '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 0x100f50db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010e1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:30:17 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 19:30:17 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 19:30:17 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 19:30:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:30:17 INFO - Return code: 0 19:30:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:30:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:30:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:30:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:30:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:30:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 19:30:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0365 --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 19:30:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lhM64jt5ZG/Render', 19:30:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:17 INFO - 'HOME': '/Users/cltbld', 19:30:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:17 INFO - 'LOGNAME': 'cltbld', 19:30:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:30:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:30:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:17 INFO - 'MOZ_NO_REMOTE': '1', 19:30:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:30:17 INFO - 'NO_EM_RESTART': '1', 19:30:17 INFO - 'PAGER': '/bin/cat', 19:30:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:17 INFO - 'PWD': '/builds/slave/test', 19:30:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:30:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:17 INFO - 'SHELL': '/bin/bash', 19:30:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UrR79ehPZ3/Listeners', 19:30:17 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 19:30:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:17 INFO - 'USER': 'cltbld', 19:30:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:17 INFO - 'XPC_FLAGS': '0x0', 19:30:17 INFO - 'XPC_SERVICE_NAME': '0', 19:30:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 19:30:17 INFO - mozversion application_buildid: 20161129165126 19:30:17 INFO - mozversion application_changeset: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f 19:30:17 INFO - mozversion application_display_name: Nightly 19:30:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:30:17 INFO - mozversion application_name: Firefox 19:30:17 INFO - mozversion application_remotingname: firefox 19:30:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:30:17 INFO - mozversion application_vendor: Mozilla 19:30:17 INFO - mozversion application_version: 53.0a1 19:30:17 INFO - mozversion platform_buildid: 20161129165126 19:30:17 INFO - mozversion platform_changeset: adcc39e3cad0f32aba0efb478cc4a023a5dfc43f 19:30:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:30:17 INFO - mozversion platform_version: 53.0a1 19:30:17 INFO - using testdate: 1480476617 19:30:17 INFO - actual date: 1480476617 19:30:17 INFO - starting webserver on 'localhost:49242' 19:30:17 INFO - SUITE-START | Running 2 tests 19:30:17 INFO - TEST-START | dromaeo_css 19:30:17 INFO - operating with platform_type : mac_ 19:30:17 INFO - Initialising browser for dromaeo_css test... 19:30:17 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmph20L5k/profile http://localhost:49242/getInfo.html) 19:30:20 INFO - PROCESS | 1641 | 2016-11-29 19:30:20.650 plugin-container[1643:7469] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 19:30:20 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:30:20 INFO - PROCESS | 1641 | 2016-11-29 19:30:20.653 plugin-container[1643:7469] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 19:30:20 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:30:20 INFO - PROCESS | 1641 | 2016-11-29 19:30:20.654 plugin-container[1643:7469] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:30:20 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 19:30:20 INFO - PROCESS | 1641 | colorDepth:24 19:30:20 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 19:30:20 INFO - PROCESS | 1641 | __metrics 19:30:20 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 19:30:21 INFO - TEST-INFO | 1641: exit 0 19:30:21 INFO - Browser initialized. 19:30:21 INFO - Running cycle 1/1 for dromaeo_css test... 19:30:21 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmph20L5k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:30:24 INFO - PROCESS | 1644 | 2016-11-29 19:30:24.318 plugin-container[1645:7586] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 19:30:24 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:30:24 INFO - PROCESS | 1644 | 2016-11-29 19:30:24.319 plugin-container[1645:7586] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 19:30:24 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:30:24 INFO - PROCESS | 1644 | 2016-11-29 19:30:24.319 plugin-container[1645:7586] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:30:33 INFO - PROCESS | 1644 | RSS: Main: 242946048 19:30:33 INFO - PROCESS | 1644 | 19:31:23 INFO - PROCESS | 1644 | 1480476683590 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 19:33:36 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 19:33:39 INFO - PROCESS | 1644 | RSS: Main: 251703296 19:33:39 INFO - PROCESS | 1644 | 19:36:42 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 19:36:42 INFO - PROCESS | 1644 | RSS: Main: 261820416 19:36:42 INFO - PROCESS | 1644 | 19:39:44 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 19:39:45 INFO - PROCESS | 1644 | RSS: Main: 266387456 19:39:45 INFO - PROCESS | 1644 | 19:42:47 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 19:42:48 INFO - PROCESS | 1644 | RSS: Main: 264908800 19:42:48 INFO - PROCESS | 1644 | 19:45:50 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 19:45:51 INFO - PROCESS | 1644 | RSS: Main: 269316096 19:45:51 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 19:48:54 INFO - PROCESS | 1644 | RSS: Main: 273494016 19:48:54 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | __start_tp_report 19:48:54 INFO - PROCESS | 1644 | _x_x_mozilla_page_load 19:48:54 INFO - PROCESS | 1644 | _x_x_mozilla_page_load_details 19:48:54 INFO - PROCESS | 1644 | |i|pagename|runs| 19:48:54 INFO - PROCESS | 1644 | |0;dojo.html;2768.736969987851;3329.6337402885692;3355.83220838958;3377.5609170807793;3411.266577685797;5618.2415373924505;5623;5690.146478028298;5718.370979192286;5749.108888122338;5601.60788744788;5681.545476361892;5713.971430142854;5794.449527294911;5832.766689332432;5072.54347108762;5112.105381558231;5203.583713302926;5215.556677682402;5224.92162617561;4295.441592592959;4365.541618130098;5066.695998240099;5230.927659829745;5250.7374631268285;4335;5221.477852214767;5247.449017853128;5264.105102132647;5338.092524270884;4191.874243772691;5045.545900868903;5072.847814565569;5111.05445492585;5117.488251174853;5117.795288188505;5135.486451354872;5143.12566863634;5143.562797162245;5162.870928226825;5375.3280839895015;5555.472230138131;5590.357108932443;5656.8868622627315;5679.176519404705;6042.425969532887;6127;6172.660503672296;6175.505959523227;6231.594946328474;6173.104899789505;6193.84515387119;6206.968965155145;6225;6251.781187658456;5214.7914083437;5803.564732645069;5807.390224026459;5818.912716309259;5870.823875283749;5845.824625261249;6122.877542449126;6254.655993920287;6261.592996455216;6265.248170219603;6015.368386319374;6057.727402266909;6066.090086486974;6150.723217455135;6153.784617538365;6134.803982731443;6182.907256391158;6212.254529456494;6231.719572619243;6245.531585131133;5869.7945571904775;5929.436703513159;6165.445109940038;6181.072839074175;6209.751609935643;6040.426159514839;6110.953680641517;6127.785527506516;6131;6161.476274516626;4851.054044461367;4905.852824415273;4938.506149385033;4966.751662416937;4969.478204788517;3556.2176321209295;4319.2009478246;4341.869743907688;4355.063661312833;4431.313146462304;5614.466625670555;5812.854678633068;5845.649261044351;5847;5853.0635098384055;6211.534134939807;6246.469050130699;6268.686565671698;6283.968580157161;6293.527985401113;5722.37053924068;5750.6262092963825;5768.942310576925;5784.363719990798;5807.854803629859;4647.18674232008;5032.119379108642;5101.617378696613;5404.081306177881;5482.314710661168;4760.976195119002;5132.5124113209185;5426.565874730012;5532.834014979557;6301.338359472373;6136.94800169746;6178.351273116258;6193.473554747717;6228.065790131335;6260.780872669435;4883.829065982815;5486.6557693365385;6179.536534759821;6314.905276420765;6328.177336946167;6178.382161783966;6189.442950134509;6198.659073750987;6203.627782332894;6221.4089661482085;5983.341832398346;6090.878182436238;6127.662978536223;6154;6166.969165154145;5971.193888824953;6033.909491357545;6052.66710330936;6090.451859332681;6107.9083813741945;6019.819405417845;6032.819015429544;6038.335783063947;6047.334793172836;6061.787837425669;5640.71796410186;5867.090600956859;5947.791827286024;5956.463918247378;5962.314333851489;6102;6108;6117.541184411276;6126.785562505291;6128.417800308964;5922.2597175353085;5924.674142922181;5940.049592065248;5944.167816505779;5983.282006159288 19:48:54 INFO - PROCESS | 1644 | |1;ext.html;480.1621171056507;501.96737212081234;505.96530095953773;548.6433818018288;553.9861503462413;35416.93749187522;37682.623173768254;37766.24467510648;37837.62162378375;37978.05054873626;38053.429198562044;38554.03614909629;38566.80716596413;38718.41922371159;38778.64274750384;8137.552434616094;8252.587370631474;8483;8917.019127895943;10744.78510429791;10788.946055269713;11065.280756750826;11090;11095.167862410311;11110.388928608923;11090.33457992519;11101;11111.999920007198;11115.499802508866;11124.777504449907;9303.20922721566;11020.228583999084;11028.28316159447;11102.944485277603;11130.499127539199;10918.1811364148;10942.890571094267;10974.725631859188;10986.176036797193;10998.505067271912;37701.81149094237;38071;38085.85742427732;38116.80941595302;38155.23689526222;3366.6801653842845;3507.175813683782;3581.820908954568;3751.737378383514;3905.453236546857;28775.71224287751;29078.563821542695;29414.85292573545;29824.105276841514;29928;2695.3665888516184;3121.6732888522515;3208.2139875730363;3282.8030318180986;3300.669933006704;411614;424357;425981;426391;427831;47728.568142955766;54789.726051370286;55702.885942280926;56153.43846561564;56514.3705599747;58488.12267815986;58615.70692146512;58999.705001474715;59016.704916476;59110.40889591049;14985.775213371808;15606.843931560767;15651.686966260611;15654.765178522332;15658.45195418155;8332.50004999702;9172.807535020405;11477.196596238184;11867.70330741721;12003.819942700866;13596.796048059286;13822.516211932534;16519.83480165207;16547.089910055063;16586.253618587194;4799.5841226838165;5203.0677621540335;6138.478229350466;6165.599236049642;6226.813195604139;825.4593241426875;865.7099871542978;893.8167675626481;902.7849878623485;941.1576638908276;24297;28440.8577957113;28707.990740001915;28810.84756609712;29495.85252073726;955.694177863091;960.8949707836044;1007.4459047523833;1026.199564339816;1032.6592224565875;11901.523939042514;13366.26485543285;13571;14103.78844317336;15036.548903532912;559265;561921;566166;566334;567824;53471.465285347425;53527;53741.65645858807;53876.73061634667;53981.46018539764;1292.4700872642172;1325.5493132335052;1346.5152545083763;1356.4574170331946;1356.7625665508497;24602.753972460763;26090.478190436465;27548;27889.489945904967;28047.859760701067;46941;47254.76372618115;47297.763511182224;47414;47450.76274618605;482146;492257;494847;495351;496506;24347;25352;25465.872670636527;26283.737162628127;38142.237155257295;41183;41839.79080104702;42626.78686606547;42634.93412664708;42812.57187428086;34678.65321346754;34764.652353477155;35032.29935401227;35434.46848297226;35824.28351433009;34877;34930.65069349274;35715;35837.82081089578;35908;34561.65438345685;34620.63380175976;34713.13217169591;35019.29961400809;35502.46746298756;25590.87204563965;26347.86826065934;28109;31792.523112153798;31874.20314492156;22549.43626409353;23120.53758924846;23729.881350593136;23853.403664908517;24327.878360608083 19:48:54 INFO - PROCESS | 1644 | |2;jquery.html;367.3332900785075;367.43965452684654;374.63473113714133;381.81672797057394;395.7447446397074;15060.397584096636;19059.14233859476;19570.21719131235;19661.60676786467;20502.89748551259;19024.95362755048;19450.51373715658;19659.90170049148;19934;19977.300794472198;5656.377798442168;5790.420957904218;5917.171595976567;5926.496247818919;5989.98170311046;4100.2209580179815;4341.458782132345;5750.568707346944;6275.184226050607;6578.243501997284;6602.834929126762;6617.205935287748;6620.966895165518;6622.039804228384;6626.536142470029;5633.774649014034;5971.313298970609;6003.789867354665;6066.96861533536;6073.119397687355;5026.1204289249235;5341.305630268041;5826.184334193216;5875.911861322041;5890.381509941437;13069.86930130696;13219.537316193982;16631.91684041584;24046.398840028964;24420.63369049466;1920.4526709887614;1994.750656167979;2032.8272096871783;2106.9262575809926;2375.916842910507;9694.36986595869;10005;10360.689179324632;11926.46330915111;15334.926555141144;1556.2452210677811;1606.76701878227;1660.5682522544225;1755.4996825904557;1838.2279442634126;108741.45629271882;114169.85830141675;114948.27577586343;115114.42442787733;116637;11318.60384886525;11545.076393888468;11614.593489227836;11663.650090497129;11806.704832379088;11985.220960637706;14477.420903163966;14493.637659058397;14495.275236238145;14503.85496145046;8054.677812887418;8193.918060819315;8210.958945205235;8221.136780638066;8385.371097167616;8019.759407217793;8020.278174964281;8120.309773669186;8160.26557609818;8187.877181842277;4409.5149533551285;4541.409616749867;4570.382998295254;4590.472095708969;4612.60792832613;1525.8022452374832;1539.6843647052328;1700.70237708403;1891.527118220445;1935.0034732112972;786.9016372953381;842.0358689300716;849.3841964575633;904.3624244907325;1001.2840818814583;19613.50966225827;20206.696899546518;20916.686249706265;22231;22304.10783568639;929.363386080537;932.7807965128189;948.7343543807744;955.8040601676779;980.3088822380205;10544.789104217873;11006.339619622688;11012.724681883017;11062.723431914106;11067.612633557786;511124;513485;515120;526679;527632.361838196;122877.55540778955;123209.38395307965;124278.75721242852;124737.75262247442;126100.36949815376;967.8693376394237;983.2674657380154;997.0677416615342;998.3111652959435;1000.0799264676369;18302.816971830645;18334;18464.984425856706;18500.444986650422;18546.907265463586;53272.73363633157;53513;53634;54810;55185.724071380995;68641.97037044348;69211.30788692049;70661.29338706547;75691;75789;7865.842683146419;7968.681252749825;8068.59657017135;8141.633626486704;8221.79445513843;10229.795404092025;11878.82181767257;12229.388530573613;12275.631731048083;12625.81061284063;9662.903370966482;9889.813222413357;10028.097471227624;10365.429901355497;10587.894121058998;8542.530160841214;10022;10341.793164136825;10414.375137491776;10632.255742098288;10397.948010259901;10676.83984740214;10898.237123401286;10947.671569852917;11115.66653000379;7741.612919353897;7982.241687039722;7983.640736167003;8212.794680132805;8255.917440825515;9812.313138080266;10553.366797991837;10601.41692206936;10739.463026848782;10842.295250808673 19:48:54 INFO - PROCESS | 1644 | |3;mootools.html;1481.444458328127;1513.4551561437881;1714.888532245404;1813.9751040662018;1843.0784607696153;7413.777586672409;7668.156502784703;7922.286994170524;8350.707725229619;8351.498910065393;7937.880931786028;8238.134995825441;8255;8269;8282.213189746964;2168.29530402619;2218.9889050554725;2221.178164079293;2221.833362497811;2223.1996481266733;2017.5359667276534;2290.541891621674;2334.9182778602753;2342.320726989171;2344.871032093243;1964.5481539245982;2284.4060544258527;2320.037184568402;2321.454458202321;2326.336993956723;2117.4388786971463;2158.715564239275;2276.3739971507803;2289.4848659051745;2315.0392587076344;2322.1872344679396;2326.4183954011496;2341.0869760793303;2343.5781559319316;2347.612643913752;7791.571463569501;7932.801679958047;8186.426950113494;8273;8354.874676879852;5045.445001049881;5157.381114266312;5214.191800270964;5221.06020916235;5293.206019097128;3155.5108958111527;3284.5237440571223;3520.119970007498;3544.503769472276;3548.822558872046;1026.2764750850633;1046.947652617366;1067.8771941226703;1090.4493230918415;1090.929089609173;10388.896111038868;10424.68725938223;10664.893351066545;10675.572977080907;10716.303440276435;4394.362817391524;4522.525134860858;4591.4260717410325;4696.9060618787435;4697.953020469751;7349.338559529668;9037.367384283038;9082.727518174466;9095.135962083592;9163.71707960886;5064.341635587423;5095.388553373619;5101.336826212569;5107.770150343244;5115.820946266937;4238.915221695549;4487.820487180542;4683.95316046842;4712.575868171881;4782.258749893772;3808.485854409619;3822.866199682998;3872.6127387261613;3896.6103389660807;3961.8415263389143;1247.2641141726244;1277.4060062070996;1289.8387701537308;1294.3981048812348;1301.342821874947;791.7505985614513;875.1248751248751;888.9066648001867;913.5204017890528;943.1747221181466;5486.478784515464;5677.687727174963;5783.7397317119985;6045.324988539119;6049.122877182872;835.3484282259847;931.4039015030386;952.6856137474617;953.909378609031;1008.505832142245;3771.73597848148;3812.8856134316015;3828.0812604974603;3951.604839516083;3957;8445.95777021111;9481.762955926157;9925.553350099262;9929.652462163935;9995.250356223167;9864.260180486492;9891.060349266809;9896.55465504054;9906.05892440217;9926.2059035277;6825.795226143224;7345.15530713964;7709.113451952985;7711.113221979432;7728.922710772933;5679;5711.286089238845;5766.452187042224;5826.73779679907;5850.824475265749;8865.379423440298;9178.724638260863;9184.357095003554;9252.306077044113;9326.020767819553;9332.860007099764;9364.765880852761;9371.156595906128;9372.968973413055;9455.905440945779;6316.14732011191;6479.125318082187;6948.33990770876;7113.146422429341;7249.238829922782;7721.112072111779;7949.602519874099;7953.6818527260575;7953.761387158394;7973.800654983672;6913.516053876381;7098.112735908012;7160.283971602798;7203.171635261802;7205.171405288459;6920.100386949664;6974;6975.895361569682;6983.301669832976;7077.964610176916;6552.049952756728;6610.305917878553;6679.365460281266;6685.164354455693;6703.463722902254;4775.808967641395;4840;4848.660593758545;4883.267509873547;4931.334269873521;4618.699784513996;4774.379330686988;4887.193613053949;4958.082754690249;4971.751412429292 19:48:54 INFO - PROCESS | 1644 | |4;prototype.html;288.93787835615336;300.51316866675984;309.3040658518334;309.69649743251597;311.0962653491606;4672.789724462399;4681.9297710534365;4755.144074066671;4815.796050987253;5467.9453205467935;4875.902481950359;5183.377994640639;5294.96748134114;5315.634542628532;5376.758045887935;3250.789463165047;4096.631303182713;4098.282800509914;4114.732542384751;4115.423840662294;3456.2396272820065;4105.240530501852;4134.421181034642;4149.83400663973;4192.706510544263;3890.688744900401;4033.919321613566;4034.5158580970324;4043.9595604043875;4058.492688413948;3412.7269818414716;4057.6753859691303;4069.2471892699946;4079.8572049978106;4113.382992551112;2848.5584734366;3258.1365938026443;3405.4891766235264;3481.1645205150835;3548.970798468467;4527.4340707411575;5048.495150484959;5217;5231.816886408996;5273.630845840793;4034.7579145251084;4053.885181575061;4123.278426275389;4296.355546667994;4569.360289559464;5061.962297728956;5210.947890521084;5534.612577119603;5620.859478513029;5710.657360558338;2978.225661328049;3520.5951315598777;3623.9309403725965;3829.8659546915724;3841.3853783394525;5903.321118071433;5906.940930590682;5914.940850591482;5920.259967504062;6011.939880601181;3769.453429252743;3914.8042597869994;3921.9016976588177;3923.743847287764;3942.704297177723;3446.655334466533;3803.668715949451;3869.5550011748446;3932.941005884914;3963.4451176835264;4199.265128602482;4349.846825821239;4530.22986092365;4572.657050721209;4594.770261486912;4336.891577710582;5449.509544141047;5717.456841600041;5718.885622287531;5754.367019627837;1213.7255881324575;1226.558438961973;1259.1437822280755;1283.9165454245444;1284.4862055178003;844.4553263145237;863.8661007543841;869.4174902815129;869.7738587967174;872.2019352292581;4486.484054333728;4848.490908454562;4956.826511072168;5086.821961231339;5111.309973153577;4494.325851122358;4833.951660483421;5340.744924942635;5528.502434780889;5624;4725.432948046257;4738.31294462308;5069.462830766517;5098.515641014098;5115.437301896788;2438.3541109750845;2442.3894026493376;2606.2832721001496;2687.1535466328046;2886.422715456917;4841.95158048422;5604.803831865946;5683.318001839723;5752.223449834302;5786.623869448473;4613.884652883705;5089.720065396365;5388.568914486832;5662.405447428043;5728.79949201776;4840.709557426495;5419.214213939035;5511.834644960658;5523.613347065667;5703.870162297128;4919.950800491949;5453.481919217668;5524.254225679482;5531.280933478783;5545.445455454584;4632.3977882875015;5161.535461808456;5407.270018547446;5455.563554915518;5459.39946605881;4733.810647574196;4826.565609095198;5545.667259964415;5725.541956643375;5749.8562535936935;3216.1155682187587;3233.951490727594;3692.8552148833946;3699.9260014800093;3734.3278209922473;5234;5248.976449592293;5419.590906364295;5529.972350138384;5543.695096769717;5376.736466930346;5574.749136288958;5580.665160090408;5790.812883358985;5859.794907178229;4886.804527818847;5706.629069110494;5740.942590574208;5772.39389864075;5832.883342333214;5376.950930203521;5530.529904958124;5596.944030559643;5625.430823484802;5637.718114094361;4767.523247675204;4998.375203099613;5033.949660503495;5693.231413759089;5786.971065144816;5541.556675466034;5559.666420014651;5578.693171875586;5590.608657393944;5608.270924779916 19:48:54 INFO - PROCESS | 1644 | |5;yui.html;614.9453686926922;652.1587152573181;669.9062131301615;728.2389902551832;733.3839574757204;11801.232919860215;15026.699466010687;15027.398904043852;15041.172735499571;15045.473408430707;13536.72926541471;13558.186508809478;13682.247476388795;13683.384247708853;13690.52083177089;559.3455656881438;635.2694401438337;796.7808852565532;806.5322113174374;822.3873214455209;765.7358211417052;804.9758507244792;810.712197170005;812.0093485947135;815.0748899998486;868.2576397180414;870.1472556894247;870.3820287595814;870.4385671242045;871.424859592669;852.2117041736369;852.6376290076693;866.3675516872655;867.1718508824058;867.3451544084239;630.0203184048802;635.2789583822395;641.5669423139409;643.2217017408941;644.6196743921062;13800.102993305503;14128.364223609962;14151.080179788281;14199.503017394341;14733.263336833115;13794.793078103836;13965.231912244823;14029.78955315671;14281.14313141215;14349.210793406359;11437.885621143765;11750.178722298058;11753.588624398191;11770.2349347293;11869.52521899123;3646.5441819772527;3673.338799016176;3684.355237833395;3710.1652128271194;3817.9045523861846;108499;110270;114179.42910285479;114314.42842785816;114980;10703.357798532114;10750.784984300426;10771.946140269249;10989.560417583207;11117.444127793578;21654;21692.34922952314;21741.239056633258;21783.891080544498;21834.126634934742;7573.734919277799;7576.924230757732;7631.54210747357;7854.921450785419;7940.404469664798;3605.837737301828;4218.2196293685765;4274.615284624399;4336.176126535948;4395.428594282786;3375.746818988586;3428.4171690812623;3484.9477257841154;3492.4586689063235;3492.4761285806826;594.9345571987078;621.7979156774066;623.2863371439677;638.6487431912429;642.6304874697067;562.5078056700387;576.4811669497486;596.4423264247943;638.1831255992337;651.3551583931968;8844.778880528038;8931.553422328858;8942.150495702897;9304.395214311047;9473.715788526355;543.9537639300625;593.9703014849326;607.1954660075329;645.9755721852883;676.4655921821706;4015.6748273069816;4192.601702838226;4348.065165989327;4447.310666846644;4824.300476430898;101620;105246;106212.34469138179;108768.45615772028;110097;93685.53157234307;93809;94050.5297473522;95133.5243323779;96415.55376669356;84024.57987710145;84427;84613.57693211618;85876;86157.99999999875;8166.714165004197;9500.857487137837;9906.504674766089;9930.602775888883;9949.900500994898;40361;49953.75023124861;50108.7472813168;52671;54665.72667136639;69789;77174;77253.61373193098;78114;79646.60176699079;10447.791044179226;11706.239094458833;12362.75274494523;12761.298128603015;12928.952262864228;10451.341806857212;13405.66485837862;14297.928510357799;14523.273836308354;14661;13337.333133343489;13462.932685336511;13585.52450664222;13635.522756703467;13813.022347946087;11326.943365283121;13672.316384180553;13726.176429414267;14048.157110573398;14332.928335358256;10252.487375631337;13399.866001339862;13466.93266533661;13735.862641373458;13900.960005562178;11043.72390690207;11052.668419947415;11460.770784584429;11507.827382589101;11572.59495917639;8732.070568244188;10187.490625468548;11074;11495.310281383145;11533.365664888512 19:48:54 INFO - PROCESS | 1644 | __end_tp_report 19:48:54 INFO - PROCESS | 1644 | __start_cc_report 19:48:54 INFO - PROCESS | 1644 | _x_x_mozilla_cycle_collect,383 19:48:54 INFO - PROCESS | 1644 | __end_cc_report 19:48:54 INFO - PROCESS | 1644 | __startTimestamp1480477734529__endTimestamp 19:48:54 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | ------- Summary: start ------- 19:48:54 INFO - PROCESS | 1644 | Number of tests: 6 19:48:54 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | [#0] dojo.html Cycles:165 Average:5634.27 Median:5867.09 stddev:694.51 (11.8%) stddev-sans-first:659.26 19:48:54 INFO - PROCESS | 1644 | Values: 2768.7 3329.6 3355.8 3377.6 3411.3 5618.2 5623.0 5690.1 5718.4 5749.1 5601.6 5681.5 5714.0 5794.4 5832.8 5072.5 5112.1 5203.6 5215.6 5224.9 4295.4 4365.5 5066.7 5230.9 5250.7 4335.0 5221.5 5247.4 5264.1 5338.1 4191.9 5045.5 5072.8 5111.1 5117.5 5117.8 5135.5 5143.1 5143.6 5162.9 5375.3 5555.5 5590.4 5656.9 5679.2 6042.4 6127.0 6172.7 6175.5 6231.6 6173.1 6193.8 6207.0 6225.0 6251.8 5214.8 5803.6 5807.4 5818.9 5870.8 5845.8 6122.9 6254.7 6261.6 6265.2 6015.4 6057.7 6066.1 6150.7 6153.8 6134.8 6182.9 6212.3 6231.7 6245.5 5869.8 5929.4 6165.4 6181.1 6209.8 6040.4 6111.0 6127.8 6131.0 6161.5 4851.1 4905.9 4938.5 4966.8 4969.5 3556.2 4319.2 4341.9 4355.1 4431.3 5614.5 5812.9 5845.6 5847.0 5853.1 6211.5 6246.5 6268.7 6284.0 6293.5 5722.4 5750.6 5768.9 5784.4 5807.9 4647.2 5032.1 5101.6 5404.1 5482.3 4761.0 5132.5 5426.6 5532.8 6301.3 6136.9 6178.4 6193.5 6228.1 6260.8 4883.8 5486.7 6179.5 6314.9 6328.2 6178.4 6189.4 6198.7 6203.6 6221.4 5983.3 6090.9 6127.7 6154.0 6167.0 5971.2 6033.9 6052.7 6090.5 6107.9 6019.8 6032.8 6038.3 6047.3 6061.8 5640.7 5867.1 5947.8 5956.5 5962.3 6102.0 6108.0 6117.5 6126.8 6128.4 5922.3 5924.7 5940.0 5944.2 5983.3 19:48:54 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | [#1] ext.html Cycles:180 Average:62326.33 Median:19462.06 stddev:132381.15 (680.2%) stddev-sans-first:132671.07 19:48:54 INFO - PROCESS | 1644 | Values: 480.2 502.0 506.0 548.6 554.0 35416.9 37682.6 37766.2 37837.6 37978.1 38053.4 38554.0 38566.8 38718.4 38778.6 8137.6 8252.6 8483.0 8917.0 10744.8 10788.9 11065.3 11090.0 11095.2 11110.4 11090.3 11101.0 11112.0 11115.5 11124.8 9303.2 11020.2 11028.3 11102.9 11130.5 10918.2 10942.9 10974.7 10986.2 10998.5 37701.8 38071.0 38085.9 38116.8 38155.2 3366.7 3507.2 3581.8 3751.7 3905.5 28775.7 29078.6 29414.9 29824.1 29928.0 2695.4 3121.7 3208.2 3282.8 3300.7 411614.0 424357.0 425981.0 426391.0 427831.0 47728.6 54789.7 55702.9 56153.4 56514.4 58488.1 58615.7 58999.7 59016.7 59110.4 14985.8 15606.8 15651.7 15654.8 15658.5 8332.5 9172.8 11477.2 11867.7 12003.8 13596.8 13822.5 16519.8 16547.1 16586.3 4799.6 5203.1 6138.5 6165.6 6226.8 825.5 865.7 893.8 902.8 941.2 24297.0 28440.9 28708.0 28810.8 29495.9 955.7 960.9 1007.4 1026.2 1032.7 11901.5 13366.3 13571.0 14103.8 15036.5 559265.0 561921.0 566166.0 566334.0 567824.0 53471.5 53527.0 53741.7 53876.7 53981.5 1292.5 1325.5 1346.5 1356.5 1356.8 24602.8 26090.5 27548.0 27889.5 28047.9 46941.0 47254.8 47297.8 47414.0 47450.8 482146.0 492257.0 494847.0 495351.0 496506.0 24347.0 25352.0 25465.9 26283.7 38142.2 41183.0 41839.8 42626.8 42634.9 42812.6 34678.7 34764.7 35032.3 35434.5 35824.3 34877.0 34930.7 35715.0 35837.8 35908.0 34561.7 34620.6 34713.1 35019.3 35502.5 25590.9 26347.9 28109.0 31792.5 31874.2 22549.4 23120.5 23729.9 23853.4 24327.9 19:48:54 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | [#2] jquery.html Cycles:180 Average:32097.10 Median:24233.52 stddev:87193.53 (359.8%) stddev-sans-first:87405.58 19:48:54 INFO - PROCESS | 1644 | Values: 367.3 367.4 374.6 381.8 395.7 15060.4 19059.1 19570.2 19661.6 20502.9 19025.0 19450.5 19659.9 19934.0 19977.3 5656.4 5790.4 5917.2 5926.5 5990.0 4100.2 4341.5 5750.6 6275.2 6578.2 6602.8 6617.2 6621.0 6622.0 6626.5 5633.8 5971.3 6003.8 6067.0 6073.1 5026.1 5341.3 5826.2 5875.9 5890.4 13069.9 13219.5 16631.9 24046.4 24420.6 1920.5 1994.8 2032.8 2106.9 2375.9 9694.4 10005.0 10360.7 11926.5 15334.9 1556.2 1606.8 1660.6 1755.5 1838.2 108741.5 114169.9 114948.3 115114.4 116637.0 11318.6 11545.1 11614.6 11663.7 11806.7 11985.2 14477.4 14493.6 14495.3 14503.9 8054.7 8193.9 8211.0 8221.1 8385.4 8019.8 8020.3 8120.3 8160.3 8187.9 4409.5 4541.4 4570.4 4590.5 4612.6 1525.8 1539.7 1700.7 1891.5 1935.0 786.9 842.0 849.4 904.4 1001.3 19613.5 20206.7 20916.7 22231.0 22304.1 929.4 932.8 948.7 955.8 980.3 10544.8 11006.3 11012.7 11062.7 11067.6 511124.0 513485.0 515120.0 526679.0 527632.4 122877.6 123209.4 124278.8 124737.8 126100.4 967.9 983.3 997.1 998.3 1000.1 18302.8 18334.0 18465.0 18500.4 18546.9 53272.7 53513.0 53634.0 54810.0 55185.7 68642.0 69211.3 70661.3 75691.0 75789.0 7865.8 7968.7 8068.6 8141.6 8221.8 10229.8 11878.8 12229.4 12275.6 12625.8 9662.9 9889.8 10028.1 10365.4 10587.9 8542.5 10022.0 10341.8 10414.4 10632.3 10397.9 10676.8 10898.2 10947.7 11115.7 7741.6 7982.2 7983.6 8212.8 8255.9 9812.3 10553.4 10601.4 10739.5 10842.3 19:48:54 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | [#3] mootools.html Cycles:180 Average:5374.85 Median:5389.84 stddev:2888.10 (53.6%) stddev-sans-first:2881.38 19:48:54 INFO - PROCESS | 1644 | Values: 1481.4 1513.5 1714.9 1814.0 1843.1 7413.8 7668.2 7922.3 8350.7 8351.5 7937.9 8238.1 8255.0 8269.0 8282.2 2168.3 2219.0 2221.2 2221.8 2223.2 2017.5 2290.5 2334.9 2342.3 2344.9 1964.5 2284.4 2320.0 2321.5 2326.3 2117.4 2158.7 2276.4 2289.5 2315.0 2322.2 2326.4 2341.1 2343.6 2347.6 7791.6 7932.8 8186.4 8273.0 8354.9 5045.4 5157.4 5214.2 5221.1 5293.2 3155.5 3284.5 3520.1 3544.5 3548.8 1026.3 1046.9 1067.9 1090.4 1090.9 10388.9 10424.7 10664.9 10675.6 10716.3 4394.4 4522.5 4591.4 4696.9 4698.0 7349.3 9037.4 9082.7 9095.1 9163.7 5064.3 5095.4 5101.3 5107.8 5115.8 4238.9 4487.8 4684.0 4712.6 4782.3 3808.5 3822.9 3872.6 3896.6 3961.8 1247.3 1277.4 1289.8 1294.4 1301.3 791.8 875.1 888.9 913.5 943.2 5486.5 5677.7 5783.7 6045.3 6049.1 835.3 931.4 952.7 953.9 1008.5 3771.7 3812.9 3828.1 3951.6 3957.0 8446.0 9481.8 9925.6 9929.7 9995.3 9864.3 9891.1 9896.6 9906.1 9926.2 6825.8 7345.2 7709.1 7711.1 7728.9 5679.0 5711.3 5766.5 5826.7 5850.8 8865.4 9178.7 9184.4 9252.3 9326.0 9332.9 9364.8 9371.2 9373.0 9455.9 6316.1 6479.1 6948.3 7113.1 7249.2 7721.1 7949.6 7953.7 7953.8 7973.8 6913.5 7098.1 7160.3 7203.2 7205.2 6920.1 6974.0 6975.9 6983.3 7078.0 6552.0 6610.3 6679.4 6685.2 6703.5 4775.8 4840.0 4848.7 4883.3 4931.3 4618.7 4774.4 4887.2 4958.1 4971.8 19:48:54 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | [#4] prototype.html Cycles:180 Average:4437.90 Median:4903.38 stddev:1385.49 (28.3%) stddev-sans-first:1353.93 19:48:54 INFO - PROCESS | 1644 | Values: 288.9 300.5 309.3 309.7 311.1 4672.8 4681.9 4755.1 4815.8 5467.9 4875.9 5183.4 5295.0 5315.6 5376.8 3250.8 4096.6 4098.3 4114.7 4115.4 3456.2 4105.2 4134.4 4149.8 4192.7 3890.7 4033.9 4034.5 4044.0 4058.5 3412.7 4057.7 4069.2 4079.9 4113.4 2848.6 3258.1 3405.5 3481.2 3549.0 4527.4 5048.5 5217.0 5231.8 5273.6 4034.8 4053.9 4123.3 4296.4 4569.4 5062.0 5210.9 5534.6 5620.9 5710.7 2978.2 3520.6 3623.9 3829.9 3841.4 5903.3 5906.9 5914.9 5920.3 6011.9 3769.5 3914.8 3921.9 3923.7 3942.7 3446.7 3803.7 3869.6 3932.9 3963.4 4199.3 4349.8 4530.2 4572.7 4594.8 4336.9 5449.5 5717.5 5718.9 5754.4 1213.7 1226.6 1259.1 1283.9 1284.5 844.5 863.9 869.4 869.8 872.2 4486.5 4848.5 4956.8 5086.8 5111.3 4494.3 4834.0 5340.7 5528.5 5624.0 4725.4 4738.3 5069.5 5098.5 5115.4 2438.4 2442.4 2606.3 2687.2 2886.4 4842.0 5604.8 5683.3 5752.2 5786.6 4613.9 5089.7 5388.6 5662.4 5728.8 4840.7 5419.2 5511.8 5523.6 5703.9 4920.0 5453.5 5524.3 5531.3 5545.4 4632.4 5161.5 5407.3 5455.6 5459.4 4733.8 4826.6 5545.7 5725.5 5749.9 3216.1 3234.0 3692.9 3699.9 3734.3 5234.0 5249.0 5419.6 5530.0 5543.7 5376.7 5574.7 5580.7 5790.8 5859.8 4886.8 5706.6 5740.9 5772.4 5832.9 5377.0 5530.5 5596.9 5625.4 5637.7 4767.5 4998.4 5033.9 5693.2 5787.0 5541.6 5559.7 5578.7 5590.6 5608.3 19:48:54 INFO - PROCESS | 1644 | 19:48:54 INFO - PROCESS | 1644 | [#5] yui.html Cycles:180 Average:21134.73 Median:22188.34 stddev:31418.00 (141.6%) stddev-sans-first:31468.35 19:48:54 INFO - PROCESS | 1644 | Values: 614.9 652.2 669.9 728.2 733.4 11801.2 15026.7 15027.4 15041.2 15045.5 13536.7 13558.2 13682.2 13683.4 13690.5 559.3 635.3 796.8 806.5 822.4 765.7 805.0 810.7 812.0 815.1 868.3 870.1 870.4 870.4 871.4 852.2 852.6 866.4 867.2 867.3 630.0 635.3 641.6 643.2 644.6 13800.1 14128.4 14151.1 14199.5 14733.3 13794.8 13965.2 14029.8 14281.1 14349.2 11437.9 11750.2 11753.6 11770.2 11869.5 3646.5 3673.3 3684.4 3710.2 3817.9 108499.0 110270.0 114179.4 114314.4 114980.0 10703.4 10750.8 10771.9 10989.6 11117.4 21654.0 21692.3 21741.2 21783.9 21834.1 7573.7 7576.9 7631.5 7854.9 7940.4 3605.8 4218.2 4274.6 4336.2 4395.4 3375.7 3428.4 3484.9 3492.5 3492.5 594.9 621.8 623.3 638.6 642.6 562.5 576.5 596.4 638.2 651.4 8844.8 8931.6 8942.2 9304.4 9473.7 544.0 594.0 607.2 646.0 676.5 4015.7 4192.6 4348.1 4447.3 4824.3 101620.0 105246.0 106212.3 108768.5 110097.0 93685.5 93809.0 94050.5 95133.5 96415.6 84024.6 84427.0 84613.6 85876.0 86158.0 8166.7 9500.9 9906.5 9930.6 9949.9 40361.0 49953.8 50108.7 52671.0 54665.7 69789.0 77174.0 77253.6 78114.0 79646.6 10447.8 11706.2 12362.8 12761.3 12929.0 10451.3 13405.7 14297.9 14523.3 14661.0 13337.3 13462.9 13585.5 13635.5 13813.0 11326.9 13672.3 13726.2 14048.2 14332.9 10252.5 13399.9 13466.9 13735.9 13901.0 11043.7 11052.7 11460.8 11507.8 11572.6 8732.1 10187.5 11074.0 11495.3 11533.4 19:48:54 INFO - PROCESS | 1644 | -------- Summary: end -------- 19:48:54 INFO - PROCESS | 1644 | 19:48:55 INFO - TEST-INFO | 1644: exit 0 19:48:55 INFO - TEST-OK | dromaeo_css | took 1117833ms 19:48:55 INFO - TEST-START | kraken 19:48:55 INFO - operating with platform_type : mac_ 19:48:55 INFO - Initialising browser for kraken test... 19:48:55 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpTiBr3B/profile http://localhost:49242/getInfo.html) 19:48:58 INFO - PROCESS | 1656 | 2016-11-29 19:48:58.178 plugin-container[1657:9939] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 19:48:58 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:48:58 INFO - PROCESS | 1656 | 2016-11-29 19:48:58.182 plugin-container[1657:9939] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 19:48:58 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:48:58 INFO - PROCESS | 1656 | 2016-11-29 19:48:58.182 plugin-container[1657:9939] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:48:58 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 19:48:58 INFO - PROCESS | 1656 | colorDepth:24 19:48:58 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 19:48:58 INFO - PROCESS | 1656 | __metrics 19:48:58 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3220: TypeError: this.tabs is undefined 19:48:59 INFO - TEST-INFO | 1656: exit 0 19:48:59 INFO - Browser initialized. 19:48:59 INFO - Running cycle 1/1 for kraken test... 19:48:59 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpTiBr3B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:49:02 INFO - PROCESS | 1658 | 2016-11-29 19:49:02.155 plugin-container[1659:10071] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8733, name = 'com.apple.tsm.portname' 19:49:02 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:02 INFO - PROCESS | 1658 | 2016-11-29 19:49:02.156 plugin-container[1659:10071] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 19:49:02 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:02 INFO - PROCESS | 1658 | 2016-11-29 19:49:02.156 plugin-container[1659:10071] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:49:11 INFO - PROCESS | 1658 | RSS: Main: 242900992 19:49:11 INFO - PROCESS | 1658 | 19:49:14 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 19:49:15 INFO - PROCESS | 1658 | RSS: Main: 255086592 19:49:15 INFO - PROCESS | 1658 | 19:49:18 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 19:49:18 INFO - PROCESS | 1658 | RSS: Main: 262692864 19:49:18 INFO - PROCESS | 1658 | 19:49:22 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 19:49:22 INFO - PROCESS | 1658 | RSS: Main: 265334784 19:49:22 INFO - PROCESS | 1658 | 19:49:24 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 19:49:25 INFO - PROCESS | 1658 | RSS: Main: 267792384 19:49:25 INFO - PROCESS | 1658 | 19:49:27 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:49:27 INFO - PROCESS | 1658 | RSS: Main: 267931648 19:49:27 INFO - PROCESS | 1658 | 19:49:30 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 19:49:31 INFO - PROCESS | 1658 | RSS: Main: 273625088 19:49:31 INFO - PROCESS | 1658 | 19:49:34 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 19:49:34 INFO - PROCESS | 1658 | RSS: Main: 273973248 19:49:34 INFO - PROCESS | 1658 | 19:49:37 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 19:49:38 INFO - PROCESS | 1658 | RSS: Main: 273588224 19:49:38 INFO - PROCESS | 1658 | 19:49:40 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:49:40 INFO - PROCESS | 1658 | RSS: Main: 271974400 19:49:40 INFO - PROCESS | 1658 | 19:49:42 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:49:43 INFO - PROCESS | 1658 | RSS: Main: 272510976 19:49:43 INFO - PROCESS | 1658 | 19:49:45 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:49:46 INFO - PROCESS | 1658 | RSS: Main: 272351232 19:49:46 INFO - PROCESS | 1658 | 19:49:49 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:49:50 INFO - PROCESS | 1658 | RSS: Main: 272920576 19:49:50 INFO - PROCESS | 1658 | 19:49:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:49:54 INFO - PROCESS | 1658 | RSS: Main: 272752640 19:49:54 INFO - PROCESS | 1658 | 19:49:55 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 19:49:56 INFO - PROCESS | 1658 | RSS: Main: 274489344 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | __start_tp_report 19:49:56 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 19:49:56 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 19:49:56 INFO - PROCESS | 1658 | |i|pagename|runs| 19:49:56 INFO - PROCESS | 1658 | |0;ai-astar;110;120;139;169;150;119;116;118;118;114 19:49:56 INFO - PROCESS | 1658 | |1;audio-beat-detection;150;118;116;151;119;116;112;115;131;119 19:49:56 INFO - PROCESS | 1658 | |2;audio-dft;221;202;199;279;277;225;196;202;229;287 19:49:56 INFO - PROCESS | 1658 | |3;audio-fft;77;59;59;86;58;58;63;57;70;59 19:49:56 INFO - PROCESS | 1658 | |4;audio-oscillator;95;70;83;72;70;72;81;71;74;82 19:49:56 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;89;87;88;86;97;125;89;85;90;85 19:49:56 INFO - PROCESS | 1658 | |6;imaging-darkroom;98;95;105;120;95;95;95;95;96;96 19:49:56 INFO - PROCESS | 1658 | |7;imaging-desaturate;95;96;92;92;98;93;93;81;147;146 19:49:56 INFO - PROCESS | 1658 | |8;json-parse-financial;57;58;57;58;57;60;60;81;53;48 19:49:56 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;76;100;113;92;70;72;73;75;73;72 19:49:56 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;85;90;121;102;122;106;76;106;112;110 19:49:56 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;154;167;156;150;150;154;168;149;201;148 19:49:56 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;228;193;186;158;211;196;242;181;176;171 19:49:56 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;72;73;73;73;73;69;70;71;72;84 19:49:56 INFO - PROCESS | 1658 | __end_tp_report 19:49:56 INFO - PROCESS | 1658 | __start_cc_report 19:49:56 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,949 19:49:56 INFO - PROCESS | 1658 | __end_cc_report 19:49:56 INFO - PROCESS | 1658 | __startTimestamp1480477796067__endTimestamp 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | ------- Summary: start ------- 19:49:56 INFO - PROCESS | 1658 | Number of tests: 14 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:127.30 Median:119.50 stddev:19.12 (16.0%) stddev-sans-first:19.23 19:49:56 INFO - PROCESS | 1658 | Values: 110.0 120.0 139.0 169.0 150.0 119.0 116.0 118.0 118.0 114.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:119.00 stddev:14.48 (12.2%) stddev-sans-first:12.13 19:49:56 INFO - PROCESS | 1658 | Values: 150.0 118.0 116.0 151.0 119.0 116.0 112.0 115.0 131.0 119.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:231.70 Median:227.00 stddev:35.94 (15.8%) stddev-sans-first:37.91 19:49:56 INFO - PROCESS | 1658 | Values: 221.0 202.0 199.0 279.0 277.0 225.0 196.0 202.0 229.0 287.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:64.60 Median:61.00 stddev:9.90 (16.2%) stddev-sans-first:9.43 19:49:56 INFO - PROCESS | 1658 | Values: 77.0 59.0 59.0 86.0 58.0 58.0 63.0 57.0 70.0 59.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:77.50 stddev:8.12 (10.5%) stddev-sans-first:5.41 19:49:56 INFO - PROCESS | 1658 | Values: 95.0 70.0 83.0 72.0 70.0 72.0 81.0 71.0 74.0 82.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:88.50 stddev:12.07 (13.6%) stddev-sans-first:12.75 19:49:56 INFO - PROCESS | 1658 | Values: 89.0 87.0 88.0 86.0 97.0 125.0 89.0 85.0 90.0 85.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:95.00 stddev:8.00 (8.4%) stddev-sans-first:8.48 19:49:56 INFO - PROCESS | 1658 | Values: 98.0 95.0 105.0 120.0 95.0 95.0 95.0 95.0 96.0 96.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:103.30 Median:93.00 stddev:23.21 (25.0%) stddev-sans-first:24.42 19:49:56 INFO - PROCESS | 1658 | Values: 95.0 96.0 92.0 92.0 98.0 93.0 93.0 81.0 147.0 146.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:58.00 stddev:8.54 (14.7%) stddev-sans-first:9.03 19:49:56 INFO - PROCESS | 1658 | Values: 57.0 58.0 57.0 58.0 57.0 60.0 60.0 81.0 53.0 48.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:81.60 Median:73.00 stddev:14.81 (20.3%) stddev-sans-first:15.57 19:49:56 INFO - PROCESS | 1658 | Values: 76.0 100.0 113.0 92.0 70.0 72.0 73.0 75.0 73.0 72.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:103.00 Median:121.50 stddev:15.11 (12.4%) stddev-sans-first:14.56 19:49:56 INFO - PROCESS | 1658 | Values: 85.0 90.0 121.0 102.0 122.0 106.0 76.0 106.0 112.0 110.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:159.70 Median:155.00 stddev:16.15 (10.4%) stddev-sans-first:16.99 19:49:56 INFO - PROCESS | 1658 | Values: 154.0 167.0 156.0 150.0 150.0 154.0 168.0 149.0 201.0 148.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.20 Median:194.50 stddev:26.11 (13.4%) stddev-sans-first:24.66 19:49:56 INFO - PROCESS | 1658 | Values: 228.0 193.0 186.0 158.0 211.0 196.0 242.0 181.0 176.0 171.0 19:49:56 INFO - PROCESS | 1658 | 19:49:56 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:73.00 stddev:4.11 (5.6%) stddev-sans-first:4.34 19:49:56 INFO - PROCESS | 1658 | Values: 72.0 73.0 73.0 73.0 73.0 69.0 70.0 71.0 72.0 84.0 19:49:56 INFO - PROCESS | 1658 | -------- Summary: end -------- 19:49:56 INFO - PROCESS | 1658 | 19:49:57 INFO - TEST-INFO | 1658: exit 0 19:49:57 INFO - TEST-OK | kraken | took 62157ms 19:49:57 INFO - SUITE-END | took 1179s 19:49:57 INFO - Completed test suite (00:19:40) 19:49:57 INFO - javascript benchmark 19:49:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7059.814381146981, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2768.736969987851, 3329.6337402885692, 3355.83220838958, 3377.5609170807793, 3411.266577685797, 5618.2415373924505, 5623.0, 5690.146478028298, 5718.370979192286, 5749.108888122338, 5601.60788744788, 5681.545476361892, 5713.971430142854, 5794.449527294911, 5832.766689332432, 5072.54347108762, 5112.105381558231, 5203.583713302926, 5215.556677682402, 5224.92162617561, 4295.441592592959, 4365.541618130098, 5066.695998240099, 5230.927659829745, 5250.7374631268285, 4335.0, 5221.477852214767, 5247.449017853128, 5264.105102132647, 5338.092524270884, 4191.874243772691, 5045.545900868903, 5072.847814565569, 5111.05445492585, 5117.488251174853, 5117.795288188505, 5135.486451354872, 5143.12566863634, 5143.562797162245, 5162.870928226825, 5375.3280839895015, 5555.472230138131, 5590.357108932443, 5656.8868622627315, 5679.176519404705, 6042.425969532887, 6127.0, 6172.660503672296, 6175.505959523227, 6231.594946328474, 6173.104899789505, 6193.84515387119, 6206.968965155145, 6225.0, 6251.781187658456, 5214.7914083437, 5803.564732645069, 5807.390224026459, 5818.912716309259, 5870.823875283749, 5845.824625261249, 6122.877542449126, 6254.655993920287, 6261.592996455216, 6265.248170219603, 6015.368386319374, 6057.727402266909, 6066.090086486974, 6150.723217455135, 6153.784617538365, 6134.803982731443, 6182.907256391158, 6212.254529456494, 6231.719572619243, 6245.531585131133, 5869.7945571904775, 5929.436703513159, 6165.445109940038, 6181.072839074175, 6209.751609935643, 6040.426159514839, 6110.953680641517, 6127.785527506516, 6131.0, 6161.476274516626, 4851.054044461367, 4905.852824415273, 4938.506149385033, 4966.751662416937, 4969.478204788517, 3556.2176321209295, 4319.2009478246, 4341.869743907688, 4355.063661312833, 4431.313146462304, 5614.466625670555, 5812.854678633068, 5845.649261044351, 5847.0, 5853.0635098384055, 6211.534134939807, 6246.469050130699, 6268.686565671698, 6283.968580157161, 6293.527985401113, 5722.37053924068, 5750.6262092963825, 5768.942310576925, 5784.363719990798, 5807.854803629859, 4647.18674232008, 5032.119379108642, 5101.617378696613, 5404.081306177881, 5482.314710661168, 4760.976195119002, 5132.5124113209185, 5426.565874730012, 5532.834014979557, 6301.338359472373, 6136.94800169746, 6178.351273116258, 6193.473554747717, 6228.065790131335, 6260.780872669435, 4883.829065982815, 5486.6557693365385, 6179.536534759821, 6314.905276420765, 6328.177336946167, 6178.382161783966, 6189.442950134509, 6198.659073750987, 6203.627782332894, 6221.4089661482085, 5983.341832398346, 6090.878182436238, 6127.662978536223, 6154.0, 6166.969165154145, 5971.193888824953, 6033.909491357545, 6052.66710330936, 6090.451859332681, 6107.9083813741945, 6019.819405417845, 6032.819015429544, 6038.335783063947, 6047.334793172836, 6061.787837425669, 5640.71796410186, 5867.090600956859, 5947.791827286024, 5956.463918247378, 5962.314333851489, 6102.0, 6108.0, 6117.541184411276, 6126.785562505291, 6128.417800308964, 5922.2597175353085, 5924.674142922181, 5940.049592065248, 5944.167816505779, 5983.282006159288], "value": 5588.244010616349, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.1621171056507, 501.96737212081234, 505.96530095953773, 548.6433818018288, 553.9861503462413, 35416.93749187522, 37682.623173768254, 37766.24467510648, 37837.62162378375, 37978.05054873626, 38053.429198562044, 38554.03614909629, 38566.80716596413, 38718.41922371159, 38778.64274750384, 8137.552434616094, 8252.587370631474, 8483.0, 8917.019127895943, 10744.78510429791, 10788.946055269713, 11065.280756750826, 11090.0, 11095.167862410311, 11110.388928608923, 11090.33457992519, 11101.0, 11111.999920007198, 11115.499802508866, 11124.777504449907, 9303.20922721566, 11020.228583999084, 11028.28316159447, 11102.944485277603, 11130.499127539199, 10918.1811364148, 10942.890571094267, 10974.725631859188, 10986.176036797193, 10998.505067271912, 37701.81149094237, 38071.0, 38085.85742427732, 38116.80941595302, 38155.23689526222, 3366.6801653842845, 3507.175813683782, 3581.820908954568, 3751.737378383514, 3905.453236546857, 28775.71224287751, 29078.563821542695, 29414.85292573545, 29824.105276841514, 29928.0, 2695.3665888516184, 3121.6732888522515, 3208.2139875730363, 3282.8030318180986, 3300.669933006704, 411614.0, 424357.0, 425981.0, 426391.0, 427831.0, 47728.568142955766, 54789.726051370286, 55702.885942280926, 56153.43846561564, 56514.3705599747, 58488.12267815986, 58615.70692146512, 58999.705001474715, 59016.704916476, 59110.40889591049, 14985.775213371808, 15606.843931560767, 15651.686966260611, 15654.765178522332, 15658.45195418155, 8332.50004999702, 9172.807535020405, 11477.196596238184, 11867.70330741721, 12003.819942700866, 13596.796048059286, 13822.516211932534, 16519.83480165207, 16547.089910055063, 16586.253618587194, 4799.5841226838165, 5203.0677621540335, 6138.478229350466, 6165.599236049642, 6226.813195604139, 825.4593241426875, 865.7099871542978, 893.8167675626481, 902.7849878623485, 941.1576638908276, 24297.0, 28440.8577957113, 28707.990740001915, 28810.84756609712, 29495.85252073726, 955.694177863091, 960.8949707836044, 1007.4459047523833, 1026.199564339816, 1032.6592224565875, 11901.523939042514, 13366.26485543285, 13571.0, 14103.78844317336, 15036.548903532912, 559265.0, 561921.0, 566166.0, 566334.0, 567824.0, 53471.465285347425, 53527.0, 53741.65645858807, 53876.73061634667, 53981.46018539764, 1292.4700872642172, 1325.5493132335052, 1346.5152545083763, 1356.4574170331946, 1356.7625665508497, 24602.753972460763, 26090.478190436465, 27548.0, 27889.489945904967, 28047.859760701067, 46941.0, 47254.76372618115, 47297.763511182224, 47414.0, 47450.76274618605, 482146.0, 492257.0, 494847.0, 495351.0, 496506.0, 24347.0, 25352.0, 25465.872670636527, 26283.737162628127, 38142.237155257295, 41183.0, 41839.79080104702, 42626.78686606547, 42634.93412664708, 42812.57187428086, 34678.65321346754, 34764.652353477155, 35032.29935401227, 35434.46848297226, 35824.28351433009, 34877.0, 34930.65069349274, 35715.0, 35837.82081089578, 35908.0, 34561.65438345685, 34620.63380175976, 34713.13217169591, 35019.29961400809, 35502.46746298756, 25590.87204563965, 26347.86826065934, 28109.0, 31792.523112153798, 31874.20314492156, 22549.43626409353, 23120.53758924846, 23729.881350593136, 23853.403664908517, 24327.878360608083], "value": 18766.225875703192, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.3332900785075, 367.43965452684654, 374.63473113714133, 381.81672797057394, 395.7447446397074, 15060.397584096636, 19059.14233859476, 19570.21719131235, 19661.60676786467, 20502.89748551259, 19024.95362755048, 19450.51373715658, 19659.90170049148, 19934.0, 19977.300794472198, 5656.377798442168, 5790.420957904218, 5917.171595976567, 5926.496247818919, 5989.98170311046, 4100.2209580179815, 4341.458782132345, 5750.568707346944, 6275.184226050607, 6578.243501997284, 6602.834929126762, 6617.205935287748, 6620.966895165518, 6622.039804228384, 6626.536142470029, 5633.774649014034, 5971.313298970609, 6003.789867354665, 6066.96861533536, 6073.119397687355, 5026.1204289249235, 5341.305630268041, 5826.184334193216, 5875.911861322041, 5890.381509941437, 13069.86930130696, 13219.537316193982, 16631.91684041584, 24046.398840028964, 24420.63369049466, 1920.4526709887614, 1994.750656167979, 2032.8272096871783, 2106.9262575809926, 2375.916842910507, 9694.36986595869, 10005.0, 10360.689179324632, 11926.46330915111, 15334.926555141144, 1556.2452210677811, 1606.76701878227, 1660.5682522544225, 1755.4996825904557, 1838.2279442634126, 108741.45629271882, 114169.85830141675, 114948.27577586343, 115114.42442787733, 116637.0, 11318.60384886525, 11545.076393888468, 11614.593489227836, 11663.650090497129, 11806.704832379088, 11985.220960637706, 14477.420903163966, 14493.637659058397, 14495.275236238145, 14503.85496145046, 8054.677812887418, 8193.918060819315, 8210.958945205235, 8221.136780638066, 8385.371097167616, 8019.759407217793, 8020.278174964281, 8120.309773669186, 8160.26557609818, 8187.877181842277, 4409.5149533551285, 4541.409616749867, 4570.382998295254, 4590.472095708969, 4612.60792832613, 1525.8022452374832, 1539.6843647052328, 1700.70237708403, 1891.527118220445, 1935.0034732112972, 786.9016372953381, 842.0358689300716, 849.3841964575633, 904.3624244907325, 1001.2840818814583, 19613.50966225827, 20206.696899546518, 20916.686249706265, 22231.0, 22304.10783568639, 929.363386080537, 932.7807965128189, 948.7343543807744, 955.8040601676779, 980.3088822380205, 10544.789104217873, 11006.339619622688, 11012.724681883017, 11062.723431914106, 11067.612633557786, 511124.0, 513485.0, 515120.0, 526679.0, 527632.361838196, 122877.55540778955, 123209.38395307965, 124278.75721242852, 124737.75262247442, 126100.36949815376, 967.8693376394237, 983.2674657380154, 997.0677416615342, 998.3111652959435, 1000.0799264676369, 18302.816971830645, 18334.0, 18464.984425856706, 18500.444986650422, 18546.907265463586, 53272.73363633157, 53513.0, 53634.0, 54810.0, 55185.724071380995, 68641.97037044348, 69211.30788692049, 70661.29338706547, 75691.0, 75789.0, 7865.842683146419, 7968.681252749825, 8068.59657017135, 8141.633626486704, 8221.79445513843, 10229.795404092025, 11878.82181767257, 12229.388530573613, 12275.631731048083, 12625.81061284063, 9662.903370966482, 9889.813222413357, 10028.097471227624, 10365.429901355497, 10587.894121058998, 8542.530160841214, 10022.0, 10341.793164136825, 10414.375137491776, 10632.255742098288, 10397.948010259901, 10676.83984740214, 10898.237123401286, 10947.671569852917, 11115.66653000379, 7741.612919353897, 7982.241687039722, 7983.640736167003, 8212.794680132805, 8255.917440825515, 9812.313138080266, 10553.366797991837, 10601.41692206936, 10739.463026848782, 10842.295250808673], "value": 9217.119555334766, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.444458328127, 1513.4551561437881, 1714.888532245404, 1813.9751040662018, 1843.0784607696153, 7413.777586672409, 7668.156502784703, 7922.286994170524, 8350.707725229619, 8351.498910065393, 7937.880931786028, 8238.134995825441, 8255.0, 8269.0, 8282.213189746964, 2168.29530402619, 2218.9889050554725, 2221.178164079293, 2221.833362497811, 2223.1996481266733, 2017.5359667276534, 2290.541891621674, 2334.9182778602753, 2342.320726989171, 2344.871032093243, 1964.5481539245982, 2284.4060544258527, 2320.037184568402, 2321.454458202321, 2326.336993956723, 2117.4388786971463, 2158.715564239275, 2276.3739971507803, 2289.4848659051745, 2315.0392587076344, 2322.1872344679396, 2326.4183954011496, 2341.0869760793303, 2343.5781559319316, 2347.612643913752, 7791.571463569501, 7932.801679958047, 8186.426950113494, 8273.0, 8354.874676879852, 5045.445001049881, 5157.381114266312, 5214.191800270964, 5221.06020916235, 5293.206019097128, 3155.5108958111527, 3284.5237440571223, 3520.119970007498, 3544.503769472276, 3548.822558872046, 1026.2764750850633, 1046.947652617366, 1067.8771941226703, 1090.4493230918415, 1090.929089609173, 10388.896111038868, 10424.68725938223, 10664.893351066545, 10675.572977080907, 10716.303440276435, 4394.362817391524, 4522.525134860858, 4591.4260717410325, 4696.9060618787435, 4697.953020469751, 7349.338559529668, 9037.367384283038, 9082.727518174466, 9095.135962083592, 9163.71707960886, 5064.341635587423, 5095.388553373619, 5101.336826212569, 5107.770150343244, 5115.820946266937, 4238.915221695549, 4487.820487180542, 4683.95316046842, 4712.575868171881, 4782.258749893772, 3808.485854409619, 3822.866199682998, 3872.6127387261613, 3896.6103389660807, 3961.8415263389143, 1247.2641141726244, 1277.4060062070996, 1289.8387701537308, 1294.3981048812348, 1301.342821874947, 791.7505985614513, 875.1248751248751, 888.9066648001867, 913.5204017890528, 943.1747221181466, 5486.478784515464, 5677.687727174963, 5783.7397317119985, 6045.324988539119, 6049.122877182872, 835.3484282259847, 931.4039015030386, 952.6856137474617, 953.909378609031, 1008.505832142245, 3771.73597848148, 3812.8856134316015, 3828.0812604974603, 3951.604839516083, 3957.0, 8445.95777021111, 9481.762955926157, 9925.553350099262, 9929.652462163935, 9995.250356223167, 9864.260180486492, 9891.060349266809, 9896.55465504054, 9906.05892440217, 9926.2059035277, 6825.795226143224, 7345.15530713964, 7709.113451952985, 7711.113221979432, 7728.922710772933, 5679.0, 5711.286089238845, 5766.452187042224, 5826.73779679907, 5850.824475265749, 8865.379423440298, 9178.724638260863, 9184.357095003554, 9252.306077044113, 9326.020767819553, 9332.860007099764, 9364.765880852761, 9371.156595906128, 9372.968973413055, 9455.905440945779, 6316.14732011191, 6479.125318082187, 6948.33990770876, 7113.146422429341, 7249.238829922782, 7721.112072111779, 7949.602519874099, 7953.6818527260575, 7953.761387158394, 7973.800654983672, 6913.516053876381, 7098.112735908012, 7160.283971602798, 7203.171635261802, 7205.171405288459, 6920.100386949664, 6974.0, 6975.895361569682, 6983.301669832976, 7077.964610176916, 6552.049952756728, 6610.305917878553, 6679.365460281266, 6685.164354455693, 6703.463722902254, 4775.808967641395, 4840.0, 4848.660593758545, 4883.267509873547, 4931.334269873521, 4618.699784513996, 4774.379330686988, 4887.193613053949, 4958.082754690249, 4971.751412429292], "value": 4386.276092113868, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.93787835615336, 300.51316866675984, 309.3040658518334, 309.69649743251597, 311.0962653491606, 4672.789724462399, 4681.9297710534365, 4755.144074066671, 4815.796050987253, 5467.9453205467935, 4875.902481950359, 5183.377994640639, 5294.96748134114, 5315.634542628532, 5376.758045887935, 3250.789463165047, 4096.631303182713, 4098.282800509914, 4114.732542384751, 4115.423840662294, 3456.2396272820065, 4105.240530501852, 4134.421181034642, 4149.83400663973, 4192.706510544263, 3890.688744900401, 4033.919321613566, 4034.5158580970324, 4043.9595604043875, 4058.492688413948, 3412.7269818414716, 4057.6753859691303, 4069.2471892699946, 4079.8572049978106, 4113.382992551112, 2848.5584734366, 3258.1365938026443, 3405.4891766235264, 3481.1645205150835, 3548.970798468467, 4527.4340707411575, 5048.495150484959, 5217.0, 5231.816886408996, 5273.630845840793, 4034.7579145251084, 4053.885181575061, 4123.278426275389, 4296.355546667994, 4569.360289559464, 5061.962297728956, 5210.947890521084, 5534.612577119603, 5620.859478513029, 5710.657360558338, 2978.225661328049, 3520.5951315598777, 3623.9309403725965, 3829.8659546915724, 3841.3853783394525, 5903.321118071433, 5906.940930590682, 5914.940850591482, 5920.259967504062, 6011.939880601181, 3769.453429252743, 3914.8042597869994, 3921.9016976588177, 3923.743847287764, 3942.704297177723, 3446.655334466533, 3803.668715949451, 3869.5550011748446, 3932.941005884914, 3963.4451176835264, 4199.265128602482, 4349.846825821239, 4530.22986092365, 4572.657050721209, 4594.770261486912, 4336.891577710582, 5449.509544141047, 5717.456841600041, 5718.885622287531, 5754.367019627837, 1213.7255881324575, 1226.558438961973, 1259.1437822280755, 1283.9165454245444, 1284.4862055178003, 844.4553263145237, 863.8661007543841, 869.4174902815129, 869.7738587967174, 872.2019352292581, 4486.484054333728, 4848.490908454562, 4956.826511072168, 5086.821961231339, 5111.309973153577, 4494.325851122358, 4833.951660483421, 5340.744924942635, 5528.502434780889, 5624.0, 4725.432948046257, 4738.31294462308, 5069.462830766517, 5098.515641014098, 5115.437301896788, 2438.3541109750845, 2442.3894026493376, 2606.2832721001496, 2687.1535466328046, 2886.422715456917, 4841.95158048422, 5604.803831865946, 5683.318001839723, 5752.223449834302, 5786.623869448473, 4613.884652883705, 5089.720065396365, 5388.568914486832, 5662.405447428043, 5728.79949201776, 4840.709557426495, 5419.214213939035, 5511.834644960658, 5523.613347065667, 5703.870162297128, 4919.950800491949, 5453.481919217668, 5524.254225679482, 5531.280933478783, 5545.445455454584, 4632.3977882875015, 5161.535461808456, 5407.270018547446, 5455.563554915518, 5459.39946605881, 4733.810647574196, 4826.565609095198, 5545.667259964415, 5725.541956643375, 5749.8562535936935, 3216.1155682187587, 3233.951490727594, 3692.8552148833946, 3699.9260014800093, 3734.3278209922473, 5234.0, 5248.976449592293, 5419.590906364295, 5529.972350138384, 5543.695096769717, 5376.736466930346, 5574.749136288958, 5580.665160090408, 5790.812883358985, 5859.794907178229, 4886.804527818847, 5706.629069110494, 5740.942590574208, 5772.39389864075, 5832.883342333214, 5376.950930203521, 5530.529904958124, 5596.944030559643, 5625.430823484802, 5637.718114094361, 4767.523247675204, 4998.375203099613, 5033.949660503495, 5693.231413759089, 5786.971065144816, 5541.556675466034, 5559.666420014651, 5578.693171875586, 5590.608657393944, 5608.270924779916], "value": 3995.6995700905995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.9453686926922, 652.1587152573181, 669.9062131301615, 728.2389902551832, 733.3839574757204, 11801.232919860215, 15026.699466010687, 15027.398904043852, 15041.172735499571, 15045.473408430707, 13536.72926541471, 13558.186508809478, 13682.247476388795, 13683.384247708853, 13690.52083177089, 559.3455656881438, 635.2694401438337, 796.7808852565532, 806.5322113174374, 822.3873214455209, 765.7358211417052, 804.9758507244792, 810.712197170005, 812.0093485947135, 815.0748899998486, 868.2576397180414, 870.1472556894247, 870.3820287595814, 870.4385671242045, 871.424859592669, 852.2117041736369, 852.6376290076693, 866.3675516872655, 867.1718508824058, 867.3451544084239, 630.0203184048802, 635.2789583822395, 641.5669423139409, 643.2217017408941, 644.6196743921062, 13800.102993305503, 14128.364223609962, 14151.080179788281, 14199.503017394341, 14733.263336833115, 13794.793078103836, 13965.231912244823, 14029.78955315671, 14281.14313141215, 14349.210793406359, 11437.885621143765, 11750.178722298058, 11753.588624398191, 11770.2349347293, 11869.52521899123, 3646.5441819772527, 3673.338799016176, 3684.355237833395, 3710.1652128271194, 3817.9045523861846, 108499.0, 110270.0, 114179.42910285479, 114314.42842785816, 114980.0, 10703.357798532114, 10750.784984300426, 10771.946140269249, 10989.560417583207, 11117.444127793578, 21654.0, 21692.34922952314, 21741.239056633258, 21783.891080544498, 21834.126634934742, 7573.734919277799, 7576.924230757732, 7631.54210747357, 7854.921450785419, 7940.404469664798, 3605.837737301828, 4218.2196293685765, 4274.615284624399, 4336.176126535948, 4395.428594282786, 3375.746818988586, 3428.4171690812623, 3484.9477257841154, 3492.4586689063235, 3492.4761285806826, 594.9345571987078, 621.7979156774066, 623.2863371439677, 638.6487431912429, 642.6304874697067, 562.5078056700387, 576.4811669497486, 596.4423264247943, 638.1831255992337, 651.3551583931968, 8844.778880528038, 8931.553422328858, 8942.150495702897, 9304.395214311047, 9473.715788526355, 543.9537639300625, 593.9703014849326, 607.1954660075329, 645.9755721852883, 676.4655921821706, 4015.6748273069816, 4192.601702838226, 4348.065165989327, 4447.310666846644, 4824.300476430898, 101620.0, 105246.0, 106212.34469138179, 108768.45615772028, 110097.0, 93685.53157234307, 93809.0, 94050.5297473522, 95133.5243323779, 96415.55376669356, 84024.57987710145, 84427.0, 84613.57693211618, 85876.0, 86157.99999999875, 8166.714165004197, 9500.857487137837, 9906.504674766089, 9930.602775888883, 9949.900500994898, 40361.0, 49953.75023124861, 50108.7472813168, 52671.0, 54665.72667136639, 69789.0, 77174.0, 77253.61373193098, 78114.0, 79646.60176699079, 10447.791044179226, 11706.239094458833, 12362.75274494523, 12761.298128603015, 12928.952262864228, 10451.341806857212, 13405.66485837862, 14297.928510357799, 14523.273836308354, 14661.0, 13337.333133343489, 13462.932685336511, 13585.52450664222, 13635.522756703467, 13813.022347946087, 11326.943365283121, 13672.316384180553, 13726.176429414267, 14048.157110573398, 14332.928335358256, 10252.487375631337, 13399.866001339862, 13466.93266533661, 13735.862641373458, 13900.960005562178, 11043.72390690207, 11052.668419947415, 11460.770784584429, 11507.827382589101, 11572.59495917639, 8732.070568244188, 10187.490625468548, 11074.0, 11495.310281383145, 11533.365664888512], "value": 7307.644048703807, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1590.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 120.0, 139.0, 169.0, 150.0, 119.0, 116.0, 118.0, 118.0, 114.0], "value": 127.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 118.0, 116.0, 151.0, 119.0, 116.0, 112.0, 115.0, 131.0, 119.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 202.0, 199.0, 279.0, 277.0, 225.0, 196.0, 202.0, 229.0, 287.0], "value": 231.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 59.0, 59.0, 86.0, 58.0, 58.0, 63.0, 57.0, 70.0, 59.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 70.0, 83.0, 72.0, 70.0, 72.0, 81.0, 71.0, 74.0, 82.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 97.0, 125.0, 89.0, 85.0, 90.0, 85.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 95.0, 105.0, 120.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 92.0, 92.0, 98.0, 93.0, 93.0, 81.0, 147.0, 146.0], "value": 103.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 58.0, 57.0, 60.0, 60.0, 81.0, 53.0, 48.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 100.0, 113.0, 92.0, 70.0, 72.0, 73.0, 75.0, 73.0, 72.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 121.0, 102.0, 122.0, 106.0, 76.0, 106.0, 112.0, 110.0], "value": 103.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 167.0, 156.0, 150.0, 150.0, 154.0, 168.0, 149.0, 201.0, 148.0], "value": 159.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 193.0, 186.0, 158.0, 211.0, 196.0, 242.0, 181.0, 176.0, 171.0], "value": 194.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 73.0, 73.0, 73.0, 69.0, 70.0, 71.0, 72.0, 84.0], "value": 73.0, "unit": "score"}]}]} 19:49:57 INFO - Return code: 0 19:49:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:49:57 ERROR - # TBPL SUCCESS # 19:49:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:49:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:49:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:49:57 INFO - 2016-11-29 19:49:57.854 screenresolution[1663:10455] starting screenresolution argv=screenresolution get 19:49:57 INFO - 2016-11-29 19:49:57.857 screenresolution[1663:10455] Display 0: 1600x1200x32@60 19:49:57 INFO - 2016-11-29 19:49:57.862 screenresolution[1664:10458] starting screenresolution argv=screenresolution list 19:49:57 INFO - Available Modes on Display 0 19:49:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:49:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:49:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:49:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:49:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:49:58 INFO - Intel Iris: 19:49:58 INFO - Chipset Model: Intel Iris 19:49:58 INFO - Type: GPU 19:49:58 INFO - Bus: Built-In 19:49:58 INFO - VRAM (Dynamic, Max): 1536 MB 19:49:58 INFO - Vendor: Intel (0x8086) 19:49:58 INFO - Device ID: 0x0a2e 19:49:58 INFO - Revision ID: 0x0009 19:49:58 INFO - Displays: 19:49:58 INFO - Display: 19:49:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:49:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:49:58 INFO - Display Serial Number: 1600x1200 60 19:49:58 INFO - Main Display: Yes 19:49:58 INFO - Mirror: Off 19:49:58 INFO - Online: Yes 19:49:58 INFO - Rotation: Supported 19:49:58 INFO - Return code: 0 19:49:58 INFO - Running post-action listener: _resource_record_post_action 19:49:58 INFO - [mozharness: 2016-11-30 03:49:58.084918Z] Finished run-tests step (success) 19:49:58 INFO - Running post-run listener: _resource_record_post_run 19:49:58 INFO - Total resource usage - Wall time: 1213s; CPU: 55.0%; Read bytes: 38709760; Write bytes: 696806912; Read time: 248; Write time: 2002 19:49:58 INFO - TinderboxPrint: CPU usage
54.6% 19:49:58 INFO - TinderboxPrint: I/O read bytes / time
38,709,760 / 248 19:49:58 INFO - TinderboxPrint: I/O write bytes / time
696,806,912 / 2,002 19:49:58 INFO - TinderboxPrint: CPU idle
2,204.7 (45.4%) 19:49:58 INFO - TinderboxPrint: CPU system
979.5 (20.2%) 19:49:58 INFO - TinderboxPrint: CPU user
1,669.5 (34.4%) 19:49:58 INFO - TinderboxPrint: Swap in / out
422,600,704 / 0 19:49:58 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 315392; Write bytes: 386496000; Read time: 8; Write time: 805 19:49:58 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 35445248; Write bytes: 308967424; Read time: 221; Write time: 1191 19:49:58 INFO - Running post-run listener: _upload_blobber_files 19:49:58 INFO - Blob upload gear active. 19:49:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:49:58 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 19:49:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:49:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:49:58 INFO - (blobuploader) - INFO - Open directory for files ... 19:49:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:49:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:49:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:49:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:49:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:49:59 INFO - (blobuploader) - INFO - Done attempting. 19:49:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:49:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:49:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:50:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:00 INFO - (blobuploader) - INFO - Done attempting. 19:50:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:50:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:50:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:03 INFO - (blobuploader) - INFO - Done attempting. 19:50:03 INFO - (blobuploader) - INFO - Iteration through files over. 19:50:03 INFO - Return code: 0 19:50:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:50:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:50:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de4bfacebc446bf1ef65ab68f248be6a7fb6dee29308684901630d738db11e3c5abce0361e46eaa94602da35f6166d8dc92503c3e2128130a5c1232234e3a5e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/431486083b9d494d6be18ed5ab95b513bd616b27bd104e7f11988423a94d21c6ec9e7ce725ce96e074a9f9e63a58da91d9dc8f5e69d22efaf7f24db2cf2dffdb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aba85dff96176ec62ac2de46ee927ad211c8681908a60f3cfdd20a9bd73d93d0acbb2d0697ddec4335801918f141141bd0610a687448a61f83b1e93ff9256273"} 19:50:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:50:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:50:03 INFO - Contents: 19:50:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de4bfacebc446bf1ef65ab68f248be6a7fb6dee29308684901630d738db11e3c5abce0361e46eaa94602da35f6166d8dc92503c3e2128130a5c1232234e3a5e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/431486083b9d494d6be18ed5ab95b513bd616b27bd104e7f11988423a94d21c6ec9e7ce725ce96e074a9f9e63a58da91d9dc8f5e69d22efaf7f24db2cf2dffdb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aba85dff96176ec62ac2de46ee927ad211c8681908a60f3cfdd20a9bd73d93d0acbb2d0697ddec4335801918f141141bd0610a687448a61f83b1e93ff9256273"} 19:50:03 INFO - Running post-run listener: copy_logs_to_upload_dir 19:50:03 INFO - Copying logs to upload dir... 19:50:03 INFO - mkdir: /builds/slave/test/build/upload/logs 19:50:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.705934 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-29 19:50:03.669596) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 19:50:03.673320) ========= 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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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/Firefox/sha512/de4bfacebc446bf1ef65ab68f248be6a7fb6dee29308684901630d738db11e3c5abce0361e46eaa94602da35f6166d8dc92503c3e2128130a5c1232234e3a5e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/431486083b9d494d6be18ed5ab95b513bd616b27bd104e7f11988423a94d21c6ec9e7ce725ce96e074a9f9e63a58da91d9dc8f5e69d22efaf7f24db2cf2dffdb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aba85dff96176ec62ac2de46ee927ad211c8681908a60f3cfdd20a9bd73d93d0acbb2d0697ddec4335801918f141141bd0610a687448a61f83b1e93ff9256273"} build_url:https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011351 build_url: 'https://queue.taskcluster.net/v1/task/MK5V1lylQauhF34HY8PoFw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de4bfacebc446bf1ef65ab68f248be6a7fb6dee29308684901630d738db11e3c5abce0361e46eaa94602da35f6166d8dc92503c3e2128130a5c1232234e3a5e9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/431486083b9d494d6be18ed5ab95b513bd616b27bd104e7f11988423a94d21c6ec9e7ce725ce96e074a9f9e63a58da91d9dc8f5e69d22efaf7f24db2cf2dffdb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aba85dff96176ec62ac2de46ee927ad211c8681908a60f3cfdd20a9bd73d93d0acbb2d0697ddec4335801918f141141bd0610a687448a61f83b1e93ff9256273"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-29 19:50:03.710662) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 19:50:03.711057) ========= 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.lhM64jt5ZG/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.UrR79ehPZ3/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005375 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-29 19:50:03.760176) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 19:50:03.760493) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-29 19:50:03.760832) ========= ========= Total master_lag: 0.82 =========