builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0388 starttime: 1476360572.33 results: success (0) buildid: 20161013025423 builduid: fbc25fadb5e444d79b4d72705cdbdd92 revision: f03e2740d604d339ed553dad62a3fc54c317f8fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:32.326107) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:32.326499) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:32.326764) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:32.350054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:32.350311) ========= 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.FJiWOwsK17/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.03lDYAEi1Z/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-10-13 05:09:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 149M=0s 2016-10-13 05:09:32 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134479 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:32.506329) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:32.506634) ========= 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.FJiWOwsK17/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.03lDYAEi1Z/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.025953 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:32.554602) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 05:09:32.554935) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f03e2740d604d339ed553dad62a3fc54c317f8fa --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 f03e2740d604d339ed553dad62a3fc54c317f8fa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FJiWOwsK17/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.03lDYAEi1Z/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-10-13 05:09:32,638 truncating revision to first 12 chars 2016-10-13 05:09:32,638 Setting DEBUG logging. 2016-10-13 05:09:32,638 attempt 1/10 2016-10-13 05:09:32,638 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f03e2740d604?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 05:09:34,066 unpacking tar archive at: mozilla-central-f03e2740d604/testing/mozharness/ program finished with exit code 0 elapsedTime=1.705505 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 05:09:34.282266) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:34.282634) ========= script_repo_revision: f03e2740d604d339ed553dad62a3fc54c317f8fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:34.283065) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:34.283335) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 05:09:34.301157) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-13 05:09:34.301443) ========= /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.FJiWOwsK17/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.03lDYAEi1Z/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:09:34 INFO - MultiFileLogger online at 20161013 05:09:34 in /builds/slave/test 05:09:34 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 05:09:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:09:34 INFO - {'append_to_log': False, 05:09:34 INFO - 'base_work_dir': '/builds/slave/test', 05:09:34 INFO - 'blob_upload_branch': 'Firefox', 05:09:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:09:34 INFO - 'branch': 'Firefox', 05:09:34 INFO - 'buildbot_json_path': 'buildprops.json', 05:09:34 INFO - 'config_files': ('talos/mac_config.py',), 05:09:34 INFO - 'default_actions': ('clobber', 05:09:34 INFO - 'read-buildbot-config', 05:09:34 INFO - 'download-and-extract', 05:09:34 INFO - 'populate-webroot', 05:09:34 INFO - 'create-virtualenv', 05:09:34 INFO - 'install', 05:09:34 INFO - 'run-tests'), 05:09:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:09:34 INFO - 'download_minidump_stackwalk': True, 05:09:34 INFO - 'download_symbols': 'ondemand', 05:09:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:09:34 INFO - 'tooltool.py': '/tools/tooltool.py', 05:09:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:09:34 INFO - '/tools/misc-python/virtualenv.py')}, 05:09:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:09:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:09:34 INFO - 'installer_path': 'installer.exe', 05:09:34 INFO - 'log_level': 'info', 05:09:34 INFO - 'log_name': 'talos', 05:09:34 INFO - 'log_to_console': True, 05:09:34 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:09:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:09:34 INFO - 'opt_config_files': (), 05:09:34 INFO - 'pip_index': False, 05:09:34 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:09:34 INFO - 'cmd': ('bash', 05:09:34 INFO - '-c', 05:09:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:09:34 INFO - 'enabled': True, 05:09:34 INFO - 'halt_on_failure': False, 05:09:34 INFO - 'name': 'check_screen_resolution'},), 05:09:34 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:09:34 INFO - 'cmd': ('bash', 05:09:34 INFO - '-c', 05:09:34 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:09:34 INFO - 'enabled': True, 05:09:34 INFO - 'halt_on_failure': False, 05:09:34 INFO - 'name': 'check_screen_resolution'},), 05:09:34 INFO - 'run_cmd_checks_enabled': True, 05:09:34 INFO - 'sps_profile': False, 05:09:34 INFO - 'sps_profile_interval': 0, 05:09:34 INFO - 'suite': 'dromaeojs-e10s', 05:09:34 INFO - 'system_bits': '32', 05:09:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:09:34 INFO - 'title': 't-yosemite-r7-0388', 05:09:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:09:34 INFO - 'use_talos_json': True, 05:09:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:09:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:09:34 INFO - 'work_dir': 'build'} 05:09:34 INFO - [mozharness: 2016-10-13 12:09:34.505408Z] Running clobber step. 05:09:34 INFO - Running pre-action listener: _resource_record_pre_action 05:09:34 INFO - Running main action method: clobber 05:09:34 INFO - rmtree: /builds/slave/test/build 05:09:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:09:36 INFO - Running post-action listener: _resource_record_post_action 05:09:36 INFO - [mozharness: 2016-10-13 12:09:36.579042Z] Finished clobber step (success) 05:09:36 INFO - [mozharness: 2016-10-13 12:09:36.579204Z] Running read-buildbot-config step. 05:09:36 INFO - Running pre-action listener: _resource_record_pre_action 05:09:36 INFO - Running main action method: read_buildbot_config 05:09:36 INFO - Using buildbot properties: 05:09:36 INFO - { 05:09:36 INFO - "project": "", 05:09:36 INFO - "product": "firefox", 05:09:36 INFO - "script_repo_revision": "production", 05:09:36 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:09:36 INFO - "repository": "", 05:09:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:09:36 INFO - "buildid": "20161013025423", 05:09:36 INFO - "pgo_build": "False", 05:09:36 INFO - "basedir": "/builds/slave/test", 05:09:36 INFO - "buildnumber": 6, 05:09:36 INFO - "slavename": "t-yosemite-r7-0388", 05:09:36 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:09:36 INFO - "platform": "yosemite_r7", 05:09:36 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:09:36 INFO - "branch": "mozilla-central", 05:09:36 INFO - "repo_path": "mozilla-central", 05:09:36 INFO - "revision": "f03e2740d604d339ed553dad62a3fc54c317f8fa", 05:09:36 INFO - "stage_platform": "macosx64", 05:09:36 INFO - "builduid": "fbc25fadb5e444d79b4d72705cdbdd92", 05:09:36 INFO - "slavebuilddir": "test" 05:09:36 INFO - } 05:09:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:09:36 INFO - Running post-action listener: _resource_record_post_action 05:09:36 INFO - [mozharness: 2016-10-13 12:09:36.582589Z] Finished read-buildbot-config step (success) 05:09:36 INFO - [mozharness: 2016-10-13 12:09:36.582740Z] Running download-and-extract step. 05:09:36 INFO - Running pre-action listener: _resource_record_pre_action 05:09:36 INFO - Running main action method: download_and_extract 05:09:36 INFO - mkdir: /builds/slave/test/build/tests 05:09:36 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')]} 05:09:36 INFO - trying https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:09:36 INFO - Downloading https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:09:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:09:37 INFO - Downloaded 1199 bytes. 05:09:37 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:09:37 INFO - Using the following test package requirements: 05:09:37 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:09:37 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:09:37 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:09:37 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:09:37 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:09:37 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:09:37 INFO - u'jsshell-mac.zip'], 05:09:37 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:09:37 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:09:37 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:09:37 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:09:37 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:09:37 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:09:37 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:09:37 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:09:37 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:09:37 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:09:37 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:09:37 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:09:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:09:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:09:37 INFO - Fetch https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:09:38 INFO - Http code: 200 05:09:38 INFO - via: 1.1 d897b81191cbfd68a1b6fc44f98c52b0.cloudfront.net (CloudFront) 05:09:38 INFO - x-amz-cf-id: jQmM9cepLnv8pjKI7KDbjsK9BEBMA1f_6iow-3QFy2FHOsT09ULwGA== 05:09:38 INFO - x-amz-version-id: tymelWDGN0OJOmuooWtJYMR9VLDu8UiJ 05:09:38 INFO - Expected file size: 16520340 05:09:38 INFO - Obtained file size: 16520340 05:09:45 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:09:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:09:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:09:45 INFO - Fetch https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:09:45 INFO - Http code: 200 05:09:45 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 05:09:45 INFO - x-amz-cf-id: 0YwuUhPpeQk_GSNpKAfulc7cYTS8_t0TKuzZpfXnvvFYYGaSNdtcRA== 05:09:45 INFO - x-amz-version-id: tymelWDGN0OJOmuooWtJYMR9VLDu8UiJ 05:09:46 INFO - Expected file size: 16520340 05:09:46 INFO - Obtained file size: 16520340 05:09:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:09:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:09:52 INFO - Fetch https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:09:53 INFO - Http code: 200 05:09:53 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 05:09:53 INFO - x-amz-cf-id: szNUyyahJCNqE-insF6lN3yCdwSeyK4QPOR7krwEGy2Wv6OCISHobA== 05:09:53 INFO - x-amz-version-id: O9kFkc_zRb05ScDXv5H6pCXl6F9kSGMH 05:09:53 INFO - Expected file size: 14409006 05:09:53 INFO - Obtained file size: 14409006 05:09:54 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')]} 05:09:54 INFO - trying https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:09:54 INFO - Downloading https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:09:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:09:57 INFO - Downloaded 111676422 bytes. 05:09:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:09:57 INFO - mkdir: /builds/slave/test/properties 05:09:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:57 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:57 INFO - Contents: 05:09:57 INFO - build_url:https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:09:57 INFO - Running post-action listener: _resource_record_post_action 05:09:57 INFO - Running post-action listener: set_extra_try_arguments 05:09:57 INFO - [mozharness: 2016-10-13 12:09:57.787646Z] Finished download-and-extract step (success) 05:09:57 INFO - [mozharness: 2016-10-13 12:09:57.787772Z] Running populate-webroot step. 05:09:57 INFO - Running pre-action listener: _resource_record_pre_action 05:09:57 INFO - Running main action method: populate_webroot 05:09:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:57 INFO - '--tppagecycles', 05:09:57 INFO - '7'], 05:09:57 INFO - 'tests': ['tsvgm']}}, 05:09:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:57 INFO - 'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:57 INFO - 'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['damp', 'tps']}, 05:09:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:57 INFO - 'tests': ['damp', 'tps']}, 05:09:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['dromaeo_dom']}, 05:09:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['basic_compositor_video']}, 05:09:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:09:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['a11yr', 05:09:57 INFO - 'ts_paint', 05:09:57 INFO - 'tpaint', 05:09:57 INFO - 'sessionrestore', 05:09:57 INFO - 'sessionrestore_no_auto_restore', 05:09:57 INFO - 'tabpaint']}, 05:09:57 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:57 INFO - 'ts_paint', 05:09:57 INFO - 'tpaint', 05:09:57 INFO - 'sessionrestore', 05:09:57 INFO - 'sessionrestore_no_auto_restore', 05:09:57 INFO - 'tabpaint']}, 05:09:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:57 INFO - 'ts_paint', 05:09:57 INFO - 'tpaint', 05:09:57 INFO - 'sessionrestore', 05:09:57 INFO - 'sessionrestore_no_auto_restore', 05:09:57 INFO - 'tabpaint']}, 05:09:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:57 INFO - 'ts_paint', 05:09:57 INFO - 'tpaint', 05:09:57 INFO - 'sessionrestore', 05:09:57 INFO - 'sessionrestore_no_auto_restore', 05:09:57 INFO - 'tabpaint']}, 05:09:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['a11yr', 05:09:57 INFO - 'ts_paint', 05:09:57 INFO - 'tpaint', 05:09:57 INFO - 'sessionrestore', 05:09:57 INFO - 'sessionrestore_no_auto_restore', 05:09:57 INFO - 'tabpaint']}, 05:09:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['a11yr', 05:09:57 INFO - 'ts_paint', 05:09:57 INFO - 'tpaint', 05:09:57 INFO - 'sessionrestore', 05:09:57 INFO - 'sessionrestore_no_auto_restore', 05:09:57 INFO - 'tabpaint']}, 05:09:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['tsvgx', 05:09:57 INFO - 'tsvgr_opacity', 05:09:57 INFO - 'tart', 05:09:57 INFO - 'tscrollx', 05:09:57 INFO - 'cart']}, 05:09:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:57 INFO - 'tsvgr_opacity', 05:09:57 INFO - 'tart', 05:09:57 INFO - 'tscrollx', 05:09:57 INFO - 'cart']}, 05:09:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:57 INFO - 'talos_options': ['--disable-e10s'], 05:09:57 INFO - 'tests': ['tp5o']}, 05:09:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:57 INFO - 'tests': ['tp5o']}, 05:09:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:57 INFO - 'talos_options': ['--disable-e10s', 05:09:57 INFO - '--xperf_path', 05:09:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:57 INFO - 'tests': ['tp5n']}, 05:09:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:57 INFO - 'talos_options': ['--xperf_path', 05:09:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:57 INFO - 'tests': ['tp5n']}}, 05:09:57 INFO - 'talos.zip': {'path': '', 05:09:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:57 INFO - Running post-action listener: _resource_record_post_action 05:09:57 INFO - [mozharness: 2016-10-13 12:09:57.807040Z] Finished populate-webroot step (success) 05:09:57 INFO - [mozharness: 2016-10-13 12:09:57.807171Z] Running create-virtualenv step. 05:09:57 INFO - Running pre-action listener: _resource_record_pre_action 05:09:57 INFO - Running main action method: create_virtualenv 05:09:57 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:57 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 05:09:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:57 INFO - Using real prefix '/tools/python27' 05:09:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:58 INFO - Installing distribute.............................................................................................................................................................................................done. 05:10:01 INFO - Installing pip.................done. 05:10:01 INFO - Return code: 0 05:10:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:10:01 INFO - Reading from file tmpfile_stdout 05:10:01 INFO - Output received: 05:10:01 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) 05:10:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:01 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:01 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 05:10:01 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 05:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:01 INFO - 'HOME': '/Users/cltbld', 05:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:01 INFO - 'LOGNAME': 'cltbld', 05:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:01 INFO - 'MOZ_NO_REMOTE': '1', 05:10:01 INFO - 'NO_EM_RESTART': '1', 05:10:01 INFO - 'PAGER': '/bin/cat', 05:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:01 INFO - 'PWD': '/builds/slave/test', 05:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:01 INFO - 'SHELL': '/bin/bash', 05:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:01 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:01 INFO - 'USER': 'cltbld', 05:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:01 INFO - 'XPC_FLAGS': '0x0', 05:10:01 INFO - 'XPC_SERVICE_NAME': '0', 05:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:02 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 05:10:02 INFO - Cleaning up... 05:10:02 INFO - Return code: 0 05:10:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:10:02 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')]} 05:10:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:02 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')]} 05:10:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:02 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:02 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 05:10:02 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 05:10:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:02 INFO - 'HOME': '/Users/cltbld', 05:10:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:02 INFO - 'LOGNAME': 'cltbld', 05:10:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:02 INFO - 'MOZ_NO_REMOTE': '1', 05:10:02 INFO - 'NO_EM_RESTART': '1', 05:10:02 INFO - 'PAGER': '/bin/cat', 05:10:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:02 INFO - 'PWD': '/builds/slave/test', 05:10:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:02 INFO - 'SHELL': '/bin/bash', 05:10:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:02 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:02 INFO - 'USER': 'cltbld', 05:10:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:02 INFO - 'XPC_FLAGS': '0x0', 05:10:02 INFO - 'XPC_SERVICE_NAME': '0', 05:10:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:02 INFO - Downloading/unpacking psutil>=3.1.1 05:10:02 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 05:10:02 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 05:10:02 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 05:10:02 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 05:10:02 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 05:10:02 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 05:10:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:10:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:05 INFO - Installing collected packages: psutil 05:10:05 INFO - Running setup.py install for psutil 05:10:05 INFO - building 'psutil._psutil_osx' extension 05:10:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:10:06 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 05:10:06 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 05:10:06 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 05:10:06 INFO - building 'psutil._psutil_posix' extension 05:10:06 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 05:10:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:10:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:10:06 INFO - ^ 05:10:06 INFO - 1 warning generated. 05:10:06 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 05:10:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:10:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:10:06 INFO - Successfully installed psutil 05:10:06 INFO - Cleaning up... 05:10:06 INFO - Return code: 0 05:10:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:10:06 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')]} 05:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:06 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')]} 05:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:06 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:06 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 05:10:06 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 05:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:06 INFO - 'HOME': '/Users/cltbld', 05:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:06 INFO - 'LOGNAME': 'cltbld', 05:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:06 INFO - 'MOZ_NO_REMOTE': '1', 05:10:06 INFO - 'NO_EM_RESTART': '1', 05:10:06 INFO - 'PAGER': '/bin/cat', 05:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:06 INFO - 'PWD': '/builds/slave/test', 05:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:06 INFO - 'SHELL': '/bin/bash', 05:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:06 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:06 INFO - 'USER': 'cltbld', 05:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:06 INFO - 'XPC_FLAGS': '0x0', 05:10:06 INFO - 'XPC_SERVICE_NAME': '0', 05:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:10:07 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 05:10:07 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 05:10:07 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 05:10:07 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 05:10:07 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 05:10:07 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 05:10:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:10:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:10:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:10:09 INFO - Installing collected packages: mozsystemmonitor 05:10:09 INFO - Running setup.py install for mozsystemmonitor 05:10:09 INFO - Successfully installed mozsystemmonitor 05:10:09 INFO - Cleaning up... 05:10:09 INFO - Return code: 0 05:10:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:10:09 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')]} 05:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:09 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')]} 05:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:09 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:09 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 05:10:09 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 05:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:09 INFO - 'HOME': '/Users/cltbld', 05:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:09 INFO - 'LOGNAME': 'cltbld', 05:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:09 INFO - 'MOZ_NO_REMOTE': '1', 05:10:09 INFO - 'NO_EM_RESTART': '1', 05:10:09 INFO - 'PAGER': '/bin/cat', 05:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:09 INFO - 'PWD': '/builds/slave/test', 05:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:09 INFO - 'SHELL': '/bin/bash', 05:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:09 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:09 INFO - 'USER': 'cltbld', 05:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:09 INFO - 'XPC_FLAGS': '0x0', 05:10:09 INFO - 'XPC_SERVICE_NAME': '0', 05:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:10 INFO - Downloading/unpacking jsonschema==2.5.1 05:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:10:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:10:12 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 05:10:12 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 05:10:12 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 05:10:12 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 05:10:12 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 05:10:12 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 05:10:13 INFO - Downloading functools32-3.2.3-2.tar.gz 05:10:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:10:13 INFO - warning: no files found matching '*.txt' 05:10:13 INFO - no previously-included directories found matching 'build' 05:10:13 INFO - no previously-included directories found matching 'dist' 05:10:13 INFO - no previously-included directories found matching '.git*' 05:10:13 INFO - Installing collected packages: jsonschema, functools32 05:10:13 INFO - Running setup.py install for functools32 05:10:13 INFO - warning: no files found matching '*.txt' 05:10:13 INFO - no previously-included directories found matching 'build' 05:10:13 INFO - no previously-included directories found matching 'dist' 05:10:13 INFO - no previously-included directories found matching '.git*' 05:10:13 INFO - Successfully installed jsonschema functools32 05:10:13 INFO - Cleaning up... 05:10:13 INFO - Return code: 0 05:10:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:10:13 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')]} 05:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:13 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')]} 05:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:13 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:13 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 05:10:13 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 05:10:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:13 INFO - 'HOME': '/Users/cltbld', 05:10:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:13 INFO - 'LOGNAME': 'cltbld', 05:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:13 INFO - 'MOZ_NO_REMOTE': '1', 05:10:13 INFO - 'NO_EM_RESTART': '1', 05:10:13 INFO - 'PAGER': '/bin/cat', 05:10:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:13 INFO - 'PWD': '/builds/slave/test', 05:10:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:13 INFO - 'SHELL': '/bin/bash', 05:10:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:13 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:13 INFO - 'USER': 'cltbld', 05:10:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:13 INFO - 'XPC_FLAGS': '0x0', 05:10:13 INFO - 'XPC_SERVICE_NAME': '0', 05:10:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:10:13 INFO - Cleaning up... 05:10:13 INFO - Return code: 0 05:10:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:10:13 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')]} 05:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:13 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')]} 05:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:13 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:13 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 05:10:13 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 05:10:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:13 INFO - 'HOME': '/Users/cltbld', 05:10:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:13 INFO - 'LOGNAME': 'cltbld', 05:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:13 INFO - 'MOZ_NO_REMOTE': '1', 05:10:13 INFO - 'NO_EM_RESTART': '1', 05:10:13 INFO - 'PAGER': '/bin/cat', 05:10:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:13 INFO - 'PWD': '/builds/slave/test', 05:10:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:13 INFO - 'SHELL': '/bin/bash', 05:10:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:13 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:13 INFO - 'USER': 'cltbld', 05:10:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:13 INFO - 'XPC_FLAGS': '0x0', 05:10:13 INFO - 'XPC_SERVICE_NAME': '0', 05:10:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:13 INFO - Downloading/unpacking blobuploader==1.2.4 05:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:16 INFO - Downloading blobuploader-1.2.4.tar.gz 05:10:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:10:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:10:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:10:17 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 05:10:17 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 05:10:17 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 05:10:17 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 05:10:17 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 05:10:17 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 05:10:17 INFO - Downloading docopt-0.6.1.tar.gz 05:10:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:10:17 INFO - Installing collected packages: blobuploader, requests, docopt 05:10:17 INFO - Running setup.py install for blobuploader 05:10:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:10:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:10:17 INFO - Running setup.py install for requests 05:10:18 INFO - Running setup.py install for docopt 05:10:18 INFO - Successfully installed blobuploader requests docopt 05:10:18 INFO - Cleaning up... 05:10:18 INFO - Return code: 0 05:10:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:18 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:18 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 05:10:18 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 05:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:18 INFO - 'HOME': '/Users/cltbld', 05:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:18 INFO - 'LOGNAME': 'cltbld', 05:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:18 INFO - 'MOZ_NO_REMOTE': '1', 05:10:18 INFO - 'NO_EM_RESTART': '1', 05:10:18 INFO - 'PAGER': '/bin/cat', 05:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:18 INFO - 'PWD': '/builds/slave/test', 05:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:18 INFO - 'SHELL': '/bin/bash', 05:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:18 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:18 INFO - 'USER': 'cltbld', 05:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:18 INFO - 'XPC_FLAGS': '0x0', 05:10:18 INFO - 'XPC_SERVICE_NAME': '0', 05:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:18 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-vmEWjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-dVnNkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-RHqYG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-FGwMhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-hUErmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-inkM2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-3_gy_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-NQOybk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-AbbIv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-LucdRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:19 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-p54brr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:20 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-hwbYx6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:20 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-YjsciV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:20 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-zvFN8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:20 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Uos1D_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:20 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-mh_QTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:20 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-U7sOOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:10:20 INFO - Running setup.py install for manifestparser 05:10:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:10:20 INFO - Running setup.py install for mozcrash 05:10:21 INFO - Running setup.py install for mozdebug 05:10:21 INFO - Running setup.py install for mozdevice 05:10:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:10:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:10:21 INFO - Running setup.py install for mozfile 05:10:21 INFO - Running setup.py install for mozhttpd 05:10:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:10:21 INFO - Running setup.py install for mozinfo 05:10:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:10:21 INFO - Running setup.py install for mozInstall 05:10:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:10:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:10:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:10:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:10:21 INFO - Running setup.py install for mozleak 05:10:22 INFO - Running setup.py install for mozlog 05:10:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:10:22 INFO - Running setup.py install for moznetwork 05:10:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:10:22 INFO - Running setup.py install for mozprocess 05:10:22 INFO - Running setup.py install for mozprofile 05:10:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:10:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:10:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:10:22 INFO - Running setup.py install for mozrunner 05:10:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:10:22 INFO - Running setup.py install for mozscreenshot 05:10:23 INFO - Running setup.py install for moztest 05:10:23 INFO - Running setup.py install for mozversion 05:10:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:10:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:10:23 INFO - Cleaning up... 05:10:23 INFO - Return code: 0 05:10:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10: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')]} 05:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10: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')]} 05:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:23 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:23 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 05:10:23 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 05:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:23 INFO - 'HOME': '/Users/cltbld', 05:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:23 INFO - 'LOGNAME': 'cltbld', 05:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:23 INFO - 'MOZ_NO_REMOTE': '1', 05:10:23 INFO - 'NO_EM_RESTART': '1', 05:10:23 INFO - 'PAGER': '/bin/cat', 05:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:23 INFO - 'PWD': '/builds/slave/test', 05:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:23 INFO - 'SHELL': '/bin/bash', 05:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:23 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:23 INFO - 'USER': 'cltbld', 05:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:23 INFO - 'XPC_FLAGS': '0x0', 05:10:23 INFO - 'XPC_SERVICE_NAME': '0', 05:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:10:23 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-3RdzR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:10:23 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)) 05:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:10:23 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-E_gA7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:10:23 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-GFVjuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:10:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:10:23 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-Yz2FXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:10:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ZIXyip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:10:24 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)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-GfEGr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:10:24 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)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-yzkOeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:10:24 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)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-JBvTgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:10:24 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)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-whS4QV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:10:24 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)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-6k_X_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:10:24 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)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-HrsCFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:10:24 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)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-yn8c8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:10:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:10:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:10:24 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-BXcLs7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:10:25 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)) 05:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:10:25 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-3f_bi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:10:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:25 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-JTseOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:10:25 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)) 05:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:10:25 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-L5Rdbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:10:25 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)) 05:10:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:10:25 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-9HZ02X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:10:25 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)) 05:10:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:10:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:10:25 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)) 05:10:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:10:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:10:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:10:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:10:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:10:27 INFO - Downloading blessings-1.6.tar.gz 05:10:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:10:28 INFO - Installing collected packages: blessings 05:10:28 INFO - Running setup.py install for blessings 05:10:28 INFO - Successfully installed blessings 05:10:28 INFO - Cleaning up... 05:10:28 INFO - Return code: 0 05:10:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:10:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:28 INFO - Reading from file tmpfile_stdout 05:10:28 INFO - Current package versions: 05:10:28 INFO - blessings == 1.6 05:10:28 INFO - blobuploader == 1.2.4 05:10:28 INFO - docopt == 0.6.1 05:10:28 INFO - functools32 == 3.2.3-2 05:10:28 INFO - jsonschema == 2.5.1 05:10:28 INFO - manifestparser == 1.1 05:10:28 INFO - mozInstall == 1.12 05:10:28 INFO - mozcrash == 0.17 05:10:28 INFO - mozdebug == 0.1 05:10:28 INFO - mozdevice == 0.48 05:10:28 INFO - mozfile == 1.2 05:10:28 INFO - mozhttpd == 0.7 05:10:28 INFO - mozinfo == 0.9 05:10:28 INFO - mozleak == 0.1 05:10:28 INFO - mozlog == 3.4 05:10:28 INFO - moznetwork == 0.27 05:10:28 INFO - mozprocess == 0.23 05:10:28 INFO - mozprofile == 0.28 05:10:28 INFO - mozrunner == 6.12 05:10:28 INFO - mozscreenshot == 0.1 05:10:28 INFO - mozsystemmonitor == 0.3 05:10:28 INFO - moztest == 0.7 05:10:28 INFO - mozversion == 1.4 05:10:28 INFO - psutil == 3.1.1 05:10:28 INFO - requests == 1.2.3 05:10:28 INFO - wsgiref == 0.1.2 05:10:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:29 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:29 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 05:10:29 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 05:10:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:29 INFO - 'HOME': '/Users/cltbld', 05:10:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:29 INFO - 'LOGNAME': 'cltbld', 05:10:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:29 INFO - 'MOZ_NO_REMOTE': '1', 05:10:29 INFO - 'NO_EM_RESTART': '1', 05:10:29 INFO - 'PAGER': '/bin/cat', 05:10:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:29 INFO - 'PWD': '/builds/slave/test', 05:10:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:29 INFO - 'SHELL': '/bin/bash', 05:10:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:29 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:29 INFO - 'USER': 'cltbld', 05:10:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:29 INFO - 'XPC_FLAGS': '0x0', 05:10:29 INFO - 'XPC_SERVICE_NAME': '0', 05:10:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 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)) 05:10:29 INFO - Cleaning up... 05:10:29 INFO - Return code: 0 05:10:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:10:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:10:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:10:30 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 0x10c349490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2bf570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9fcbc97200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, '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.FJiWOwsK17/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.03lDYAEi1Z/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 05:10:30 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 05:10:30 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 05:10:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:10:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:10:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:10:30 INFO - 'HOME': '/Users/cltbld', 05:10:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:10:30 INFO - 'LOGNAME': 'cltbld', 05:10:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:30 INFO - 'MOZ_NO_REMOTE': '1', 05:10:30 INFO - 'NO_EM_RESTART': '1', 05:10:30 INFO - 'PAGER': '/bin/cat', 05:10:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:10:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:30 INFO - 'PWD': '/builds/slave/test', 05:10:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:10:30 INFO - 'SHELL': '/bin/bash', 05:10:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:10:30 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:10:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:10:30 INFO - 'USER': 'cltbld', 05:10:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:10:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:10:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:30 INFO - 'XPC_FLAGS': '0x0', 05:10:30 INFO - 'XPC_SERVICE_NAME': '0', 05:10:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:10:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:10:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:10:30 INFO - Cleaning up... 05:10:30 INFO - Return code: 0 05:10:30 INFO - Running post-action listener: _resource_record_post_action 05:10:30 INFO - Running post-action listener: _start_resource_monitoring 05:10:30 INFO - Starting resource monitoring. 05:10:30 INFO - [mozharness: 2016-10-13 12:10:30.364905Z] Finished create-virtualenv step (success) 05:10:30 INFO - [mozharness: 2016-10-13 12:10:30.365930Z] Running install step. 05:10:30 INFO - Running pre-action listener: _resource_record_pre_action 05:10:30 INFO - Running main action method: install 05:10:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:10:30 INFO - Reading from file tmpfile_stdout 05:10:30 INFO - Detecting whether we're running mozinstall >=1.0... 05:10:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:10:30 INFO - Reading from file tmpfile_stdout 05:10:30 INFO - Output received: 05:10:30 INFO - Usage: mozinstall [options] installer 05:10:30 INFO - Options: 05:10:30 INFO - -h, --help show this help message and exit 05:10:30 INFO - -d DEST, --destination=DEST 05:10:30 INFO - Directory to install application into. [default: 05:10:30 INFO - "/builds/slave/test"] 05:10:30 INFO - --app=APP Application being installed. [default: firefox] 05:10:30 INFO - mkdir: /builds/slave/test/build/application 05:10:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:10:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:11:04 INFO - Reading from file tmpfile_stdout 05:11:04 INFO - Output received: 05:11:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:11:04 INFO - Running post-action listener: _resource_record_post_action 05:11:04 INFO - [mozharness: 2016-10-13 12:11:04.993411Z] Finished install step (success) 05:11:04 INFO - [mozharness: 2016-10-13 12:11:04.993546Z] Running run-tests step. 05:11:04 INFO - Running pre-action listener: _resource_record_pre_action 05:11:04 INFO - Running main action method: run_tests 05:11:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:11:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:11:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:11:05 INFO - 2016-10-13 05:11:05.007 screenresolution[1644:7481] starting screenresolution argv=screenresolution get 05:11:05 INFO - 2016-10-13 05:11:05.010 screenresolution[1644:7481] Display 0: 1600x1200x32@60 05:11:05 INFO - 2016-10-13 05:11:05.016 screenresolution[1645:7483] starting screenresolution argv=screenresolution list 05:11:05 INFO - Available Modes on Display 0 05:11:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:11:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:11:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:11:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:11:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:11:05 INFO - Intel Iris: 05:11:05 INFO - Chipset Model: Intel Iris 05:11:05 INFO - Type: GPU 05:11:05 INFO - Bus: Built-In 05:11:05 INFO - VRAM (Dynamic, Max): 1536 MB 05:11:05 INFO - Vendor: Intel (0x8086) 05:11:05 INFO - Device ID: 0x0a2e 05:11:05 INFO - Revision ID: 0x0009 05:11:05 INFO - Displays: 05:11:05 INFO - Display: 05:11:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:11:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:11:05 INFO - Display Serial Number: 1600x1200 60 05:11:05 INFO - Main Display: Yes 05:11:05 INFO - Mirror: Off 05:11:05 INFO - Online: Yes 05:11:05 INFO - Rotation: Supported 05:11:05 INFO - Return code: 0 05:11:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:11:05 INFO - Python 2.7.3 05:11:05 INFO - Return code: 0 05:11:05 INFO - grabbing minidump binary from tooltool 05:11:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:05 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 0x10c15dd30>, '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 0x10bf83db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c155b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:11:05 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 05:11:05 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 05:11:05 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 05:11:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:11:05 INFO - Return code: 0 05:11:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:11:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:11:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:11:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:11:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:11:05 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/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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 05:11:05 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/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0388 --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 05:11:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FJiWOwsK17/Render', 05:11:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:05 INFO - 'HOME': '/Users/cltbld', 05:11:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:05 INFO - 'LOGNAME': 'cltbld', 05:11:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:11:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:05 INFO - 'MOZ_NO_REMOTE': '1', 05:11:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:11:05 INFO - 'NO_EM_RESTART': '1', 05:11:05 INFO - 'PAGER': '/bin/cat', 05:11:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:05 INFO - 'PWD': '/builds/slave/test', 05:11:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:11:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:05 INFO - 'SHELL': '/bin/bash', 05:11:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.03lDYAEi1Z/Listeners', 05:11:05 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 05:11:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:05 INFO - 'USER': 'cltbld', 05:11:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:05 INFO - 'XPC_FLAGS': '0x0', 05:11:05 INFO - 'XPC_SERVICE_NAME': '0', 05:11:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:05 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/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--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 05:11:05 INFO - mozversion application_buildid: 20161013025423 05:11:05 INFO - mozversion application_changeset: f03e2740d604d339ed553dad62a3fc54c317f8fa 05:11:05 INFO - mozversion application_display_name: Nightly 05:11:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:11:05 INFO - mozversion application_name: Firefox 05:11:05 INFO - mozversion application_remotingname: firefox 05:11:05 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:11:05 INFO - mozversion application_vendor: Mozilla 05:11:05 INFO - mozversion application_version: 52.0a1 05:11:05 INFO - mozversion platform_buildid: 20161013025423 05:11:05 INFO - mozversion platform_changeset: f03e2740d604d339ed553dad62a3fc54c317f8fa 05:11:05 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:11:05 INFO - mozversion platform_version: 52.0a1 05:11:05 INFO - using testdate: 1476360665 05:11:05 INFO - actual date: 1476360665 05:11:05 INFO - starting webserver on 'localhost:49269' 05:11:05 INFO - SUITE-START | Running 2 tests 05:11:05 INFO - TEST-START | dromaeo_css 05:11:05 INFO - operating with platform_type : mac_ 05:11:05 INFO - Initialising browser for dromaeo_css test... 05:11:05 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpALAEfG/profile http://localhost:49269/getInfo.html) 05:11:08 INFO - PROCESS | 1655 | 2016-10-13 05:11:08.440 plugin-container[1657:7662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 05:11:08 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:08 INFO - PROCESS | 1655 | 2016-10-13 05:11:08.442 plugin-container[1657:7662] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 05:11:08 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:08 INFO - PROCESS | 1655 | 2016-10-13 05:11:08.442 plugin-container[1657:7662] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:11:08 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 05:11:08 INFO - PROCESS | 1655 | colorDepth:24 05:11:08 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 05:11:08 INFO - PROCESS | 1655 | __metrics 05:11:08 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 05:11:09 INFO - TEST-INFO | 1655: exit 0 05:11:09 INFO - Browser initialized. 05:11:09 INFO - Running cycle 1/1 for dromaeo_css test... 05:11:09 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpALAEfG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:11:11 INFO - PROCESS | 1658 | 2016-10-13 05:11:11.761 plugin-container[1659:7781] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 05:11:11 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:11 INFO - PROCESS | 1658 | 2016-10-13 05:11:11.761 plugin-container[1659:7781] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 05:11:11 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:11:11 INFO - PROCESS | 1658 | 2016-10-13 05:11:11.761 plugin-container[1659:7781] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:11:21 INFO - PROCESS | 1658 | RSS: Main: 239898624 05:11:21 INFO - PROCESS | 1658 | 05:12:10 INFO - PROCESS | 1658 | 1476360730933 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] 05:14:23 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49269/tests/dromaeo/cssquery-ext.html) 05:14:25 INFO - PROCESS | 1658 | RSS: Main: 256860160 05:14:25 INFO - PROCESS | 1658 | 05:17:27 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-ext.html (next: http://localhost:49269/tests/dromaeo/cssquery-jquery.html) 05:17:27 INFO - PROCESS | 1658 | RSS: Main: 266387456 05:17:27 INFO - PROCESS | 1658 | 05:20:29 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49269/tests/dromaeo/cssquery-mootools.html) 05:20:30 INFO - PROCESS | 1658 | RSS: Main: 269946880 05:20:30 INFO - PROCESS | 1658 | 05:23:32 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49269/tests/dromaeo/cssquery-prototype.html) 05:23:33 INFO - PROCESS | 1658 | RSS: Main: 269373440 05:23:33 INFO - PROCESS | 1658 | 05:26:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49269/tests/dromaeo/cssquery-yui.html) 05:26:36 INFO - PROCESS | 1658 | RSS: Main: 274538496 05:26:36 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49269/tests/dromaeo/cssquery-yui.html (next: http://localhost:49269/tests/dromaeo/cssquery-dojo.html) 05:29:39 INFO - PROCESS | 1658 | RSS: Main: 277377024 05:29:39 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | __start_tp_report 05:29:39 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 05:29:39 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 05:29:39 INFO - PROCESS | 1658 | |i|pagename|runs| 05:29:39 INFO - PROCESS | 1658 | |0;dojo.html;3217.3726123405936;3364.7308215342773;3402.5576675032244;3427.1603457153;3429.8971030869084;5607.57943154263;5682.914756278659;5713.371529131792;5751.827445176651;5788.739506722197;5525.364583072937;5669.433056694328;5698.572607054467;5716.857078573027;5746.454086861752;5099.413567439755;5164.664296820713;5165.3026841376495;5191.610629202806;5234.319538459995;5179.741012949356;5265.420803711589;5303.946960530403;5353.544948679367;5383.838484845461;5078.542931136216;5205.141151709963;5231.686098834082;5239.764210610531;5284.867878303054;5063.8480845574695;5183.792648294064;5214.97392513035;5232.2674825524455;5251.133562962144;5098.847034588968;5135.922961155548;5146.047981123466;5150.716710580917;5213.895722085537;5430;5551.77792888284;5612.522935550483;5676.631018983753;5710.143478478261;5377.247185394048;6096.963516202212;6126.670355029888;6225.128482012522;6269.0909818076125;6222.471089957314;6228.5951413158455;6237.313895471494;6248.28144763349;6280.591761535486;4587.8623641290815;5769.711514424262;5789.9710501447225;5805.6806875621405;5812.393722854108;6170.074488826712;6212.720427580679;6234.688265586793;6236.282827474898;6255.74977000924;6094.257048438488;6100.853682747703;6103.596172880262;6112.694365281807;6114.327423983358;6083.372880085992;6202.224721909762;6213.409726076015;6236.906446403307;6259.374062593704;5914.378990205966;6063.878722425527;6113.38866113394;6136.7852125175405;6140.473573783631;4567.725936443824;6099.176611157525;6111.694415279218;6163.96918015416;6201.069839524108;4886.3403440535785;4893.363862697826;4921.753912304371;4929.359183306147;4977.925331120036;3641.308151451216;4328.696991210648;4333.090051089306;4366.12677464502;4403.779811009436;5690.2033715279895;5806.1581070744505;5810.564207684441;5850.473457388855;5854.502367298742;6177.135201071854;6208.099825525273;6217.316095229521;6230.158928544589;6245.875082498415;5629.971850140723;5715.942550628147;5719.342275638355;5819.854503637358;5827.155062515912;4729.077829823152;5422.972885135549;5436.184572314184;5446.482584154499;5446.754896029688;6329.240491141092;6389.48884089281;6394.296627370893;6409.4231519163495;6411.519136064907;6117.3576774439825;6142.836439233709;6149.139120523219;6224.408681175281;6251;4742.336072949721;6224.315325314121;6318.810435686937;6334.239891213084;6348.619082855043;6139.723712432862;6151.784687535915;6156.5998210116195;6176.1970943778815;6178.196834411498;5030.8239211627415;6091.969540152271;6143.972489078683;6180.042093475518;6218.160548325918;5217.0870097733505;5439.537639300704;6147.569670123048;6152.107944347955;6153.3538978408415;5016.172331565397;6131.938680613315;6133.662648554194;6150.261968563801;6156.78451254204;5594.804181853616;5720.141978703228;5722.456366645162;5895.292564892069;5934.376890426615;5486.341639003345;5610.429079857647;5846.006178949504;6109.908351374822;6125.388069147376;5178.860650656849;5456.727163641882;5464.699441530754;5540.861478463071;5967.104934259895 05:29:39 INFO - PROCESS | 1658 | |1;ext.html;463.3767582601401;478.3804972560534;493.90615783001226;519.2548692626082;538.9973050134749;37378.43932341014;39149.608503914955;39453.40819887697;40868.773936781945;41290;38609.61390386102;40218.798906005504;40419.79790101053;40425.19149617006;40498.19003619926;8132.918670813305;9376.390534615262;9452.621895124186;9454.196393306578;9479.47862867542;9377.67178148765;9407.905920940771;9409.95295023524;9446.38598491096;9467.053294670546;9374.109459601326;9404.717858464257;9462.384944978554;9468.384555003902;9474.005229450911;9374.85937710935;9417.764555886124;9445.480498572575;9476.857847132298;9505.904940950571;9328.160465558132;9358.719238422858;9403.623855045858;9405.059494050609;9410.764730881714;38520.614793851986;38637.61362386368;38756.61243387558;38926.22147557033;38974.61025389738;3226.2902161524426;3323.21904352477;3341.916452088693;3404.285100128976;3589.3898037333456;29764.255893602618;30207.546886796717;30362;30671.926482573224;30762.23094422635;3140.34052848902;3181.1728950473043;3213.405519978788;3230.7576931730214;3293.275479394529;453694;456626;456631.716841417;460152;462155;46213.382531611234;54987.72506137444;55337.44662553323;55830;55863.720681396335;49333.753331233835;55630.72184639051;57999;58012.70993645089;58282.4171758277;15513.991590546804;15732.68534629324;15762.448314308944;15776.526704198663;15812;12159.148859579744;12201;12227.205231659913;12245.571405000783;12288.139830211978;14305.28473576317;15311.540653780405;15364.923175384052;15432.305546250222;15645.139517326656;3170.4134735074085;5228.816991405282;5235.424103348629;5917.378675239038;6157.230346206724;728.7595093619093;857.0615176381851;888.5068786823375;931.7577429868147;1035.9378437293788;29160.416791664047;29689.25776855596;29827.552586711216;30349.696503035128;30351.848240758656;1000.2947921715173;1000.5947591225565;1000.9949950250202;1043.4678314059884;1048.2871647279924;15232.847671523365;15288.38846446152;15442.79043780183;15548.144852033247;15551.22243887801;659598;659604;659733;661972;663130;55068.724656376464;55646;55751.14612965661;55870;56006;1275.6428200103985;1339.1429485129524;1346.098114263446;1347.2455424961856;1349.2646507653353;29424.117276480883;29436.116916492538;29584.556231656974;30112.548311774903;30191.943281985026;43382.78308608437;45952.77023614861;47478.28782568195;48229.75885120552;48546.75726621344;461407.6929615465;580142.3971520462;596567;603728;603986;24175.39561510906;24855.13007044817;25771.355716107246;25922.48155036851;26039.609405859304;37475.81262093672;44647.330290046324;45463.86340341518;46149.30776038429;46395.76802115968;37751.2449751009;37826;37964;38935;39481.80259098686;37665.81167094147;37852.432213516266;38944.831655050395;38989.415158773205;39035.6096439032;36562.53749849976;36709.60478994124;37018.074548136516;37191.62808371882;37220;26550.86724566365;32587.674123259414;33785.32429351448;34070.318593628486;34103.31793364069;20351.694724578847;24431.900564474996;24785.008599656532;24959;25423.23730288094 05:29:39 INFO - PROCESS | 1658 | |2;jquery.html;271.5722736689714;359.6313778377163;375.76702444484414;380.8878076018027;382.70722896983796;19064.809351906475;20211;20445.693314600292;20919.05864236109;21078.57842843142;19830;20241.392758217276;20301.898490507527;20388.286409975655;20419.693704594443;5605.90684816461;5762.279715035631;5882.647041177522;6004.759809607611;6069.514438844903;5867.677277749722;6040.214772079624;6040.697965101749;6046.516278697693;6054.273487181523;5672.12082127271;5864.091065884773;5927.318358388776;5994.8501287467725;5998.700064996755;5825.970870145643;5838.737256823453;5869.295684517842;5876.823695289148;5920.970395148018;4856.757162141878;5053;5060.9493905060845;5093.719845408501;5119.360079990001;19832.93424847903;21728.674069888963;24961.87519062393;25436.109736159146;25513;2206.6800313954545;2767.8892844286206;2811.5360965440677;2893.594896714462;3040.3488337365293;12064.155509114365;12217.450214740358;12574.868261856385;14843.332050057774;14994.850051499454;1426.7289215049107;1654.346533119411;1881.8306352428347;1890.4706682129026;1900.9049547522568;105030;106976.93023069747;120867.395663022;121242;126794.73205267922;10416.104430917456;10663.146948244123;10968.067714244378;11545.538178472934;11578.594749183738;14316.141031538142;14363.92818035924;14384.568462946128;14392.496262630757;14392.992490525563;8365.95817020911;8543.914560854437;8579.184977427134;8653.956730216434;8654.740357789151;8365.330773538102;8391.95804020976;8409.789755256168;8470.745877623682;8563.443376180529;4436.711613745097;4524.25349817283;4556.88607784808;4577.878419787107;4580.610648094883;1776.6446710657663;1781.6525777473269;1781.8485428738443;1790.2123065850983;1790.6955817511055;832.6253186066294;871.2072014466805;910.7996240827008;936.8922573903952;1023.3808545829814;20166.39500814978;21051.78948210529;21135.365939021853;21199.364019079454;21202.363929081843;765.4297548326532;859.9398042136991;897.8967418746797;924.3621900888365;937.301710225886;10725.249232553646;11154.498047587878;11177.99398054179;11189.49647265875;11245.325280483197;585689;591512;606637;612939;613842;119909;121534.39232803957;124554;126356;137592;953.8283109040301;959.1846930109491;971.6502059258661;1014.9391036537831;1026.645807196531;15694.66595339409;16513.752293715843;18098.90950545239;19216.03919803976;19251.71854943027;52747.47252527426;53181;53523.732381337846;53850.653733656975;54243;59359.70320148372;76539.61730191314;77241.22758772565;78551.21448785439;79287.20712792798;6711;6866.244713081657;7944.020992127952;8075.757727268191;8198.590070496331;10382.377057376581;12345.506179752709;12505.249685018927;12900.483980640669;13057.869421306046;10306.12397946183;10791.6222932197;10990.505427255634;11127.443627818739;11142.888571114509;10516.947415262875;10600;10636.574537018432;11019.283746556448;11112.22214444981;10792.622258220925;10866.67399978002;10959.671209863716;11372.601958931398;11401.942990284995;7034.859302813813;7343.963280183779;7359.668814903235;7459.627018649155;7576.583287919218;9972.60109595608;10735.463226838783;11064.28082174656;11084.390358530358;11235.325880447199 05:29:39 INFO - PROCESS | 1658 | |3;mootools.html;1500.6473478732498;1509.886758493113;1722.5951901303192;1807.1867659553188;1845.4555906007736;7877.566733829637;8121.309688676455;8347.457415268003;8930.19628233459;8934.151255630713;6948.756793512228;8634.525101119436;8652.134786521345;8751.993520745116;8780.956095219532;2333.3583264602244;2354.1289722802585;2355.105060077177;2356.69362982812;2366.7041619797524;2313.710786151731;2327.883605819702;2365.042157926043;2368.6447032945025;2375.8099352051795;2211.8119959803435;2329.0683726509365;2334.8482348647367;2342.4612339162004;2363.149266264152;2137.1237792505076;2320.6867072945106;2336.275754516097;2341.5668101401297;2343;2317.5017371265258;2321.5356928614347;2322.558713844381;2328.371339738278;2328.860268383902;8766.035736069027;8798.692045778367;8868.246199073086;8874.689385871528;8925.598348074353;5091.261767043795;5201.933603611288;5253.52718255355;5285.894282114375;5347.54545863602;3470.5661792275964;3542.574891013069;3663.084228942764;3672.816359182057;3680.6135355787524;1041.5313109100935;1064.7072055184808;1074.2802322443981;1086.255914698434;1093.2511229807528;11490.885091149148;11565.595204167814;11630.476628551736;11645.068394528418;11645.359505227294;4531.388262584509;4625.121226966866;4786.7845946932475;4792.424909010941;4802.831900883452;9882.851757223503;9988.200943924468;10006.299559030798;10021.699349019542;10037.598496060076;5192.065428222956;5244.396894357122;5258.2638430619745;5267.657602255841;5268.157094864803;4332.935005974913;4479.820807167677;4643.628509719214;4760.166970780099;4831.565159135695;3880;3904.550976637723;3990.68074554035;3999.7600143991453;4077.3068578341754;1280.0847394113255;1293.5407930184838;1293.6959814443599;1295.4947570447532;1313.638749343919;759.9848003039908;892.3797960417447;916.9266458683289;935.4434111703525;938.7512309238058;5746.9425305747245;5892.823215303548;5949.940500595025;6092.116643086816;6128.571000030044;430.5328718340573;439.83726021371655;441.3886766827902;974.7660561465198;979.0894468144552;3272.4600440927456;3306.553615261909;4001.799910004488;4048.068944142805;4173.436586060843;10083.848742268725;10472.109870660926;10497.895021049844;10498.370097794157;10507.94746026265;10358.015988481082;10524.105451036594;10673.519691613896;10694.786104277871;10716.571337146583;8015.5190688557705;8186.959065204635;8191.508509489449;8205.466644668077;8234.217749313926;5799.94200057994;6017.157597936205;6042;6149.41580549847;6192.318844927145;9773.8760042596;9918.05778451046;10029.949850250701;10083.495825208856;10109.443980581138;9932.851007234753;10207.183425325808;10227.94886025565;10265.538050787583;10280.948595256976;7412.6664300107705;7690.192529784292;7930.920690793018;8068.7175948841505;8242.093369729444;8156.755297341089;8263.628136733741;8350.290225330671;8390.706325278587;8396.622152003052;7280.781576552712;7431.442641801995;7457.440691948017;7467.813304667427;7493.625318734151;6997.965010174917;7130.322619351154;7232.168300645493;7277.16312624055;7388.6675099621725;6876.209235937931;6889.586624802528;6997.930020699931;6999.51003429755;7004.15950085993;4790.520947905182;5005.62457815658;5035.823746168867;5060.2156665716875;5118.104331742004;4323.666437214995;4427.512973572969;4890.2909078184175;5166.173412254021;5201.063808514353 05:29:39 INFO - PROCESS | 1658 | |4;prototype.html;300.9653889802673;308.2202028866967;308.8356894507706;314.20662826363446;316.0959655385598;5541;5588.469095435938;5621.80323688671;5656.943430565693;5657.603967722261;5110.412302585194;5461.388890277368;5512.862178445541;5513.388730381596;5517.36550296716;3213.4215841148584;3251.577294951653;3901.824417901187;3909.4135879618;4001.979990100053;3465.2549701814105;3931.4889064421586;4118.361653943643;4165.791710414467;4168.7290326128705;3455.378031954247;3477.7217822574135;4156.854510092147;4183.6403168970055;4235.703500754948;3302.884399046046;3990.4812374391436;4036.3340048891896;4155.792210389483;4164.916701665949;3571.821408929543;3575.8748443804343;3580.982095089534;3587.264610754789;3590.7486475946694;4698.02682873194;4872.4883887192045;5324.494173053554;5358.812441564526;5361.463853614608;3830.655241028293;3842.2315536892734;4062.7765472898977;4508.977455112704;4644.837430689943;4442.955570444319;5742.885142297131;5759.251297331362;5796.913046304308;5956.553258505586;3955.2287303975736;3966.9008274793073;3974.4435778990965;3976.005998500375;3994.3609022556543;5281.841544753664;5713.628614140109;6111.638598770703;6112.877742445171;6131.785387511461;3300.669933006728;3556.3065202285306;3878.825452854628;3987.381955796798;3994.1013272013565;4067.864371205141;4103.671706263492;4120.75275483472;4123.865936867325;4128.545859955402;3982.287616325008;4382.5099466181655;4780.282957556395;4790.592799612003;4803.927941080887;4545.36364908913;5066.189409694432;5393.784248630011;5840.532757379399;5855.502282305966;1185.958491452795;1366.7403193393222;1369.1921766157816;1370.1641998381178;1370.1984339161702;621.7264403662374;757.83706503101;810.6473683947502;829;840.3025488844245;4899.289603007615;4957.578605818546;5013.799448022039;5103.622022054099;5498.725063746797;6019.849503762441;6027.728752206073;6028.613418913675;6044.274687037584;6081.422264884829;4572.7942242599165;5240.960243059017;5431.21247419122;5492.5331346835965;5538.335399752055;2928.677845437;3126.7498600111935;3170.7621928355466;3171.254755132542;3173.6191657000845;5608.747606357642;6243;6257.936150854366;6269.62382257066;6270.561060725706;5328.626996110235;5653.575981801381;6052.939470605325;6055.757769689163;6077.696115194222;6202.34875338092;6228.45322914266;6241.065269766387;6261.780837670751;6293.96853015732;5701.144828275792;5768.278965129359;5924.081767326072;6003.998000999501;6036.3058248302;4838.661293709547;5041.823536176216;5849.883002340014;5952.791652292149;5958.553108516792;5288.920666189933;5701.914471282851;6087.086936959491;6201.193844800147;6245.781397651061;3155.7002084801907;3685.281370132798;3865.671417929395;3918.588548202512;3919.4316824059783;5096.847094587169;5972.402759723993;5993.280806303272;5997.850053748517;6015.699215039143;5444.161865884463;5786.855328616818;5814.476697097281;5833.737481813244;5859.794907178229;4607.424071991001;5037.874053148701;5738.282714660668;5741.650712082574;5773.3072031356505;5012.571417146096;5012.949870501394;5195.68825870449;5394.460553944574;5680.346760122638;4726.763661816826;5411.0801163803035;5447.700376479332;5505.532029777513;5783.421657834183;5693.373728889736;5694.857628559319;5719.771209151588;5738.053221218453;5858.7070646466655 05:29:39 INFO - PROCESS | 1658 | |5;yui.html;687.5840116729379;696.6447111972896;706.766766966901;742.3430264216174;745.7203548669249;13954.302284885753;14976.326065327059;14991.925040374783;15008.39966401344;15082;13428.66428339292;13476.797848032287;13507.189568625889;13529.59411217665;13548.525801596947;655.3839390972471;685.9314068593151;808.4987307869129;829.904560975488;842.9409941304111;838.7106448275366;849.6261644876265;850.196564246787;850.217799624344;854.8461276970143;854.5727136431784;908.2416182487598;912.4434095201922;913.0823522360018;913.7715571107224;802.2418814220564;858.2147335188326;874.9650013999433;875.0505701314082;894.5795476126243;541.6506353402073;583;598.8083813179784;678.7285085965603;685.4208194075998;12249.387530623433;13845;14225.573232803032;14229.43082276718;14269.429222831075;13108.27904465254;13821.79267310991;14402.351894164787;14403.71192576153;14815.18516481593;10409.646745923057;11392.886071139348;11760.58837940668;11765.470553825098;11806.763864722743;3701.8889433317045;3736.1220113273357;3736.5516138063335;3762.5861155272896;3822.9044273893373;114690;115033;115058;115138;117728.82271177265;8833.779155521164;9031.45811251327;9189.586468608928;9194.356395052282;9279.118483744034;20612.58774824495;20693.89653051725;20834.79165208359;20966.266180683604;21049.789502104784;7386.409087273005;7419.777406677808;7476.401887848959;7479.401647868158;7623.161452240249;4198.832046718158;4207.179599978036;4232.68254880879;4268.509121451011;4375.321825117048;3383.9661603384143;3487.3897068013;3493.6681015303507;3495.8601655933476;3744.194998075427;703.1000319590829;708.553611224925;712.2521352579874;712.8217945513621;721.9278072192739;558.5029323901731;587.6297932302622;597.2683462758087;605.0193833951334;633.5818359882455;7822.804429889298;8608.440451370641;8910.465372077697;9246.491442970566;9311.255099592016;507.23154421052135;523.9730128947306;630.3790766095392;633.3792882974618;701.5650296816007;4304.182205380967;4694.647901407408;4993.7503124843615;5065;5106.387233532;121364;149528.07550792038;158285;161063;164470;131689;132277;140016;141286;142838.57421386876;115520;116310;117846.4107679456;118857;119548.40225798816;9680.951595241979;9773.951130244302;9789.265805064506;9800.705978820646;9841.19525935105;64038.67980660253;64092.03861942168;64103;64133;64408.677956609914;88341;99597.00402995877;99958;101318.49340753249;101418.492907535;12945.482180712666;13175.736485270432;13205.537806176737;13519.797203042159;13822.101563398346;14808.925955370154;14861.331240094005;14992.175430351435;15072.924635376752;15600.453984110502;13890.722185556435;13975.231362275172;13990;14052;14493.92753036228;11372.715682108013;13186.340682965621;13290.107024770994;13488.258145802076;13725.970552208824;13191.538296159588;13230.69232691827;13293.401796919356;13803.447862085806;14216.147031178163;11334.603288885179;11379.564369052005;11386.259893106922;11526.769464610494;11713.062954963752;9500.95249523748;9828.705138845846;9973.50132493387;10694.358338499715;11572.94213528927 05:29:39 INFO - PROCESS | 1658 | __end_tp_report 05:29:39 INFO - PROCESS | 1658 | __start_cc_report 05:29:39 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,378 05:29:39 INFO - PROCESS | 1658 | __end_cc_report 05:29:39 INFO - PROCESS | 1658 | __startTimestamp1476361779438__endTimestamp 05:29:39 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | ------- Summary: start ------- 05:29:39 INFO - PROCESS | 1658 | Number of tests: 6 05:29:39 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5646.00 Median:5789.97 stddev:661.95 (11.4%) stddev-sans-first:635.98 05:29:39 INFO - PROCESS | 1658 | Values: 3217.4 3364.7 3402.6 3427.2 3429.9 5607.6 5682.9 5713.4 5751.8 5788.7 5525.4 5669.4 5698.6 5716.9 5746.5 5099.4 5164.7 5165.3 5191.6 5234.3 5179.7 5265.4 5303.9 5353.5 5383.8 5078.5 5205.1 5231.7 5239.8 5284.9 5063.8 5183.8 5215.0 5232.3 5251.1 5098.8 5135.9 5146.0 5150.7 5213.9 5430.0 5551.8 5612.5 5676.6 5710.1 5377.2 6097.0 6126.7 6225.1 6269.1 6222.5 6228.6 6237.3 6248.3 6280.6 4587.9 5769.7 5790.0 5805.7 5812.4 6170.1 6212.7 6234.7 6236.3 6255.7 6094.3 6100.9 6103.6 6112.7 6114.3 6083.4 6202.2 6213.4 6236.9 6259.4 5914.4 6063.9 6113.4 6136.8 6140.5 4567.7 6099.2 6111.7 6164.0 6201.1 4886.3 4893.4 4921.8 4929.4 4977.9 3641.3 4328.7 4333.1 4366.1 4403.8 5690.2 5806.2 5810.6 5850.5 5854.5 6177.1 6208.1 6217.3 6230.2 6245.9 5630.0 5715.9 5719.3 5819.9 5827.2 4729.1 5423.0 5436.2 5446.5 5446.8 6329.2 6389.5 6394.3 6409.4 6411.5 6117.4 6142.8 6149.1 6224.4 6251.0 4742.3 6224.3 6318.8 6334.2 6348.6 6139.7 6151.8 6156.6 6176.2 6178.2 5030.8 6092.0 6144.0 6180.0 6218.2 5217.1 5439.5 6147.6 6152.1 6153.4 5016.2 6131.9 6133.7 6150.3 6156.8 5594.8 5720.1 5722.5 5895.3 5934.4 5486.3 5610.4 5846.0 6109.9 6125.4 5178.9 5456.7 5464.7 5540.9 5967.1 05:29:39 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:68567.57 Median:38959.72 stddev:152499.02 (391.4%) stddev-sans-first:152841.09 05:29:39 INFO - PROCESS | 1658 | Values: 463.4 478.4 493.9 519.3 539.0 37378.4 39149.6 39453.4 40868.8 41290.0 38609.6 40218.8 40419.8 40425.2 40498.2 8132.9 9376.4 9452.6 9454.2 9479.5 9377.7 9407.9 9410.0 9446.4 9467.1 9374.1 9404.7 9462.4 9468.4 9474.0 9374.9 9417.8 9445.5 9476.9 9505.9 9328.2 9358.7 9403.6 9405.1 9410.8 38520.6 38637.6 38756.6 38926.2 38974.6 3226.3 3323.2 3341.9 3404.3 3589.4 29764.3 30207.5 30362.0 30671.9 30762.2 3140.3 3181.2 3213.4 3230.8 3293.3 453694.0 456626.0 456631.7 460152.0 462155.0 46213.4 54987.7 55337.4 55830.0 55863.7 49333.8 55630.7 57999.0 58012.7 58282.4 15514.0 15732.7 15762.4 15776.5 15812.0 12159.1 12201.0 12227.2 12245.6 12288.1 14305.3 15311.5 15364.9 15432.3 15645.1 3170.4 5228.8 5235.4 5917.4 6157.2 728.8 857.1 888.5 931.8 1035.9 29160.4 29689.3 29827.6 30349.7 30351.8 1000.3 1000.6 1001.0 1043.5 1048.3 15232.8 15288.4 15442.8 15548.1 15551.2 659598.0 659604.0 659733.0 661972.0 663130.0 55068.7 55646.0 55751.1 55870.0 56006.0 1275.6 1339.1 1346.1 1347.2 1349.3 29424.1 29436.1 29584.6 30112.5 30191.9 43382.8 45952.8 47478.3 48229.8 48546.8 461407.7 580142.4 596567.0 603728.0 603986.0 24175.4 24855.1 25771.4 25922.5 26039.6 37475.8 44647.3 45463.9 46149.3 46395.8 37751.2 37826.0 37964.0 38935.0 39481.8 37665.8 37852.4 38944.8 38989.4 39035.6 36562.5 36709.6 37018.1 37191.6 37220.0 26550.9 32587.7 33785.3 34070.3 34103.3 20351.7 24431.9 24785.0 24959.0 25423.2 05:29:39 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34859.32 Median:25198.99 stddev:100376.33 (398.3%) stddev-sans-first:100624.31 05:29:39 INFO - PROCESS | 1658 | Values: 271.6 359.6 375.8 380.9 382.7 19064.8 20211.0 20445.7 20919.1 21078.6 19830.0 20241.4 20301.9 20388.3 20419.7 5605.9 5762.3 5882.6 6004.8 6069.5 5867.7 6040.2 6040.7 6046.5 6054.3 5672.1 5864.1 5927.3 5994.9 5998.7 5826.0 5838.7 5869.3 5876.8 5921.0 4856.8 5053.0 5060.9 5093.7 5119.4 19832.9 21728.7 24961.9 25436.1 25513.0 2206.7 2767.9 2811.5 2893.6 3040.3 12064.2 12217.5 12574.9 14843.3 14994.9 1426.7 1654.3 1881.8 1890.5 1900.9 105030.0 106976.9 120867.4 121242.0 126794.7 10416.1 10663.1 10968.1 11545.5 11578.6 14316.1 14363.9 14384.6 14392.5 14393.0 8366.0 8543.9 8579.2 8654.0 8654.7 8365.3 8392.0 8409.8 8470.7 8563.4 4436.7 4524.3 4556.9 4577.9 4580.6 1776.6 1781.7 1781.8 1790.2 1790.7 832.6 871.2 910.8 936.9 1023.4 20166.4 21051.8 21135.4 21199.4 21202.4 765.4 859.9 897.9 924.4 937.3 10725.2 11154.5 11178.0 11189.5 11245.3 585689.0 591512.0 606637.0 612939.0 613842.0 119909.0 121534.4 124554.0 126356.0 137592.0 953.8 959.2 971.7 1014.9 1026.6 15694.7 16513.8 18098.9 19216.0 19251.7 52747.5 53181.0 53523.7 53850.7 54243.0 59359.7 76539.6 77241.2 78551.2 79287.2 6711.0 6866.2 7944.0 8075.8 8198.6 10382.4 12345.5 12505.2 12900.5 13057.9 10306.1 10791.6 10990.5 11127.4 11142.9 10516.9 10600.0 10636.6 11019.3 11112.2 10792.6 10866.7 10959.7 11372.6 11401.9 7034.9 7344.0 7359.7 7459.6 7576.6 9972.6 10735.5 11064.3 11084.4 11235.3 05:29:39 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5680.46 Median:4773.48 stddev:3196.08 (67.0%) stddev-sans-first:3189.61 05:29:39 INFO - PROCESS | 1658 | Values: 1500.6 1509.9 1722.6 1807.2 1845.5 7877.6 8121.3 8347.5 8930.2 8934.2 6948.8 8634.5 8652.1 8752.0 8781.0 2333.4 2354.1 2355.1 2356.7 2366.7 2313.7 2327.9 2365.0 2368.6 2375.8 2211.8 2329.1 2334.8 2342.5 2363.1 2137.1 2320.7 2336.3 2341.6 2343.0 2317.5 2321.5 2322.6 2328.4 2328.9 8766.0 8798.7 8868.2 8874.7 8925.6 5091.3 5201.9 5253.5 5285.9 5347.5 3470.6 3542.6 3663.1 3672.8 3680.6 1041.5 1064.7 1074.3 1086.3 1093.3 11490.9 11565.6 11630.5 11645.1 11645.4 4531.4 4625.1 4786.8 4792.4 4802.8 9882.9 9988.2 10006.3 10021.7 10037.6 5192.1 5244.4 5258.3 5267.7 5268.2 4332.9 4479.8 4643.6 4760.2 4831.6 3880.0 3904.6 3990.7 3999.8 4077.3 1280.1 1293.5 1293.7 1295.5 1313.6 760.0 892.4 916.9 935.4 938.8 5746.9 5892.8 5949.9 6092.1 6128.6 430.5 439.8 441.4 974.8 979.1 3272.5 3306.6 4001.8 4048.1 4173.4 10083.8 10472.1 10497.9 10498.4 10507.9 10358.0 10524.1 10673.5 10694.8 10716.6 8015.5 8187.0 8191.5 8205.5 8234.2 5799.9 6017.2 6042.0 6149.4 6192.3 9773.9 9918.1 10029.9 10083.5 10109.4 9932.9 10207.2 10227.9 10265.5 10280.9 7412.7 7690.2 7930.9 8068.7 8242.1 8156.8 8263.6 8350.3 8390.7 8396.6 7280.8 7431.4 7457.4 7467.8 7493.6 6998.0 7130.3 7232.2 7277.2 7388.7 6876.2 6889.6 6997.9 6999.5 7004.2 4790.5 5005.6 5035.8 5060.2 5118.1 4323.7 4427.5 4890.3 5166.2 5201.1 05:29:39 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4638.18 Median:5261.40 stddev:1482.43 (28.2%) stddev-sans-first:1450.40 05:29:39 INFO - PROCESS | 1658 | Values: 301.0 308.2 308.8 314.2 316.1 5541.0 5588.5 5621.8 5656.9 5657.6 5110.4 5461.4 5512.9 5513.4 5517.4 3213.4 3251.6 3901.8 3909.4 4002.0 3465.3 3931.5 4118.4 4165.8 4168.7 3455.4 3477.7 4156.9 4183.6 4235.7 3302.9 3990.5 4036.3 4155.8 4164.9 3571.8 3575.9 3581.0 3587.3 3590.7 4698.0 4872.5 5324.5 5358.8 5361.5 3830.7 3842.2 4062.8 4509.0 4644.8 4443.0 5742.9 5759.3 5796.9 5956.6 3955.2 3966.9 3974.4 3976.0 3994.4 5281.8 5713.6 6111.6 6112.9 6131.8 3300.7 3556.3 3878.8 3987.4 3994.1 4067.9 4103.7 4120.8 4123.9 4128.5 3982.3 4382.5 4780.3 4790.6 4803.9 4545.4 5066.2 5393.8 5840.5 5855.5 1186.0 1366.7 1369.2 1370.2 1370.2 621.7 757.8 810.6 829.0 840.3 4899.3 4957.6 5013.8 5103.6 5498.7 6019.8 6027.7 6028.6 6044.3 6081.4 4572.8 5241.0 5431.2 5492.5 5538.3 2928.7 3126.7 3170.8 3171.3 3173.6 5608.7 6243.0 6257.9 6269.6 6270.6 5328.6 5653.6 6052.9 6055.8 6077.7 6202.3 6228.5 6241.1 6261.8 6294.0 5701.1 5768.3 5924.1 6004.0 6036.3 4838.7 5041.8 5849.9 5952.8 5958.6 5288.9 5701.9 6087.1 6201.2 6245.8 3155.7 3685.3 3865.7 3918.6 3919.4 5096.8 5972.4 5993.3 5997.9 6015.7 5444.2 5786.9 5814.5 5833.7 5859.8 4607.4 5037.9 5738.3 5741.7 5773.3 5012.6 5012.9 5195.7 5394.5 5680.3 4726.8 5411.1 5447.7 5505.5 5783.4 5693.4 5694.9 5719.8 5738.1 5858.7 05:29:39 INFO - PROCESS | 1658 | 05:29:39 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:25593.47 Median:3740.37 stddev:41714.99 (1115.3%) stddev-sans-first:41790.09 05:29:39 INFO - PROCESS | 1658 | Values: 687.6 696.6 706.8 742.3 745.7 13954.3 14976.3 14991.9 15008.4 15082.0 13428.7 13476.8 13507.2 13529.6 13548.5 655.4 685.9 808.5 829.9 842.9 838.7 849.6 850.2 850.2 854.8 854.6 908.2 912.4 913.1 913.8 802.2 858.2 875.0 875.1 894.6 541.7 583.0 598.8 678.7 685.4 12249.4 13845.0 14225.6 14229.4 14269.4 13108.3 13821.8 14402.4 14403.7 14815.2 10409.6 11392.9 11760.6 11765.5 11806.8 3701.9 3736.1 3736.6 3762.6 3822.9 114690.0 115033.0 115058.0 115138.0 117728.8 8833.8 9031.5 9189.6 9194.4 9279.1 20612.6 20693.9 20834.8 20966.3 21049.8 7386.4 7419.8 7476.4 7479.4 7623.2 4198.8 4207.2 4232.7 4268.5 4375.3 3384.0 3487.4 3493.7 3495.9 3744.2 703.1 708.6 712.3 712.8 721.9 558.5 587.6 597.3 605.0 633.6 7822.8 8608.4 8910.5 9246.5 9311.3 507.2 524.0 630.4 633.4 701.6 4304.2 4694.6 4993.8 5065.0 5106.4 121364.0 149528.1 158285.0 161063.0 164470.0 131689.0 132277.0 140016.0 141286.0 142838.6 115520.0 116310.0 117846.4 118857.0 119548.4 9681.0 9774.0 9789.3 9800.7 9841.2 64038.7 64092.0 64103.0 64133.0 64408.7 88341.0 99597.0 99958.0 101318.5 101418.5 12945.5 13175.7 13205.5 13519.8 13822.1 14808.9 14861.3 14992.2 15072.9 15600.5 13890.7 13975.2 13990.0 14052.0 14493.9 11372.7 13186.3 13290.1 13488.3 13726.0 13191.5 13230.7 13293.4 13803.4 14216.1 11334.6 11379.6 11386.3 11526.8 11713.1 9501.0 9828.7 9973.5 10694.4 11572.9 05:29:39 INFO - PROCESS | 1658 | -------- Summary: end -------- 05:29:39 INFO - PROCESS | 1658 | 05:29:40 INFO - TEST-INFO | 1658: exit 0 05:29:40 INFO - TEST-OK | dromaeo_css | took 1114439ms 05:29:40 INFO - TEST-START | kraken 05:29:40 INFO - operating with platform_type : mac_ 05:29:40 INFO - Initialising browser for kraken test... 05:29:40 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpGNXipl/profile http://localhost:49269/getInfo.html) 05:29:42 INFO - PROCESS | 1667 | 2016-10-13 05:29:42.789 plugin-container[1668:9932] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a43, name = 'com.apple.tsm.portname' 05:29:42 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:42 INFO - PROCESS | 1667 | 2016-10-13 05:29:42.792 plugin-container[1668:9932] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 05:29:42 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:42 INFO - PROCESS | 1667 | 2016-10-13 05:29:42.792 plugin-container[1668:9932] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:29:42 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 05:29:42 INFO - PROCESS | 1667 | colorDepth:24 05:29:42 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 05:29:42 INFO - PROCESS | 1667 | __metrics 05:29:42 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 05:29:43 INFO - TEST-INFO | 1667: exit 0 05:29:43 INFO - Browser initialized. 05:29:43 INFO - Running cycle 1/1 for kraken test... 05:29:43 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmpGNXipl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:46 INFO - PROCESS | 1669 | 2016-10-13 05:29:46.058 plugin-container[1670:10047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 05:29:46 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:46 INFO - PROCESS | 1669 | 2016-10-13 05:29:46.059 plugin-container[1670:10047] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 05:29:46 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:29:46 INFO - PROCESS | 1669 | 2016-10-13 05:29:46.059 plugin-container[1670:10047] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:29:55 INFO - PROCESS | 1669 | RSS: Main: 240590848 05:29:55 INFO - PROCESS | 1669 | 05:29:58 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection) 05:29:59 INFO - PROCESS | 1669 | RSS: Main: 254324736 05:29:59 INFO - PROCESS | 1669 | 05:30:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-dft) 05:30:03 INFO - PROCESS | 1669 | RSS: Main: 261832704 05:30:03 INFO - PROCESS | 1669 | 05:30:06 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-fft) 05:30:06 INFO - PROCESS | 1669 | RSS: Main: 261488640 05:30:06 INFO - PROCESS | 1669 | 05:30:08 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator) 05:30:09 INFO - PROCESS | 1669 | RSS: Main: 262721536 05:30:09 INFO - PROCESS | 1669 | 05:30:11 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:30:12 INFO - PROCESS | 1669 | RSS: Main: 263045120 05:30:12 INFO - PROCESS | 1669 | 05:30:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom) 05:30:15 INFO - PROCESS | 1669 | RSS: Main: 264523776 05:30:15 INFO - PROCESS | 1669 | 05:30:18 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate) 05:30:19 INFO - PROCESS | 1669 | RSS: Main: 264679424 05:30:19 INFO - PROCESS | 1669 | 05:30:22 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial) 05:30:22 INFO - PROCESS | 1669 | RSS: Main: 264904704 05:30:22 INFO - PROCESS | 1669 | 05:30:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:30:25 INFO - PROCESS | 1669 | RSS: Main: 264564736 05:30:25 INFO - PROCESS | 1669 | 05:30:27 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:30:27 INFO - PROCESS | 1669 | RSS: Main: 264675328 05:30:27 INFO - PROCESS | 1669 | 05:30:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:30:30 INFO - PROCESS | 1669 | RSS: Main: 265240576 05:30:30 INFO - PROCESS | 1669 | 05:30:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:30:34 INFO - PROCESS | 1669 | RSS: Main: 264994816 05:30:34 INFO - PROCESS | 1669 | 05:30:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:30:38 INFO - PROCESS | 1669 | RSS: Main: 264957952 05:30:38 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49269/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49269/tests/kraken/driver.html?testName=ai-astar) 05:30:40 INFO - PROCESS | 1669 | RSS: Main: 266723328 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | __start_tp_report 05:30:40 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 05:30:40 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 05:30:40 INFO - PROCESS | 1669 | |i|pagename|runs| 05:30:40 INFO - PROCESS | 1669 | |0;ai-astar;106;118;115;120;117;118;116;117;116;134 05:30:40 INFO - PROCESS | 1669 | |1;audio-beat-detection;154;136;95;157;142;145;100;108;190;146 05:30:40 INFO - PROCESS | 1669 | |2;audio-dft;202;204;227;201;183;193;192;193;203;193 05:30:40 INFO - PROCESS | 1669 | |3;audio-fft;86;60;59;82;58;59;57;59;77;58 05:30:40 INFO - PROCESS | 1669 | |4;audio-oscillator;84;72;117;102;100;74;89;69;71;87 05:30:40 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;88;86;89;86;89;86;90;86;91;86 05:30:40 INFO - PROCESS | 1669 | |6;imaging-darkroom;95;95;116;95;117;152;120;150;152;94 05:30:40 INFO - PROCESS | 1669 | |7;imaging-desaturate;85;87;109;121;137;117;143;144;149;144 05:30:40 INFO - PROCESS | 1669 | |8;json-parse-financial;55;57;56;57;59;68;88;88;57;58 05:30:40 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;68;72;78;78;76;69;74;71;71;64 05:30:40 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;105;106;101;120;107;80;80;87;107;108 05:30:40 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;147;148;146;147;145;148;157;163;206;151 05:30:40 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;177;178;173;178;184;173;173;237;159;218 05:30:40 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;74;70;72;70;71;69;68;70;68;67 05:30:40 INFO - PROCESS | 1669 | __end_tp_report 05:30:40 INFO - PROCESS | 1669 | __start_cc_report 05:30:40 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,962 05:30:40 INFO - PROCESS | 1669 | __end_cc_report 05:30:40 INFO - PROCESS | 1669 | __startTimestamp1476361840316__endTimestamp 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | ------- Summary: start ------- 05:30:40 INFO - PROCESS | 1669 | Number of tests: 14 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:117.70 Median:117.50 stddev:6.85 (5.8%) stddev-sans-first:5.81 05:30:40 INFO - PROCESS | 1669 | Values: 106.0 118.0 115.0 120.0 117.0 118.0 116.0 117.0 116.0 134.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:137.30 Median:150.00 stddev:29.14 (19.4%) stddev-sans-first:30.27 05:30:40 INFO - PROCESS | 1669 | Values: 154.0 136.0 95.0 157.0 142.0 145.0 100.0 108.0 190.0 146.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:199.10 Median:201.50 stddev:11.79 (5.9%) stddev-sans-first:12.46 05:30:40 INFO - PROCESS | 1669 | Values: 202.0 204.0 227.0 201.0 183.0 193.0 192.0 193.0 203.0 193.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:65.50 Median:59.50 stddev:11.38 (19.1%) stddev-sans-first:9.35 05:30:40 INFO - PROCESS | 1669 | Values: 86.0 60.0 59.0 82.0 58.0 59.0 57.0 59.0 77.0 58.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:73.00 stddev:15.91 (21.8%) stddev-sans-first:16.85 05:30:40 INFO - PROCESS | 1669 | Values: 84.0 72.0 117.0 102.0 100.0 74.0 89.0 69.0 71.0 87.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.50 stddev:1.95 (2.2%) stddev-sans-first:2.06 05:30:40 INFO - PROCESS | 1669 | Values: 88.0 86.0 89.0 86.0 89.0 86.0 90.0 86.0 91.0 86.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:118.60 Median:123.00 stddev:24.73 (20.1%) stddev-sans-first:24.71 05:30:40 INFO - PROCESS | 1669 | Values: 95.0 95.0 116.0 95.0 117.0 152.0 120.0 150.0 152.0 94.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:123.60 Median:144.00 stddev:23.87 (16.6%) stddev-sans-first:20.83 05:30:40 INFO - PROCESS | 1669 | Values: 85.0 87.0 109.0 121.0 137.0 117.0 143.0 144.0 149.0 144.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:58.50 stddev:13.00 (22.2%) stddev-sans-first:13.34 05:30:40 INFO - PROCESS | 1669 | Values: 55.0 57.0 56.0 57.0 59.0 68.0 88.0 88.0 57.0 58.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:73.00 stddev:4.51 (6.2%) stddev-sans-first:4.53 05:30:40 INFO - PROCESS | 1669 | Values: 68.0 72.0 78.0 78.0 76.0 69.0 74.0 71.0 71.0 64.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:100.10 Median:114.00 stddev:13.30 (11.7%) stddev-sans-first:13.99 05:30:40 INFO - PROCESS | 1669 | Values: 105.0 106.0 101.0 120.0 107.0 80.0 80.0 87.0 107.0 108.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:155.80 Median:149.50 stddev:18.52 (12.4%) stddev-sans-first:19.36 05:30:40 INFO - PROCESS | 1669 | Values: 147.0 148.0 146.0 147.0 145.0 148.0 157.0 163.0 206.0 151.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.00 Median:178.00 stddev:23.72 (13.3%) stddev-sans-first:24.98 05:30:40 INFO - PROCESS | 1669 | Values: 177.0 178.0 173.0 178.0 184.0 173.0 173.0 237.0 159.0 218.0 05:30:40 INFO - PROCESS | 1669 | 05:30:40 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:70.00 stddev:2.08 (3.0%) stddev-sans-first:1.59 05:30:40 INFO - PROCESS | 1669 | Values: 74.0 70.0 72.0 70.0 71.0 69.0 68.0 70.0 68.0 67.0 05:30:40 INFO - PROCESS | 1669 | -------- Summary: end -------- 05:30:40 INFO - PROCESS | 1669 | 05:30:41 INFO - TEST-INFO | 1669: exit 0 05:30:41 INFO - TEST-OK | kraken | took 61134ms 05:30:41 INFO - SUITE-END | took 1175s 05:30:41 INFO - Completed test suite (00:19:35) 05:30:41 INFO - javascript benchmark 05:30:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7261.947408695398, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.3726123405936, 3364.7308215342773, 3402.5576675032244, 3427.1603457153, 3429.8971030869084, 5607.57943154263, 5682.914756278659, 5713.371529131792, 5751.827445176651, 5788.739506722197, 5525.364583072937, 5669.433056694328, 5698.572607054467, 5716.857078573027, 5746.454086861752, 5099.413567439755, 5164.664296820713, 5165.3026841376495, 5191.610629202806, 5234.319538459995, 5179.741012949356, 5265.420803711589, 5303.946960530403, 5353.544948679367, 5383.838484845461, 5078.542931136216, 5205.141151709963, 5231.686098834082, 5239.764210610531, 5284.867878303054, 5063.8480845574695, 5183.792648294064, 5214.97392513035, 5232.2674825524455, 5251.133562962144, 5098.847034588968, 5135.922961155548, 5146.047981123466, 5150.716710580917, 5213.895722085537, 5430.0, 5551.77792888284, 5612.522935550483, 5676.631018983753, 5710.143478478261, 5377.247185394048, 6096.963516202212, 6126.670355029888, 6225.128482012522, 6269.0909818076125, 6222.471089957314, 6228.5951413158455, 6237.313895471494, 6248.28144763349, 6280.591761535486, 4587.8623641290815, 5769.711514424262, 5789.9710501447225, 5805.6806875621405, 5812.393722854108, 6170.074488826712, 6212.720427580679, 6234.688265586793, 6236.282827474898, 6255.74977000924, 6094.257048438488, 6100.853682747703, 6103.596172880262, 6112.694365281807, 6114.327423983358, 6083.372880085992, 6202.224721909762, 6213.409726076015, 6236.906446403307, 6259.374062593704, 5914.378990205966, 6063.878722425527, 6113.38866113394, 6136.7852125175405, 6140.473573783631, 4567.725936443824, 6099.176611157525, 6111.694415279218, 6163.96918015416, 6201.069839524108, 4886.3403440535785, 4893.363862697826, 4921.753912304371, 4929.359183306147, 4977.925331120036, 3641.308151451216, 4328.696991210648, 4333.090051089306, 4366.12677464502, 4403.779811009436, 5690.2033715279895, 5806.1581070744505, 5810.564207684441, 5850.473457388855, 5854.502367298742, 6177.135201071854, 6208.099825525273, 6217.316095229521, 6230.158928544589, 6245.875082498415, 5629.971850140723, 5715.942550628147, 5719.342275638355, 5819.854503637358, 5827.155062515912, 4729.077829823152, 5422.972885135549, 5436.184572314184, 5446.482584154499, 5446.754896029688, 6329.240491141092, 6389.48884089281, 6394.296627370893, 6409.4231519163495, 6411.519136064907, 6117.3576774439825, 6142.836439233709, 6149.139120523219, 6224.408681175281, 6251.0, 4742.336072949721, 6224.315325314121, 6318.810435686937, 6334.239891213084, 6348.619082855043, 6139.723712432862, 6151.784687535915, 6156.5998210116195, 6176.1970943778815, 6178.196834411498, 5030.8239211627415, 6091.969540152271, 6143.972489078683, 6180.042093475518, 6218.160548325918, 5217.0870097733505, 5439.537639300704, 6147.569670123048, 6152.107944347955, 6153.3538978408415, 5016.172331565397, 6131.938680613315, 6133.662648554194, 6150.261968563801, 6156.78451254204, 5594.804181853616, 5720.141978703228, 5722.456366645162, 5895.292564892069, 5934.376890426615, 5486.341639003345, 5610.429079857647, 5846.006178949504, 6109.908351374822, 6125.388069147376, 5178.860650656849, 5456.727163641882, 5464.699441530754, 5540.861478463071, 5967.104934259895], "value": 5606.826953090469, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [463.3767582601401, 478.3804972560534, 493.90615783001226, 519.2548692626082, 538.9973050134749, 37378.43932341014, 39149.608503914955, 39453.40819887697, 40868.773936781945, 41290.0, 38609.61390386102, 40218.798906005504, 40419.79790101053, 40425.19149617006, 40498.19003619926, 8132.918670813305, 9376.390534615262, 9452.621895124186, 9454.196393306578, 9479.47862867542, 9377.67178148765, 9407.905920940771, 9409.95295023524, 9446.38598491096, 9467.053294670546, 9374.109459601326, 9404.717858464257, 9462.384944978554, 9468.384555003902, 9474.005229450911, 9374.85937710935, 9417.764555886124, 9445.480498572575, 9476.857847132298, 9505.904940950571, 9328.160465558132, 9358.719238422858, 9403.623855045858, 9405.059494050609, 9410.764730881714, 38520.614793851986, 38637.61362386368, 38756.61243387558, 38926.22147557033, 38974.61025389738, 3226.2902161524426, 3323.21904352477, 3341.916452088693, 3404.285100128976, 3589.3898037333456, 29764.255893602618, 30207.546886796717, 30362.0, 30671.926482573224, 30762.23094422635, 3140.34052848902, 3181.1728950473043, 3213.405519978788, 3230.7576931730214, 3293.275479394529, 453694.0, 456626.0, 456631.716841417, 460152.0, 462155.0, 46213.382531611234, 54987.72506137444, 55337.44662553323, 55830.0, 55863.720681396335, 49333.753331233835, 55630.72184639051, 57999.0, 58012.70993645089, 58282.4171758277, 15513.991590546804, 15732.68534629324, 15762.448314308944, 15776.526704198663, 15812.0, 12159.148859579744, 12201.0, 12227.205231659913, 12245.571405000783, 12288.139830211978, 14305.28473576317, 15311.540653780405, 15364.923175384052, 15432.305546250222, 15645.139517326656, 3170.4134735074085, 5228.816991405282, 5235.424103348629, 5917.378675239038, 6157.230346206724, 728.7595093619093, 857.0615176381851, 888.5068786823375, 931.7577429868147, 1035.9378437293788, 29160.416791664047, 29689.25776855596, 29827.552586711216, 30349.696503035128, 30351.848240758656, 1000.2947921715173, 1000.5947591225565, 1000.9949950250202, 1043.4678314059884, 1048.2871647279924, 15232.847671523365, 15288.38846446152, 15442.79043780183, 15548.144852033247, 15551.22243887801, 659598.0, 659604.0, 659733.0, 661972.0, 663130.0, 55068.724656376464, 55646.0, 55751.14612965661, 55870.0, 56006.0, 1275.6428200103985, 1339.1429485129524, 1346.098114263446, 1347.2455424961856, 1349.2646507653353, 29424.117276480883, 29436.116916492538, 29584.556231656974, 30112.548311774903, 30191.943281985026, 43382.78308608437, 45952.77023614861, 47478.28782568195, 48229.75885120552, 48546.75726621344, 461407.6929615465, 580142.3971520462, 596567.0, 603728.0, 603986.0, 24175.39561510906, 24855.13007044817, 25771.355716107246, 25922.48155036851, 26039.609405859304, 37475.81262093672, 44647.330290046324, 45463.86340341518, 46149.30776038429, 46395.76802115968, 37751.2449751009, 37826.0, 37964.0, 38935.0, 39481.80259098686, 37665.81167094147, 37852.432213516266, 38944.831655050395, 38989.415158773205, 39035.6096439032, 36562.53749849976, 36709.60478994124, 37018.074548136516, 37191.62808371882, 37220.0, 26550.86724566365, 32587.674123259414, 33785.32429351448, 34070.318593628486, 34103.31793364069, 20351.694724578847, 24431.900564474996, 24785.008599656532, 24959.0, 25423.23730288094], "value": 19031.52725239892, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.5722736689714, 359.6313778377163, 375.76702444484414, 380.8878076018027, 382.70722896983796, 19064.809351906475, 20211.0, 20445.693314600292, 20919.05864236109, 21078.57842843142, 19830.0, 20241.392758217276, 20301.898490507527, 20388.286409975655, 20419.693704594443, 5605.90684816461, 5762.279715035631, 5882.647041177522, 6004.759809607611, 6069.514438844903, 5867.677277749722, 6040.214772079624, 6040.697965101749, 6046.516278697693, 6054.273487181523, 5672.12082127271, 5864.091065884773, 5927.318358388776, 5994.8501287467725, 5998.700064996755, 5825.970870145643, 5838.737256823453, 5869.295684517842, 5876.823695289148, 5920.970395148018, 4856.757162141878, 5053.0, 5060.9493905060845, 5093.719845408501, 5119.360079990001, 19832.93424847903, 21728.674069888963, 24961.87519062393, 25436.109736159146, 25513.0, 2206.6800313954545, 2767.8892844286206, 2811.5360965440677, 2893.594896714462, 3040.3488337365293, 12064.155509114365, 12217.450214740358, 12574.868261856385, 14843.332050057774, 14994.850051499454, 1426.7289215049107, 1654.346533119411, 1881.8306352428347, 1890.4706682129026, 1900.9049547522568, 105030.0, 106976.93023069747, 120867.395663022, 121242.0, 126794.73205267922, 10416.104430917456, 10663.146948244123, 10968.067714244378, 11545.538178472934, 11578.594749183738, 14316.141031538142, 14363.92818035924, 14384.568462946128, 14392.496262630757, 14392.992490525563, 8365.95817020911, 8543.914560854437, 8579.184977427134, 8653.956730216434, 8654.740357789151, 8365.330773538102, 8391.95804020976, 8409.789755256168, 8470.745877623682, 8563.443376180529, 4436.711613745097, 4524.25349817283, 4556.88607784808, 4577.878419787107, 4580.610648094883, 1776.6446710657663, 1781.6525777473269, 1781.8485428738443, 1790.2123065850983, 1790.6955817511055, 832.6253186066294, 871.2072014466805, 910.7996240827008, 936.8922573903952, 1023.3808545829814, 20166.39500814978, 21051.78948210529, 21135.365939021853, 21199.364019079454, 21202.363929081843, 765.4297548326532, 859.9398042136991, 897.8967418746797, 924.3621900888365, 937.301710225886, 10725.249232553646, 11154.498047587878, 11177.99398054179, 11189.49647265875, 11245.325280483197, 585689.0, 591512.0, 606637.0, 612939.0, 613842.0, 119909.0, 121534.39232803957, 124554.0, 126356.0, 137592.0, 953.8283109040301, 959.1846930109491, 971.6502059258661, 1014.9391036537831, 1026.645807196531, 15694.66595339409, 16513.752293715843, 18098.90950545239, 19216.03919803976, 19251.71854943027, 52747.47252527426, 53181.0, 53523.732381337846, 53850.653733656975, 54243.0, 59359.70320148372, 76539.61730191314, 77241.22758772565, 78551.21448785439, 79287.20712792798, 6711.0, 6866.244713081657, 7944.020992127952, 8075.757727268191, 8198.590070496331, 10382.377057376581, 12345.506179752709, 12505.249685018927, 12900.483980640669, 13057.869421306046, 10306.12397946183, 10791.6222932197, 10990.505427255634, 11127.443627818739, 11142.888571114509, 10516.947415262875, 10600.0, 10636.574537018432, 11019.283746556448, 11112.22214444981, 10792.622258220925, 10866.67399978002, 10959.671209863716, 11372.601958931398, 11401.942990284995, 7034.859302813813, 7343.963280183779, 7359.668814903235, 7459.627018649155, 7576.583287919218, 9972.60109595608, 10735.463226838783, 11064.28082174656, 11084.390358530358, 11235.325880447199], "value": 9463.025873511076, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1500.6473478732498, 1509.886758493113, 1722.5951901303192, 1807.1867659553188, 1845.4555906007736, 7877.566733829637, 8121.309688676455, 8347.457415268003, 8930.19628233459, 8934.151255630713, 6948.756793512228, 8634.525101119436, 8652.134786521345, 8751.993520745116, 8780.956095219532, 2333.3583264602244, 2354.1289722802585, 2355.105060077177, 2356.69362982812, 2366.7041619797524, 2313.710786151731, 2327.883605819702, 2365.042157926043, 2368.6447032945025, 2375.8099352051795, 2211.8119959803435, 2329.0683726509365, 2334.8482348647367, 2342.4612339162004, 2363.149266264152, 2137.1237792505076, 2320.6867072945106, 2336.275754516097, 2341.5668101401297, 2343.0, 2317.5017371265258, 2321.5356928614347, 2322.558713844381, 2328.371339738278, 2328.860268383902, 8766.035736069027, 8798.692045778367, 8868.246199073086, 8874.689385871528, 8925.598348074353, 5091.261767043795, 5201.933603611288, 5253.52718255355, 5285.894282114375, 5347.54545863602, 3470.5661792275964, 3542.574891013069, 3663.084228942764, 3672.816359182057, 3680.6135355787524, 1041.5313109100935, 1064.7072055184808, 1074.2802322443981, 1086.255914698434, 1093.2511229807528, 11490.885091149148, 11565.595204167814, 11630.476628551736, 11645.068394528418, 11645.359505227294, 4531.388262584509, 4625.121226966866, 4786.7845946932475, 4792.424909010941, 4802.831900883452, 9882.851757223503, 9988.200943924468, 10006.299559030798, 10021.699349019542, 10037.598496060076, 5192.065428222956, 5244.396894357122, 5258.2638430619745, 5267.657602255841, 5268.157094864803, 4332.935005974913, 4479.820807167677, 4643.628509719214, 4760.166970780099, 4831.565159135695, 3880.0, 3904.550976637723, 3990.68074554035, 3999.7600143991453, 4077.3068578341754, 1280.0847394113255, 1293.5407930184838, 1293.6959814443599, 1295.4947570447532, 1313.638749343919, 759.9848003039908, 892.3797960417447, 916.9266458683289, 935.4434111703525, 938.7512309238058, 5746.9425305747245, 5892.823215303548, 5949.940500595025, 6092.116643086816, 6128.571000030044, 430.5328718340573, 439.83726021371655, 441.3886766827902, 974.7660561465198, 979.0894468144552, 3272.4600440927456, 3306.553615261909, 4001.799910004488, 4048.068944142805, 4173.436586060843, 10083.848742268725, 10472.109870660926, 10497.895021049844, 10498.370097794157, 10507.94746026265, 10358.015988481082, 10524.105451036594, 10673.519691613896, 10694.786104277871, 10716.571337146583, 8015.5190688557705, 8186.959065204635, 8191.508509489449, 8205.466644668077, 8234.217749313926, 5799.94200057994, 6017.157597936205, 6042.0, 6149.41580549847, 6192.318844927145, 9773.8760042596, 9918.05778451046, 10029.949850250701, 10083.495825208856, 10109.443980581138, 9932.851007234753, 10207.183425325808, 10227.94886025565, 10265.538050787583, 10280.948595256976, 7412.6664300107705, 7690.192529784292, 7930.920690793018, 8068.7175948841505, 8242.093369729444, 8156.755297341089, 8263.628136733741, 8350.290225330671, 8390.706325278587, 8396.622152003052, 7280.781576552712, 7431.442641801995, 7457.440691948017, 7467.813304667427, 7493.625318734151, 6997.965010174917, 7130.322619351154, 7232.168300645493, 7277.16312624055, 7388.6675099621725, 6876.209235937931, 6889.586624802528, 6997.930020699931, 6999.51003429755, 7004.15950085993, 4790.520947905182, 5005.62457815658, 5035.823746168867, 5060.2156665716875, 5118.104331742004, 4323.666437214995, 4427.512973572969, 4890.2909078184175, 5166.173412254021, 5201.063808514353], "value": 4529.316409255191, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.9653889802673, 308.2202028866967, 308.8356894507706, 314.20662826363446, 316.0959655385598, 5541.0, 5588.469095435938, 5621.80323688671, 5656.943430565693, 5657.603967722261, 5110.412302585194, 5461.388890277368, 5512.862178445541, 5513.388730381596, 5517.36550296716, 3213.4215841148584, 3251.577294951653, 3901.824417901187, 3909.4135879618, 4001.979990100053, 3465.2549701814105, 3931.4889064421586, 4118.361653943643, 4165.791710414467, 4168.7290326128705, 3455.378031954247, 3477.7217822574135, 4156.854510092147, 4183.6403168970055, 4235.703500754948, 3302.884399046046, 3990.4812374391436, 4036.3340048891896, 4155.792210389483, 4164.916701665949, 3571.821408929543, 3575.8748443804343, 3580.982095089534, 3587.264610754789, 3590.7486475946694, 4698.02682873194, 4872.4883887192045, 5324.494173053554, 5358.812441564526, 5361.463853614608, 3830.655241028293, 3842.2315536892734, 4062.7765472898977, 4508.977455112704, 4644.837430689943, 4442.955570444319, 5742.885142297131, 5759.251297331362, 5796.913046304308, 5956.553258505586, 3955.2287303975736, 3966.9008274793073, 3974.4435778990965, 3976.005998500375, 3994.3609022556543, 5281.841544753664, 5713.628614140109, 6111.638598770703, 6112.877742445171, 6131.785387511461, 3300.669933006728, 3556.3065202285306, 3878.825452854628, 3987.381955796798, 3994.1013272013565, 4067.864371205141, 4103.671706263492, 4120.75275483472, 4123.865936867325, 4128.545859955402, 3982.287616325008, 4382.5099466181655, 4780.282957556395, 4790.592799612003, 4803.927941080887, 4545.36364908913, 5066.189409694432, 5393.784248630011, 5840.532757379399, 5855.502282305966, 1185.958491452795, 1366.7403193393222, 1369.1921766157816, 1370.1641998381178, 1370.1984339161702, 621.7264403662374, 757.83706503101, 810.6473683947502, 829.0, 840.3025488844245, 4899.289603007615, 4957.578605818546, 5013.799448022039, 5103.622022054099, 5498.725063746797, 6019.849503762441, 6027.728752206073, 6028.613418913675, 6044.274687037584, 6081.422264884829, 4572.7942242599165, 5240.960243059017, 5431.21247419122, 5492.5331346835965, 5538.335399752055, 2928.677845437, 3126.7498600111935, 3170.7621928355466, 3171.254755132542, 3173.6191657000845, 5608.747606357642, 6243.0, 6257.936150854366, 6269.62382257066, 6270.561060725706, 5328.626996110235, 5653.575981801381, 6052.939470605325, 6055.757769689163, 6077.696115194222, 6202.34875338092, 6228.45322914266, 6241.065269766387, 6261.780837670751, 6293.96853015732, 5701.144828275792, 5768.278965129359, 5924.081767326072, 6003.998000999501, 6036.3058248302, 4838.661293709547, 5041.823536176216, 5849.883002340014, 5952.791652292149, 5958.553108516792, 5288.920666189933, 5701.914471282851, 6087.086936959491, 6201.193844800147, 6245.781397651061, 3155.7002084801907, 3685.281370132798, 3865.671417929395, 3918.588548202512, 3919.4316824059783, 5096.847094587169, 5972.402759723993, 5993.280806303272, 5997.850053748517, 6015.699215039143, 5444.161865884463, 5786.855328616818, 5814.476697097281, 5833.737481813244, 5859.794907178229, 4607.424071991001, 5037.874053148701, 5738.282714660668, 5741.650712082574, 5773.3072031356505, 5012.571417146096, 5012.949870501394, 5195.68825870449, 5394.460553944574, 5680.346760122638, 4726.763661816826, 5411.0801163803035, 5447.700376479332, 5505.532029777513, 5783.421657834183, 5693.373728889736, 5694.857628559319, 5719.771209151588, 5738.053221218453, 5858.7070646466655], "value": 4156.137312152589, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.5840116729379, 696.6447111972896, 706.766766966901, 742.3430264216174, 745.7203548669249, 13954.302284885753, 14976.326065327059, 14991.925040374783, 15008.39966401344, 15082.0, 13428.66428339292, 13476.797848032287, 13507.189568625889, 13529.59411217665, 13548.525801596947, 655.3839390972471, 685.9314068593151, 808.4987307869129, 829.904560975488, 842.9409941304111, 838.7106448275366, 849.6261644876265, 850.196564246787, 850.217799624344, 854.8461276970143, 854.5727136431784, 908.2416182487598, 912.4434095201922, 913.0823522360018, 913.7715571107224, 802.2418814220564, 858.2147335188326, 874.9650013999433, 875.0505701314082, 894.5795476126243, 541.6506353402073, 583.0, 598.8083813179784, 678.7285085965603, 685.4208194075998, 12249.387530623433, 13845.0, 14225.573232803032, 14229.43082276718, 14269.429222831075, 13108.27904465254, 13821.79267310991, 14402.351894164787, 14403.71192576153, 14815.18516481593, 10409.646745923057, 11392.886071139348, 11760.58837940668, 11765.470553825098, 11806.763864722743, 3701.8889433317045, 3736.1220113273357, 3736.5516138063335, 3762.5861155272896, 3822.9044273893373, 114690.0, 115033.0, 115058.0, 115138.0, 117728.82271177265, 8833.779155521164, 9031.45811251327, 9189.586468608928, 9194.356395052282, 9279.118483744034, 20612.58774824495, 20693.89653051725, 20834.79165208359, 20966.266180683604, 21049.789502104784, 7386.409087273005, 7419.777406677808, 7476.401887848959, 7479.401647868158, 7623.161452240249, 4198.832046718158, 4207.179599978036, 4232.68254880879, 4268.509121451011, 4375.321825117048, 3383.9661603384143, 3487.3897068013, 3493.6681015303507, 3495.8601655933476, 3744.194998075427, 703.1000319590829, 708.553611224925, 712.2521352579874, 712.8217945513621, 721.9278072192739, 558.5029323901731, 587.6297932302622, 597.2683462758087, 605.0193833951334, 633.5818359882455, 7822.804429889298, 8608.440451370641, 8910.465372077697, 9246.491442970566, 9311.255099592016, 507.23154421052135, 523.9730128947306, 630.3790766095392, 633.3792882974618, 701.5650296816007, 4304.182205380967, 4694.647901407408, 4993.7503124843615, 5065.0, 5106.387233532, 121364.0, 149528.07550792038, 158285.0, 161063.0, 164470.0, 131689.0, 132277.0, 140016.0, 141286.0, 142838.57421386876, 115520.0, 116310.0, 117846.4107679456, 118857.0, 119548.40225798816, 9680.951595241979, 9773.951130244302, 9789.265805064506, 9800.705978820646, 9841.19525935105, 64038.67980660253, 64092.03861942168, 64103.0, 64133.0, 64408.677956609914, 88341.0, 99597.00402995877, 99958.0, 101318.49340753249, 101418.492907535, 12945.482180712666, 13175.736485270432, 13205.537806176737, 13519.797203042159, 13822.101563398346, 14808.925955370154, 14861.331240094005, 14992.175430351435, 15072.924635376752, 15600.453984110502, 13890.722185556435, 13975.231362275172, 13990.0, 14052.0, 14493.92753036228, 11372.715682108013, 13186.340682965621, 13290.107024770994, 13488.258145802076, 13725.970552208824, 13191.538296159588, 13230.69232691827, 13293.401796919356, 13803.447862085806, 14216.147031178163, 11334.603288885179, 11379.564369052005, 11386.259893106922, 11526.769464610494, 11713.062954963752, 9500.95249523748, 9828.705138845846, 9973.50132493387, 10694.358338499715, 11572.94213528927], "value": 7714.916312806001, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 115.0, 120.0, 117.0, 118.0, 116.0, 117.0, 116.0, 134.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 136.0, 95.0, 157.0, 142.0, 145.0, 100.0, 108.0, 190.0, 146.0], "value": 137.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 204.0, 227.0, 201.0, 183.0, 193.0, 192.0, 193.0, 203.0, 193.0], "value": 199.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 60.0, 59.0, 82.0, 58.0, 59.0, 57.0, 59.0, 77.0, 58.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 117.0, 102.0, 100.0, 74.0, 89.0, 69.0, 71.0, 87.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 89.0, 86.0, 90.0, 86.0, 91.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 116.0, 95.0, 117.0, 152.0, 120.0, 150.0, 152.0, 94.0], "value": 118.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 109.0, 121.0, 137.0, 117.0, 143.0, 144.0, 149.0, 144.0], "value": 123.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 56.0, 57.0, 59.0, 68.0, 88.0, 88.0, 57.0, 58.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 78.0, 78.0, 76.0, 69.0, 74.0, 71.0, 71.0, 64.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 106.0, 101.0, 120.0, 107.0, 80.0, 80.0, 87.0, 107.0, 108.0], "value": 100.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 148.0, 146.0, 147.0, 145.0, 148.0, 157.0, 163.0, 206.0, 151.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 173.0, 178.0, 184.0, 173.0, 173.0, 237.0, 159.0, 218.0], "value": 185.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 72.0, 70.0, 71.0, 69.0, 68.0, 70.0, 68.0, 67.0], "value": 69.9, "unit": "score"}]}]} 05:30:41 INFO - Return code: 0 05:30:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:30:41 ERROR - # TBPL SUCCESS # 05:30:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:30:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:30:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:30:41 INFO - 2016-10-13 05:30:41.641 screenresolution[1675:10446] starting screenresolution argv=screenresolution get 05:30:41 INFO - 2016-10-13 05:30:41.644 screenresolution[1675:10446] Display 0: 1600x1200x32@60 05:30:41 INFO - 2016-10-13 05:30:41.650 screenresolution[1676:10449] starting screenresolution argv=screenresolution list 05:30:41 INFO - Available Modes on Display 0 05:30:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:30:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:30:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:30:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:30:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:30:41 INFO - Intel Iris: 05:30:41 INFO - Chipset Model: Intel Iris 05:30:41 INFO - Type: GPU 05:30:41 INFO - Bus: Built-In 05:30:41 INFO - VRAM (Dynamic, Max): 1536 MB 05:30:41 INFO - Vendor: Intel (0x8086) 05:30:41 INFO - Device ID: 0x0a2e 05:30:41 INFO - Revision ID: 0x0009 05:30:41 INFO - Displays: 05:30:41 INFO - Display: 05:30:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:30:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:30:41 INFO - Display Serial Number: 1600x1200 60 05:30:41 INFO - Main Display: Yes 05:30:41 INFO - Mirror: Off 05:30:41 INFO - Online: Yes 05:30:41 INFO - Rotation: Supported 05:30:41 INFO - Return code: 0 05:30:41 INFO - Running post-action listener: _resource_record_post_action 05:30:41 INFO - [mozharness: 2016-10-13 12:30:41.886447Z] Finished run-tests step (success) 05:30:41 INFO - Running post-run listener: _resource_record_post_run 05:30:41 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38304256; Write bytes: 711901696; Read time: 251; Write time: 2004 05:30:41 INFO - TinderboxPrint: CPU usage
54.5% 05:30:41 INFO - TinderboxPrint: I/O read bytes / time
38,304,256 / 251 05:30:41 INFO - TinderboxPrint: I/O write bytes / time
711,901,696 / 2,004 05:30:41 INFO - TinderboxPrint: CPU idle
2,206.4 (45.5%) 05:30:41 INFO - TinderboxPrint: CPU system
979.4 (20.2%) 05:30:41 INFO - TinderboxPrint: CPU user
1,659.9 (34.3%) 05:30:41 INFO - TinderboxPrint: Swap in / out
445,632,512 / 0 05:30:41 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 400650752; Write bytes: 390342144; Read time: 29734; Write time: 789 05:30:41 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 31265280; Write bytes: 300469248; Read time: 187; Write time: 1161 05:30:42 INFO - Running post-run listener: _upload_blobber_files 05:30:42 INFO - Blob upload gear active. 05:30:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:30:42 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 05:30:42 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'] 05:30:42 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 05:30:42 INFO - (blobuploader) - INFO - Open directory for files ... 05:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:30:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:43 INFO - (blobuploader) - INFO - Done attempting. 05:30:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:30:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:44 INFO - (blobuploader) - INFO - Done attempting. 05:30:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:30:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:30:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:30:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:30:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:30:46 INFO - (blobuploader) - INFO - Done attempting. 05:30:46 INFO - (blobuploader) - INFO - Iteration through files over. 05:30:46 INFO - Return code: 0 05:30:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:30:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:30:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e0345801be7e0d57754100d52a3fd67148d16c10cbd3bcfd50bfbfae84f27d608481bc4ac835610eda13feca797ebb300bcc199ced4bdd75dab283558a71f8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8053d6fc1bd4ebd8a7922d50f00240a40dcff4c5db7b67f5502798dcd7675fb87c592633352ae5bc5e5d1300241b91a0a7490f705fd46537fb1a2d3010c37eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac509ab9f6335aa85af3854b0b87587cc895d4bbbd626cd2dc3c6b4669fdea0ee8bd1a8180e391e8572a591205701832bca5ecee580a92c2847dde4857053533"} 05:30:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:30:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:30:46 INFO - Contents: 05:30:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e0345801be7e0d57754100d52a3fd67148d16c10cbd3bcfd50bfbfae84f27d608481bc4ac835610eda13feca797ebb300bcc199ced4bdd75dab283558a71f8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8053d6fc1bd4ebd8a7922d50f00240a40dcff4c5db7b67f5502798dcd7675fb87c592633352ae5bc5e5d1300241b91a0a7490f705fd46537fb1a2d3010c37eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac509ab9f6335aa85af3854b0b87587cc895d4bbbd626cd2dc3c6b4669fdea0ee8bd1a8180e391e8572a591205701832bca5ecee580a92c2847dde4857053533"} 05:30:46 INFO - Running post-run listener: copy_logs_to_upload_dir 05:30:46 INFO - Copying logs to upload dir... 05:30:46 INFO - mkdir: /builds/slave/test/build/upload/logs 05:30:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.328877 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-10-13 05:30:46.667139) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 05:30:46.670322) ========= 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.FJiWOwsK17/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.03lDYAEi1Z/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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/3e0345801be7e0d57754100d52a3fd67148d16c10cbd3bcfd50bfbfae84f27d608481bc4ac835610eda13feca797ebb300bcc199ced4bdd75dab283558a71f8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8053d6fc1bd4ebd8a7922d50f00240a40dcff4c5db7b67f5502798dcd7675fb87c592633352ae5bc5e5d1300241b91a0a7490f705fd46537fb1a2d3010c37eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac509ab9f6335aa85af3854b0b87587cc895d4bbbd626cd2dc3c6b4669fdea0ee8bd1a8180e391e8572a591205701832bca5ecee580a92c2847dde4857053533"} build_url:https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010797 build_url: 'https://queue.taskcluster.net/v1/task/NrHNfP3pT4-nwpDr_CkIiQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3e0345801be7e0d57754100d52a3fd67148d16c10cbd3bcfd50bfbfae84f27d608481bc4ac835610eda13feca797ebb300bcc199ced4bdd75dab283558a71f8c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a8053d6fc1bd4ebd8a7922d50f00240a40dcff4c5db7b67f5502798dcd7675fb87c592633352ae5bc5e5d1300241b91a0a7490f705fd46537fb1a2d3010c37eb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ac509ab9f6335aa85af3854b0b87587cc895d4bbbd626cd2dc3c6b4669fdea0ee8bd1a8180e391e8572a591205701832bca5ecee580a92c2847dde4857053533"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 05:30:46.702545) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:30:46.702910) ========= 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.FJiWOwsK17/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.03lDYAEi1Z/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.005144 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:30:46.751962) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 05:30:46.752275) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 05:30:46.752595) ========= ========= Total master_lag: 0.17 =========