builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0304 starttime: 1475851057.9 results: success (0) buildid: 20161007063359 builduid: 3772910b802d4aa08df7745324d27dcb revision: 1de5e07bed6601d2647e88ed2690b3f30418d8aa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:37.900593) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:37.901031) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:37.901316) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:37.925315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:37.925639) ========= 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.vgWhXrZVU5/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.kGlU15p77O/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-07 07:37:37-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-10-07 07:37:38 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095286 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:38.043172) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:38.043505) ========= 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.vgWhXrZVU5/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.kGlU15p77O/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.035165 ========= master_lag: 0.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:38.335497) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-07 07:37:38.335875) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1de5e07bed6601d2647e88ed2690b3f30418d8aa --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 1de5e07bed6601d2647e88ed2690b3f30418d8aa --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vgWhXrZVU5/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.kGlU15p77O/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-07 07:37:38,416 truncating revision to first 12 chars 2016-10-07 07:37:38,416 Setting DEBUG logging. 2016-10-07 07:37:38,416 attempt 1/10 2016-10-07 07:37:38,416 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1de5e07bed66?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-07 07:37:39,665 unpacking tar archive at: mozilla-central-1de5e07bed66/testing/mozharness/ program finished with exit code 0 elapsedTime=1.509952 ========= master_lag: 1.76 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-07 07:37:41.609430) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:41.609744) ========= script_repo_revision: 1de5e07bed6601d2647e88ed2690b3f30418d8aa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:41.610140) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:41.610414) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-07 07:37:42.550703) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-07 07:37:42.551165) ========= /tools/buildbot/bin/python 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', '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.vgWhXrZVU5/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.kGlU15p77O/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:37:42 INFO - MultiFileLogger online at 20161007 07:37:42 in /builds/slave/test 07:37:42 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 07:37:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:37:42 INFO - {'append_to_log': False, 07:37:42 INFO - 'base_work_dir': '/builds/slave/test', 07:37:42 INFO - 'blob_upload_branch': 'Firefox', 07:37:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:37:42 INFO - 'branch': 'Firefox', 07:37:42 INFO - 'buildbot_json_path': 'buildprops.json', 07:37:42 INFO - 'config_files': ('talos/mac_config.py',), 07:37:42 INFO - 'default_actions': ('clobber', 07:37:42 INFO - 'read-buildbot-config', 07:37:42 INFO - 'download-and-extract', 07:37:42 INFO - 'populate-webroot', 07:37:42 INFO - 'create-virtualenv', 07:37:42 INFO - 'install', 07:37:42 INFO - 'run-tests'), 07:37:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:37:42 INFO - 'download_minidump_stackwalk': True, 07:37:42 INFO - 'download_symbols': 'ondemand', 07:37:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:37:42 INFO - 'tooltool.py': '/tools/tooltool.py', 07:37:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:37:42 INFO - '/tools/misc-python/virtualenv.py')}, 07:37:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:37:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:37:42 INFO - 'installer_path': 'installer.exe', 07:37:42 INFO - 'log_level': 'info', 07:37:42 INFO - 'log_name': 'talos', 07:37:42 INFO - 'log_to_console': True, 07:37:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:37:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:37:42 INFO - 'opt_config_files': (), 07:37:42 INFO - 'pip_index': False, 07:37:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:37:42 INFO - 'cmd': ('bash', 07:37:42 INFO - '-c', 07:37:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:37:42 INFO - 'enabled': True, 07:37:42 INFO - 'halt_on_failure': False, 07:37:42 INFO - 'name': 'check_screen_resolution'},), 07:37:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:37:42 INFO - 'cmd': ('bash', 07:37:42 INFO - '-c', 07:37:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:37:42 INFO - 'enabled': True, 07:37:42 INFO - 'halt_on_failure': False, 07:37:42 INFO - 'name': 'check_screen_resolution'},), 07:37:42 INFO - 'run_cmd_checks_enabled': True, 07:37:42 INFO - 'sps_profile': False, 07:37:42 INFO - 'sps_profile_interval': 0, 07:37:42 INFO - 'suite': 'dromaeojs-e10s', 07:37:42 INFO - 'system_bits': '32', 07:37:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:37:42 INFO - 'title': 't-yosemite-r7-0304', 07:37:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:37:42 INFO - 'use_talos_json': True, 07:37:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:37:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:37:42 INFO - 'work_dir': 'build'} 07:37:42 INFO - [mozharness: 2016-10-07 14:37:42.749366Z] Running clobber step. 07:37:42 INFO - Running pre-action listener: _resource_record_pre_action 07:37:42 INFO - Running main action method: clobber 07:37:42 INFO - rmtree: /builds/slave/test/build 07:37:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:37:44 INFO - Running post-action listener: _resource_record_post_action 07:37:44 INFO - [mozharness: 2016-10-07 14:37:44.960920Z] Finished clobber step (success) 07:37:44 INFO - [mozharness: 2016-10-07 14:37:44.961133Z] Running read-buildbot-config step. 07:37:44 INFO - Running pre-action listener: _resource_record_pre_action 07:37:44 INFO - Running main action method: read_buildbot_config 07:37:44 INFO - Using buildbot properties: 07:37:44 INFO - { 07:37:44 INFO - "project": "", 07:37:44 INFO - "product": "firefox", 07:37:44 INFO - "script_repo_revision": "production", 07:37:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:37:44 INFO - "repository": "", 07:37:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:37:44 INFO - "buildid": "20161007063359", 07:37:44 INFO - "pgo_build": "False", 07:37:44 INFO - "basedir": "/builds/slave/test", 07:37:44 INFO - "buildnumber": 12, 07:37:44 INFO - "slavename": "t-yosemite-r7-0304", 07:37:44 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:37:44 INFO - "platform": "yosemite_r7", 07:37:44 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:37:44 INFO - "branch": "mozilla-central", 07:37:44 INFO - "repo_path": "mozilla-central", 07:37:44 INFO - "revision": "1de5e07bed6601d2647e88ed2690b3f30418d8aa", 07:37:44 INFO - "stage_platform": "macosx64", 07:37:44 INFO - "builduid": "3772910b802d4aa08df7745324d27dcb", 07:37:44 INFO - "slavebuilddir": "test" 07:37:44 INFO - } 07:37:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:37:44 INFO - Running post-action listener: _resource_record_post_action 07:37:44 INFO - [mozharness: 2016-10-07 14:37:44.966842Z] Finished read-buildbot-config step (success) 07:37:44 INFO - [mozharness: 2016-10-07 14:37:44.967027Z] Running download-and-extract step. 07:37:44 INFO - Running pre-action listener: _resource_record_pre_action 07:37:44 INFO - Running main action method: download_and_extract 07:37:44 INFO - mkdir: /builds/slave/test/build/tests 07:37:44 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')]} 07:37:44 INFO - trying https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:37:44 INFO - Downloading https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/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 07:37:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/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 07:37:46 INFO - Downloaded 1199 bytes. 07:37:46 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:37:46 INFO - Using the following test package requirements: 07:37:46 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:37:46 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:37:46 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:37:46 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:37:46 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:37:46 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:37:46 INFO - u'jsshell-mac.zip'], 07:37:46 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:37:46 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:37:46 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:37:46 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:37:46 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:37:46 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:37:46 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:37:46 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:37:46 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:37:46 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:37:46 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:37:46 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:37:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:37:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:37:46 INFO - Fetch https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:37:46 INFO - Http code: 200 07:37:46 INFO - via: 1.1 68e4011ca1c00bec92bb202e1ddce131.cloudfront.net (CloudFront) 07:37:46 INFO - x-amz-cf-id: l0YwhmKX2mdJzpgKDiDFWk9RYCVgQzxbASHtWG3YfRfD3hnqGvrfeQ== 07:37:46 INFO - x-amz-version-id: 4AlI3s0la8z0WJTLZ1gpXeQQmfLOqfkt 07:37:47 INFO - Expected file size: 16482446 07:37:47 INFO - Obtained file size: 16482446 07:37:53 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 07:37:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:37:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:37:53 INFO - Fetch https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:37:54 INFO - Http code: 200 07:37:54 INFO - via: 1.1 ff09df097f823b2834408d17e9779d62.cloudfront.net (CloudFront) 07:37:54 INFO - x-amz-cf-id: mVQrfgXMgLqLxenTtF3m3EMSQtftUmBrs3hwPdBAHnDQSeuuvTCwmg== 07:37:54 INFO - x-amz-version-id: 4AlI3s0la8z0WJTLZ1gpXeQQmfLOqfkt 07:37:54 INFO - Expected file size: 16482446 07:37:54 INFO - Obtained file size: 16482446 07:38:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:38:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:38:00 INFO - Fetch https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:38:01 INFO - Http code: 200 07:38:01 INFO - via: 1.1 3d3d633d266d05d90a4eea7a6a59b514.cloudfront.net (CloudFront) 07:38:01 INFO - x-amz-cf-id: xBdKrvgo3l54KsFPbPfTTTVXPUbCiZMgYOltVxBP3fy9LQSkrgVVzg== 07:38:01 INFO - x-amz-version-id: 8BLmwPYaObV6wvtHkqtTobN0iHV4KK0F 07:38:02 INFO - Expected file size: 14409006 07:38:02 INFO - Obtained file size: 14409006 07:38: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')]} 07:38:02 INFO - trying https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:38:02 INFO - Downloading https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:38:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/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 07:38:06 INFO - Downloaded 111752786 bytes. 07:38:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:38:06 INFO - mkdir: /builds/slave/test/properties 07:38:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:38:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:38:06 INFO - Contents: 07:38:06 INFO - build_url:https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:38:07 INFO - Running post-action listener: _resource_record_post_action 07:38:07 INFO - Running post-action listener: set_extra_try_arguments 07:38:07 INFO - [mozharness: 2016-10-07 14:38:07.303822Z] Finished download-and-extract step (success) 07:38:07 INFO - [mozharness: 2016-10-07 14:38:07.303953Z] Running populate-webroot step. 07:38:07 INFO - Running pre-action listener: _resource_record_pre_action 07:38:07 INFO - Running main action method: populate_webroot 07:38:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:38:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:38:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:38:07 INFO - '--tppagecycles', 07:38:07 INFO - '7'], 07:38:07 INFO - 'tests': ['tsvgm']}}, 07:38:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:38:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:38:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:38:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:38:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:07 INFO - 'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:38:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:38:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:07 INFO - 'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['damp', 'tps']}, 07:38:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:07 INFO - 'tests': ['damp', 'tps']}, 07:38:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['dromaeo_dom']}, 07:38:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:38:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['basic_compositor_video']}, 07:38:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:38:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['a11yr', 07:38:07 INFO - 'ts_paint', 07:38:07 INFO - 'tpaint', 07:38:07 INFO - 'sessionrestore', 07:38:07 INFO - 'sessionrestore_no_auto_restore', 07:38:07 INFO - 'tabpaint']}, 07:38:07 INFO - 'other-e10s': {'tests': ['a11yr', 07:38:07 INFO - 'ts_paint', 07:38:07 INFO - 'tpaint', 07:38:07 INFO - 'sessionrestore', 07:38:07 INFO - 'sessionrestore_no_auto_restore', 07:38:07 INFO - 'tabpaint']}, 07:38:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:38:07 INFO - 'ts_paint', 07:38:07 INFO - 'tpaint', 07:38:07 INFO - 'sessionrestore', 07:38:07 INFO - 'sessionrestore_no_auto_restore', 07:38:07 INFO - 'tabpaint']}, 07:38:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:38:07 INFO - 'ts_paint', 07:38:07 INFO - 'tpaint', 07:38:07 INFO - 'sessionrestore', 07:38:07 INFO - 'sessionrestore_no_auto_restore', 07:38:07 INFO - 'tabpaint']}, 07:38:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['a11yr', 07:38:07 INFO - 'ts_paint', 07:38:07 INFO - 'tpaint', 07:38:07 INFO - 'sessionrestore', 07:38:07 INFO - 'sessionrestore_no_auto_restore', 07:38:07 INFO - 'tabpaint']}, 07:38:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['a11yr', 07:38:07 INFO - 'ts_paint', 07:38:07 INFO - 'tpaint', 07:38:07 INFO - 'sessionrestore', 07:38:07 INFO - 'sessionrestore_no_auto_restore', 07:38:07 INFO - 'tabpaint']}, 07:38:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['tsvgx', 07:38:07 INFO - 'tsvgr_opacity', 07:38:07 INFO - 'tart', 07:38:07 INFO - 'tscrollx', 07:38:07 INFO - 'cart']}, 07:38:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:38:07 INFO - 'tsvgr_opacity', 07:38:07 INFO - 'tart', 07:38:07 INFO - 'tscrollx', 07:38:07 INFO - 'cart']}, 07:38:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:07 INFO - 'talos_options': ['--disable-e10s'], 07:38:07 INFO - 'tests': ['tp5o']}, 07:38:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:07 INFO - 'tests': ['tp5o']}, 07:38:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:07 INFO - 'talos_options': ['--disable-e10s', 07:38:07 INFO - '--xperf_path', 07:38:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:38:07 INFO - 'tests': ['tp5n']}, 07:38:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:38:07 INFO - 'talos_options': ['--xperf_path', 07:38:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:38:07 INFO - 'tests': ['tp5n']}}, 07:38:07 INFO - 'talos.zip': {'path': '', 07:38:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:38:07 INFO - Running post-action listener: _resource_record_post_action 07:38:07 INFO - [mozharness: 2016-10-07 14:38:07.322289Z] Finished populate-webroot step (success) 07:38:07 INFO - [mozharness: 2016-10-07 14:38:07.322408Z] Running create-virtualenv step. 07:38:07 INFO - Running pre-action listener: _resource_record_pre_action 07:38:07 INFO - Running main action method: create_virtualenv 07:38:07 INFO - Creating virtualenv /builds/slave/test/build/venv 07:38:07 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 07:38:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:38:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:38:07 INFO - Using real prefix '/tools/python27' 07:38:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:38:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:38:11 INFO - Installing pip.................done. 07:38:11 INFO - Return code: 0 07:38:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:38:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:38:11 INFO - Reading from file tmpfile_stdout 07:38:11 INFO - Output received: 07:38:11 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) 07:38:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:38:11 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')]} 07:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:11 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')]} 07:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:11 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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:11 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 07:38:11 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 07:38:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:11 INFO - 'HOME': '/Users/cltbld', 07:38:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:11 INFO - 'LOGNAME': 'cltbld', 07:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:11 INFO - 'MOZ_NO_REMOTE': '1', 07:38:11 INFO - 'NO_EM_RESTART': '1', 07:38:11 INFO - 'PAGER': '/bin/cat', 07:38:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:11 INFO - 'PWD': '/builds/slave/test', 07:38:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:11 INFO - 'SHELL': '/bin/bash', 07:38:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:11 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:11 INFO - 'USER': 'cltbld', 07:38:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:11 INFO - 'XPC_FLAGS': '0x0', 07:38:11 INFO - 'XPC_SERVICE_NAME': '0', 07:38:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:11 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 07:38:11 INFO - Cleaning up... 07:38:11 INFO - Return code: 0 07:38:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:38:11 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')]} 07:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:11 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')]} 07:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:11 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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:11 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 07:38:11 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 07:38:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:11 INFO - 'HOME': '/Users/cltbld', 07:38:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:11 INFO - 'LOGNAME': 'cltbld', 07:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:11 INFO - 'MOZ_NO_REMOTE': '1', 07:38:11 INFO - 'NO_EM_RESTART': '1', 07:38:11 INFO - 'PAGER': '/bin/cat', 07:38:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:11 INFO - 'PWD': '/builds/slave/test', 07:38:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:11 INFO - 'SHELL': '/bin/bash', 07:38:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:11 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:11 INFO - 'USER': 'cltbld', 07:38:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:11 INFO - 'XPC_FLAGS': '0x0', 07:38:11 INFO - 'XPC_SERVICE_NAME': '0', 07:38:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:11 INFO - Downloading/unpacking psutil>=3.1.1 07:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:38:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:38:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:38:15 INFO - Installing collected packages: psutil 07:38:15 INFO - Running setup.py install for psutil 07:38:15 INFO - building 'psutil._psutil_osx' extension 07:38:15 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 07:38:16 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 07:38:16 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 07:38:16 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 07:38:16 INFO - building 'psutil._psutil_posix' extension 07:38:16 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 07:38:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:38:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:38:16 INFO - ^ 07:38:16 INFO - 1 warning generated. 07:38:16 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 07:38:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:38:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:38:16 INFO - Successfully installed psutil 07:38:16 INFO - Cleaning up... 07:38:16 INFO - Return code: 0 07:38:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:38:16 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')]} 07:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:16 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')]} 07:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:16 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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:16 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 07:38:16 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 07:38:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:16 INFO - 'HOME': '/Users/cltbld', 07:38:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:16 INFO - 'LOGNAME': 'cltbld', 07:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:16 INFO - 'MOZ_NO_REMOTE': '1', 07:38:16 INFO - 'NO_EM_RESTART': '1', 07:38:16 INFO - 'PAGER': '/bin/cat', 07:38:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:16 INFO - 'PWD': '/builds/slave/test', 07:38:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:16 INFO - 'SHELL': '/bin/bash', 07:38:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:16 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:16 INFO - 'USER': 'cltbld', 07:38:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:16 INFO - 'XPC_FLAGS': '0x0', 07:38:16 INFO - 'XPC_SERVICE_NAME': '0', 07:38:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:38: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 07:38: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 07:38: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 07:38: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 07:38: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 07:38: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 07:38:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:38:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:38:19 INFO - Installing collected packages: mozsystemmonitor 07:38:19 INFO - Running setup.py install for mozsystemmonitor 07:38:19 INFO - Successfully installed mozsystemmonitor 07:38:19 INFO - Cleaning up... 07:38:19 INFO - Return code: 0 07:38:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:38:19 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')]} 07:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:19 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')]} 07:38:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:19 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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:19 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 07:38:19 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 07:38:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:19 INFO - 'HOME': '/Users/cltbld', 07:38:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:19 INFO - 'LOGNAME': 'cltbld', 07:38:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:19 INFO - 'MOZ_NO_REMOTE': '1', 07:38:19 INFO - 'NO_EM_RESTART': '1', 07:38:19 INFO - 'PAGER': '/bin/cat', 07:38:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:19 INFO - 'PWD': '/builds/slave/test', 07:38:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:19 INFO - 'SHELL': '/bin/bash', 07:38:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:19 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:19 INFO - 'USER': 'cltbld', 07:38:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:19 INFO - 'XPC_FLAGS': '0x0', 07:38:19 INFO - 'XPC_SERVICE_NAME': '0', 07:38:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:19 INFO - Downloading/unpacking jsonschema==2.5.1 07:38:19 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 07:38:19 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 07:38:19 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 07:38:19 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 07:38:19 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 07:38:19 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 07:38:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:38:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:22 INFO - Downloading functools32-3.2.3-2.tar.gz 07:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:38:22 INFO - warning: no files found matching '*.txt' 07:38:22 INFO - no previously-included directories found matching 'build' 07:38:22 INFO - no previously-included directories found matching 'dist' 07:38:22 INFO - no previously-included directories found matching '.git*' 07:38:22 INFO - Installing collected packages: jsonschema, functools32 07:38:22 INFO - Running setup.py install for functools32 07:38:23 INFO - warning: no files found matching '*.txt' 07:38:23 INFO - no previously-included directories found matching 'build' 07:38:23 INFO - no previously-included directories found matching 'dist' 07:38:23 INFO - no previously-included directories found matching '.git*' 07:38:23 INFO - Successfully installed jsonschema functools32 07:38:23 INFO - Cleaning up... 07:38:23 INFO - Return code: 0 07:38:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:38: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')]} 07:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38: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')]} 07:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:23 INFO - 'HOME': '/Users/cltbld', 07:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:23 INFO - 'LOGNAME': 'cltbld', 07:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:23 INFO - 'MOZ_NO_REMOTE': '1', 07:38:23 INFO - 'NO_EM_RESTART': '1', 07:38:23 INFO - 'PAGER': '/bin/cat', 07:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:23 INFO - 'PWD': '/builds/slave/test', 07:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:23 INFO - 'SHELL': '/bin/bash', 07:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:23 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:23 INFO - 'USER': 'cltbld', 07:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:23 INFO - 'XPC_FLAGS': '0x0', 07:38:23 INFO - 'XPC_SERVICE_NAME': '0', 07:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:38:23 INFO - Cleaning up... 07:38:23 INFO - Return code: 0 07:38:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:38: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')]} 07:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38: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')]} 07:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:23 INFO - 'HOME': '/Users/cltbld', 07:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:23 INFO - 'LOGNAME': 'cltbld', 07:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:23 INFO - 'MOZ_NO_REMOTE': '1', 07:38:23 INFO - 'NO_EM_RESTART': '1', 07:38:23 INFO - 'PAGER': '/bin/cat', 07:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:23 INFO - 'PWD': '/builds/slave/test', 07:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:23 INFO - 'SHELL': '/bin/bash', 07:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:23 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:23 INFO - 'USER': 'cltbld', 07:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:23 INFO - 'XPC_FLAGS': '0x0', 07:38:23 INFO - 'XPC_SERVICE_NAME': '0', 07:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:23 INFO - Downloading/unpacking blobuploader==1.2.4 07:38:23 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 07:38:23 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 07:38:23 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 07:38:23 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 07:38:23 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 07:38:23 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 07:38:26 INFO - Downloading blobuploader-1.2.4.tar.gz 07:38:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:38:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:38:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:27 INFO - Downloading docopt-0.6.1.tar.gz 07:38:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:38:27 INFO - Installing collected packages: blobuploader, requests, docopt 07:38:27 INFO - Running setup.py install for blobuploader 07:38:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:38:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:38:27 INFO - Running setup.py install for requests 07:38:28 INFO - Running setup.py install for docopt 07:38:28 INFO - Successfully installed blobuploader requests docopt 07:38:28 INFO - Cleaning up... 07:38:28 INFO - Return code: 0 07:38:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:38: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')]} 07:38:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38: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')]} 07:38:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:28 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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:28 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 07:38:28 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 07:38:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:28 INFO - 'HOME': '/Users/cltbld', 07:38:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:28 INFO - 'LOGNAME': 'cltbld', 07:38:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:28 INFO - 'MOZ_NO_REMOTE': '1', 07:38:28 INFO - 'NO_EM_RESTART': '1', 07:38:28 INFO - 'PAGER': '/bin/cat', 07:38:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:28 INFO - 'PWD': '/builds/slave/test', 07:38:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:28 INFO - 'SHELL': '/bin/bash', 07:38:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:28 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:28 INFO - 'USER': 'cltbld', 07:38:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:28 INFO - 'XPC_FLAGS': '0x0', 07:38:28 INFO - 'XPC_SERVICE_NAME': '0', 07:38:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:38:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-adA7Aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:38:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-6DazFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:38:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Elu28U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:38:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-E4xozq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:38:28 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-V74veH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-b69Qh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-q7PH8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-bzSvGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-ZFbdxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-KekGCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-2fhQHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-y3wZCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-wzPJDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-pxB6oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:38:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:29 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-MTDlrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:38:30 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-WafswQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:38:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:38:30 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-3U0GVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:38:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:38:30 INFO - Running setup.py install for manifestparser 07:38:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:38:30 INFO - Running setup.py install for mozcrash 07:38:30 INFO - Running setup.py install for mozdebug 07:38:30 INFO - Running setup.py install for mozdevice 07:38:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:38:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:38:30 INFO - Running setup.py install for mozfile 07:38:31 INFO - Running setup.py install for mozhttpd 07:38:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:38:31 INFO - Running setup.py install for mozinfo 07:38:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:38:31 INFO - Running setup.py install for mozInstall 07:38:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:38:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:38:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:38:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:38:31 INFO - Running setup.py install for mozleak 07:38:31 INFO - Running setup.py install for mozlog 07:38:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:38:31 INFO - Running setup.py install for moznetwork 07:38:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:38:31 INFO - Running setup.py install for mozprocess 07:38:32 INFO - Running setup.py install for mozprofile 07:38:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:38:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:38:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:38:32 INFO - Running setup.py install for mozrunner 07:38:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:38:32 INFO - Running setup.py install for mozscreenshot 07:38:32 INFO - Running setup.py install for moztest 07:38:32 INFO - Running setup.py install for mozversion 07:38:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:38:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:38:32 INFO - Cleaning up... 07:38:32 INFO - Return code: 0 07:38:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:38:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:38:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:38:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:38:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:38:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:32 INFO - 'HOME': '/Users/cltbld', 07:38:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:32 INFO - 'LOGNAME': 'cltbld', 07:38:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:32 INFO - 'MOZ_NO_REMOTE': '1', 07:38:32 INFO - 'NO_EM_RESTART': '1', 07:38:32 INFO - 'PAGER': '/bin/cat', 07:38:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:32 INFO - 'PWD': '/builds/slave/test', 07:38:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:32 INFO - 'SHELL': '/bin/bash', 07:38:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:32 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:32 INFO - 'USER': 'cltbld', 07:38:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:32 INFO - 'XPC_FLAGS': '0x0', 07:38:32 INFO - 'XPC_SERVICE_NAME': '0', 07:38:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-PhFmEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:38:33 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)) 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-98aTsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:38:33 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)) 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-TJK6NO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:38:33 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)) 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-XFXg2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:38:33 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)) 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-tC3ZsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:38:33 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)) 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Y33l0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:38:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-hPx2x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:38:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-dppmVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:38:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:38:33 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-BRPS2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:38:34 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)) 07:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:38:34 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-kyEu9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:38:34 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)) 07:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:38:34 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Neme8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:38:34 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)) 07:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:38:34 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-diDyTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:38:34 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)) 07:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:38:34 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-7tedU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:38:34 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)) 07:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:38:34 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-nM4M9_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:38:34 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)) 07:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:34 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-hrbnoy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:38:34 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)) 07:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:38:34 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-SJXDhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:38:34 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)) 07:38:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:38:34 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Jwft9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:38:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:38:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:38:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:38:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:38:37 INFO - Downloading blessings-1.6.tar.gz 07:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:38:37 INFO - Installing collected packages: blessings 07:38:37 INFO - Running setup.py install for blessings 07:38:38 INFO - Successfully installed blessings 07:38:38 INFO - Cleaning up... 07:38:38 INFO - Return code: 0 07:38:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:38:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:38:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:38:38 INFO - Reading from file tmpfile_stdout 07:38:38 INFO - Current package versions: 07:38:38 INFO - blessings == 1.6 07:38:38 INFO - blobuploader == 1.2.4 07:38:38 INFO - docopt == 0.6.1 07:38:38 INFO - functools32 == 3.2.3-2 07:38:38 INFO - jsonschema == 2.5.1 07:38:38 INFO - manifestparser == 1.1 07:38:38 INFO - mozInstall == 1.12 07:38:38 INFO - mozcrash == 0.17 07:38:38 INFO - mozdebug == 0.1 07:38:38 INFO - mozdevice == 0.48 07:38:38 INFO - mozfile == 1.2 07:38:38 INFO - mozhttpd == 0.7 07:38:38 INFO - mozinfo == 0.9 07:38:38 INFO - mozleak == 0.1 07:38:38 INFO - mozlog == 3.4 07:38:38 INFO - moznetwork == 0.27 07:38:38 INFO - mozprocess == 0.23 07:38:38 INFO - mozprofile == 0.28 07:38:38 INFO - mozrunner == 6.12 07:38:38 INFO - mozscreenshot == 0.1 07:38:38 INFO - mozsystemmonitor == 0.3 07:38:38 INFO - moztest == 0.7 07:38:38 INFO - mozversion == 1.4 07:38:38 INFO - psutil == 3.1.1 07:38:38 INFO - requests == 1.2.3 07:38:38 INFO - wsgiref == 0.1.2 07:38:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:38:38 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')]} 07:38:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:39 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')]} 07:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:39 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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:39 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 07:38:39 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 07:38:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:39 INFO - 'HOME': '/Users/cltbld', 07:38:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:39 INFO - 'LOGNAME': 'cltbld', 07:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:39 INFO - 'MOZ_NO_REMOTE': '1', 07:38:39 INFO - 'NO_EM_RESTART': '1', 07:38:39 INFO - 'PAGER': '/bin/cat', 07:38:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:39 INFO - 'PWD': '/builds/slave/test', 07:38:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:39 INFO - 'SHELL': '/bin/bash', 07:38:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:39 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:39 INFO - 'USER': 'cltbld', 07:38:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:39 INFO - 'XPC_FLAGS': '0x0', 07:38:39 INFO - 'XPC_SERVICE_NAME': '0', 07:38:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 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)) 07:38:39 INFO - Cleaning up... 07:38:39 INFO - Return code: 0 07:38:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:38:39 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')]} 07:38:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:38:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:39 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')]} 07:38:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:38:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:38:39 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 0x109856490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097cc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb193cd50b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, '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.vgWhXrZVU5/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.kGlU15p77O/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 07:38:39 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 07:38:39 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 07:38:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:38:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:38:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:38:39 INFO - 'HOME': '/Users/cltbld', 07:38:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:38:39 INFO - 'LOGNAME': 'cltbld', 07:38:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:38:39 INFO - 'MOZ_NO_REMOTE': '1', 07:38:39 INFO - 'NO_EM_RESTART': '1', 07:38:39 INFO - 'PAGER': '/bin/cat', 07:38:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:38:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:38:39 INFO - 'PWD': '/builds/slave/test', 07:38:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:38:39 INFO - 'SHELL': '/bin/bash', 07:38:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:38:39 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:38:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:38:39 INFO - 'USER': 'cltbld', 07:38:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:38:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:38:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:38:39 INFO - 'XPC_FLAGS': '0x0', 07:38:39 INFO - 'XPC_SERVICE_NAME': '0', 07:38:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:38:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:38:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:38:39 INFO - Cleaning up... 07:38:39 INFO - Return code: 0 07:38:39 INFO - Running post-action listener: _resource_record_post_action 07:38:39 INFO - Running post-action listener: _start_resource_monitoring 07:38:39 INFO - Starting resource monitoring. 07:38:39 INFO - [mozharness: 2016-10-07 14:38:39.568916Z] Finished create-virtualenv step (success) 07:38:39 INFO - [mozharness: 2016-10-07 14:38:39.569881Z] Running install step. 07:38:39 INFO - Running pre-action listener: _resource_record_pre_action 07:38:39 INFO - Running main action method: install 07:38:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:38:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:38:39 INFO - Reading from file tmpfile_stdout 07:38:39 INFO - Detecting whether we're running mozinstall >=1.0... 07:38:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:38:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:38:39 INFO - Reading from file tmpfile_stdout 07:38:39 INFO - Output received: 07:38:39 INFO - Usage: mozinstall [options] installer 07:38:39 INFO - Options: 07:38:39 INFO - -h, --help show this help message and exit 07:38:39 INFO - -d DEST, --destination=DEST 07:38:39 INFO - Directory to install application into. [default: 07:38:39 INFO - "/builds/slave/test"] 07:38:39 INFO - --app=APP Application being installed. [default: firefox] 07:38:39 INFO - mkdir: /builds/slave/test/build/application 07:38:39 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'] 07:38:39 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 07:39:14 INFO - Reading from file tmpfile_stdout 07:39:14 INFO - Output received: 07:39:14 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:39:14 INFO - Running post-action listener: _resource_record_post_action 07:39:14 INFO - [mozharness: 2016-10-07 14:39:14.267212Z] Finished install step (success) 07:39:14 INFO - [mozharness: 2016-10-07 14:39:14.267381Z] Running run-tests step. 07:39:14 INFO - Running pre-action listener: _resource_record_pre_action 07:39:14 INFO - Running main action method: run_tests 07:39:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:39:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:39:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:39:14 INFO - 2016-10-07 07:39:14.281 screenresolution[1652:8363] starting screenresolution argv=screenresolution get 07:39:14 INFO - 2016-10-07 07:39:14.284 screenresolution[1652:8363] Display 0: 1600x1200x32@60 07:39:14 INFO - 2016-10-07 07:39:14.291 screenresolution[1653:8366] starting screenresolution argv=screenresolution list 07:39:14 INFO - Available Modes on Display 0 07:39:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:39:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:39:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:39:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:39:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:39:14 INFO - Intel Iris: 07:39:14 INFO - Chipset Model: Intel Iris 07:39:14 INFO - Type: GPU 07:39:14 INFO - Bus: Built-In 07:39:14 INFO - VRAM (Dynamic, Max): 1536 MB 07:39:14 INFO - Vendor: Intel (0x8086) 07:39:14 INFO - Device ID: 0x0a2e 07:39:14 INFO - Revision ID: 0x0009 07:39:14 INFO - Displays: 07:39:14 INFO - Display: 07:39:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:39:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:39:14 INFO - Display Serial Number: 1600x1200 60 07:39:14 INFO - Main Display: Yes 07:39:14 INFO - Mirror: Off 07:39:14 INFO - Online: Yes 07:39:14 INFO - Rotation: Supported 07:39:14 INFO - Return code: 0 07:39:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:39:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:39:14 INFO - Python 2.7.3 07:39:14 INFO - Return code: 0 07:39:14 INFO - grabbing minidump binary from tooltool 07:39:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:39:14 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 0x10966ad30>, '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 0x109490db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109662b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:39:14 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 07:39:14 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 07:39:14 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 07:39:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:39:14 INFO - Return code: 0 07:39:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:39:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:39:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:39:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:39:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:39:14 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/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 07:39:14 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/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0304 --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 07:39:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgWhXrZVU5/Render', 07:39:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:39:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:39:14 INFO - 'HOME': '/Users/cltbld', 07:39:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:39:14 INFO - 'LOGNAME': 'cltbld', 07:39:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:39:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:39:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:39:14 INFO - 'MOZ_NO_REMOTE': '1', 07:39:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:39:14 INFO - 'NO_EM_RESTART': '1', 07:39:14 INFO - 'PAGER': '/bin/cat', 07:39:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:39:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:39:14 INFO - 'PWD': '/builds/slave/test', 07:39:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:39:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:39:14 INFO - 'SHELL': '/bin/bash', 07:39:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kGlU15p77O/Listeners', 07:39:14 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 07:39:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:39:14 INFO - 'USER': 'cltbld', 07:39:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:39:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:39:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:39:14 INFO - 'XPC_FLAGS': '0x0', 07:39:14 INFO - 'XPC_SERVICE_NAME': '0', 07:39:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:39:14 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/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 07:39:14 INFO - mozversion application_buildid: 20161007063359 07:39:14 INFO - mozversion application_changeset: 1de5e07bed6601d2647e88ed2690b3f30418d8aa 07:39:14 INFO - mozversion application_display_name: Nightly 07:39:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:39:14 INFO - mozversion application_name: Firefox 07:39:14 INFO - mozversion application_remotingname: firefox 07:39:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:39:14 INFO - mozversion application_vendor: Mozilla 07:39:14 INFO - mozversion application_version: 52.0a1 07:39:14 INFO - mozversion platform_buildid: 20161007063359 07:39:14 INFO - mozversion platform_changeset: 1de5e07bed6601d2647e88ed2690b3f30418d8aa 07:39:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:39:14 INFO - mozversion platform_version: 52.0a1 07:39:14 INFO - using testdate: 1475851154 07:39:14 INFO - actual date: 1475851154 07:39:14 INFO - starting webserver on 'localhost:49272' 07:39:14 INFO - SUITE-START | Running 2 tests 07:39:14 INFO - TEST-START | dromaeo_css 07:39:14 INFO - operating with platform_type : mac_ 07:39:14 INFO - Initialising browser for dromaeo_css test... 07:39:14 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpRwZDXN/profile http://localhost:49272/getInfo.html) 07:39:17 INFO - PROCESS | 1663 | 2016-10-07 07:39:17.602 plugin-container[1665:8541] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c3b, name = 'com.apple.tsm.portname' 07:39:17 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:39:17 INFO - PROCESS | 1663 | 2016-10-07 07:39:17.605 plugin-container[1665:8541] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 07:39:17 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:39:17 INFO - PROCESS | 1663 | 2016-10-07 07:39:17.605 plugin-container[1665:8541] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:39:17 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 07:39:17 INFO - PROCESS | 1663 | colorDepth:24 07:39:17 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 07:39:17 INFO - PROCESS | 1663 | __metrics 07:39:17 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 07:39:18 INFO - TEST-INFO | 1663: exit 0 07:39:18 INFO - Browser initialized. 07:39:18 INFO - Running cycle 1/1 for dromaeo_css test... 07:39:18 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpRwZDXN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:39:20 INFO - PROCESS | 1667 | 2016-10-07 07:39:20.654 plugin-container[1668:8667] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e13, name = 'com.apple.tsm.portname' 07:39:20 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:39:20 INFO - PROCESS | 1667 | 2016-10-07 07:39:20.656 plugin-container[1668:8667] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 07:39:20 INFO - PROCESS | 1667 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:39:20 INFO - PROCESS | 1667 | 2016-10-07 07:39:20.656 plugin-container[1668:8667] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:39:30 INFO - PROCESS | 1667 | RSS: Main: 239374336 07:39:30 INFO - PROCESS | 1667 | 07:40:19 INFO - PROCESS | 1667 | 1475851219973 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] 07:42:33 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 07:42:35 INFO - PROCESS | 1667 | RSS: Main: 250462208 07:42:35 INFO - PROCESS | 1667 | 07:45:37 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 07:45:38 INFO - PROCESS | 1667 | RSS: Main: 265867264 07:45:38 INFO - PROCESS | 1667 | 07:48:40 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 07:48:40 INFO - PROCESS | 1667 | RSS: Main: 270581760 07:48:40 INFO - PROCESS | 1667 | 07:51:42 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 07:51:43 INFO - PROCESS | 1667 | RSS: Main: 270938112 07:51:43 INFO - PROCESS | 1667 | 07:54:45 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 07:54:46 INFO - PROCESS | 1667 | RSS: Main: 276373504 07:54:46 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 07:57:49 INFO - PROCESS | 1667 | RSS: Main: 279056384 07:57:49 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | __start_tp_report 07:57:49 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 07:57:49 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 07:57:49 INFO - PROCESS | 1667 | |i|pagename|runs| 07:57:49 INFO - PROCESS | 1667 | |0;dojo.html;2873.1955052585276;3361.6974472297475;3385.069105995852;3426.5888093428794;3442.3115376924616;4218.093109981353;5464.0711079116545;5497.642653227537;5633.042382794924;5657.377688454267;5646.378898321192;5665.688387138705;5689.203511508382;5765.740541675625;5776.740046697898;5157.948420515794;5222.686638801665;5261.947380526203;5311.920321195166;5328.6269961102735;5252.737363131847;5310.15037593985;5330.546903513206;5353.277307563468;5361.008213480519;5135.152699804527;5198.2722418861385;5209.531142197183;5265.104932161504;5278.155495120763;5170.767315470775;5204.9479505204845;5254.0280048190825;5277.261183434322;5300.973495132538;5149.2791009258735;5187.221916712486;5208.739563021872;5232.293640358579;5283.471652834724;5350.250964864922;5566.721663916828;5571.164325351189;5764.798232061899;5802.506786923117;6120.69094295093;6131.202943617346;6134.5092392608485;6157.138000679908;6171.814845554641;6168.599041062316;6207.844803879894;6261.436470717657;6278;6282.654454005028;5842.660872073097;5855.560832937546;5866.325372582208;5880.088586269135;5885.588008839383;6164.167837341991;6177.166082578794;6221.2845522764555;6292.748290068346;6295.874082518324;6154.969225153845;6165.414285642857;6166.321704612489;6174.197354343906;6186.515236343245;6108.949222962717;6165.8458538536;6276.592021518587;6278.434940855345;6283.931731605638;6132.01899668756;6174.104754810617;6176.042713379434;6199.442050215412;6227.034809604518;5847.649141051551;6187.907181392283;6258.311585725566;6272.498200143978;6272.560920735505;4839.564439200419;4941.752912354368;4968.056069346812;4974.253861920669;4993.051320249141;4400.779961001937;4415.337699345124;4431.0251744616135;4462.598366147063;4555.476120246148;5724.513416359656;5822.301323841166;5823.5341172706085;5831.329397119385;5850.3564607893095;6233.501319894398;6239.812805615748;6275.309715931244;6286.748530058747;6303.747850086037;5656.60403771732;5713.600047996601;5742.425757424224;5770.740316685759;5804.245448091721;5415.377231618336;5418.4039755626845;5431;5433.592480563973;5449.12813949774;6320.336364681734;6344.809655710336;6356.0465930109;6356.968215158895;6366.618002919932;6096.315032485105;6146.477549408171;6164.167837341902;6190.566660333733;6269.843253918689;6255.655938923403;6291.748330066746;6292.748290068346;6302.212223472066;6306.432421082125;6044.184035155374;6167.506599471942;6176.197094377702;6191.9690401547705;6196.349383314687;6105.520399749808;6123.295820980644;6146.340488068286;6256.624602523863;6268.091126786681;5668.3198016236665;5916.763329466773;6136.929937132656;6144.941444615925;6145.187169784835;6093.116498107838;6102.941543873452;6106.847328816815;6108.541859360655;6139.140520327247;3824.330742120062;5428.941356435457;5910.497607703393;5941.643501389931;5942.584019118794;5084.542391184663;5213.40045894727;5909.6158749681135;6118.143459915526;6133.84665383369;5930.7924222652;5937.079752638348;5971.641701497924;5975;6001.699915004319 07:57:49 INFO - PROCESS | 1667 | |1;ext.html;436.3694461503125;460.68212933076177;495.66047257628526;503.88158782686037;511.51915205480145;29198.708012919866;33170;39790.20419591606;39840.80079599605;40663.59336406635;41142.177156456855;41695;41714.58285417145;41734;41741.791291043504;8182.877256841152;9915.008499150063;9927.305088643761;9934.155596774246;9986.600535978589;9803.019698030175;9881.357711748713;9891.851622225673;9905.801883962316;9908.058734420218;9870.950645246763;9905.15806156474;9908.355956862817;9913.702588922344;9928.45393503357;9858.605655773761;9912.256580756472;9927.305088643761;9930.602775888956;9962.203023758082;9025.729228123168;9209.585568649427;9749.902500974971;9806.970268121815;9836.803263934753;38316;38321.23357532862;38493.61506384928;38554.614453855385;38581.80709096465;3468.5317482140094;3522.6301238370115;3556.270964452281;3958.4458175855116;4176.561461046576;29410.705892941223;30269.545956810667;30761.230969225726;30791.692083079106;30847.383052339053;3137.8117312961067;3265.248992731685;3297.1757060734817;3325.7339412846914;3333.4333163362285;439472;441157;445216;445233.99999999674;445564;53445.7327713359;53928.92142157214;53949.46050539445;53968.73015634897;54017;55923.16115258275;56863.431365685814;56993.14510282349;57067;57117.71441142851;15622.9218853905;15781.053136811828;15822.52532424029;15840.683186336439;15910.04539727597;11598.304101753922;12367.257964522158;12397.00823934083;12403.379831008593;12404.317762522967;11931.58239461632;12047.81928271059;14340.21128837924;14637.487687930872;14700.264986750619;6330.905036424458;6336.746530138835;7197.424206063502;7267.854642907112;7298.2701729826595;799.4523751230425;856.3748463621466;952.2382094324513;963.7711917305492;1066.7039546950493;25007;28127;28252;29932.40135197284;29990.25024374408;1012.1953047327393;1030.7320096774893;1051.1380667852288;1075.1506310015156;1092.9289596176222;14789.482368117064;15150;15182.316795744218;15503;15586.532404027897;536332;641933;653562;654635;658097;54992.17511737327;55242.171367429524;55335.44664553383;55350;55409.72295138579;1332.733453309342;1373.7527245095778;1379.3930670504947;1392.463901397969;1398.1052126638756;28515.572266415606;29197;29278;29777.85111074431;29908.700912990593;47141.29288060751;47346.053078938916;47347.28979065386;47366.76316618395;47528.2870756932;483013;486344;486383;487079;487528;37904.810475947445;38264.42603361008;38689.2262154761;38702.2259554813;38720.41919371268;44063.11873762443;45150.54849451463;45447.772761137305;45848.770756146005;46238.84402889955;37255.81372093122;37379.684975367105;38197.23605527818;38434.6156538431;38697.6130238694;35209.47185792266;36768.81615592012;36854.63145368512;37305.81347093247;37741.81129094337;36795;37212.627873720914;37713.62286377211;37873.86378408652;38983.61016389913;30721.69278307188;34136.97589072332;34365.14087147841;34382.82808585941;34425.827870860485;24555.508889821747;24997.500049998533;25296.86164122582;25426.745732542437;25627.487450251265 07:57:49 INFO - PROCESS | 1667 | |2;jquery.html;322.8763901622354;339;373.6487701560533;384.73453317211107;401.89148929788956;19682.70475942862;20611.69082463764;20719.896400518013;21484.248051318205;21578.244761433314;20263.797362026413;20456.18175272991;20559.074841632126;20652.380428587167;20831;5579.69311687857;5956.315023772257;6082.391760823926;6183.567150299481;6322.747090116412;6092.238470191226;6227.221597300338;6230.5638605297645;6245.188125543673;6249.531285153609;6192.225971753531;7548.547087174759;7968.521888686669;7975.122736498981;8025.478343907627;6389.520785941073;6569.638669873155;7630.771076867675;7931.682732690685;8080.030396352417;6553.6395498247575;7768.41736869737;7776.494527855728;7777.766666999943;7798.922010779933;27175.45649087007;27468.862655686793;28210.717892821016;28389.432211355863;28487.720982321363;2530.784883284923;2627.2249686342575;3039.6504401993834;3159.162821852211;3384.170878134848;16871.831281687148;16982.66034679312;17098.401555945606;17301.30794768208;17647.5588110297;1950.2686492565288;2017.7376940997576;2021.3632705697657;2023.1705000949687;2086.013142281661;115484.84515154824;117785.82214177834;119291;133846;146707;17451.38920137789;20276.391708248775;21447.571048578942;21633.783662163176;21728.654269146235;34408.13979650479;34787.478187827204;34828.477572836426;34943;35020;9203.033681463483;10317.071463568314;10337.689869303782;10485.423301718327;10711.892881071246;8548.145185481526;8592.157333013485;8684.305255579664;8770.561471926378;8793.472391656522;9608.423494590346;9637.469939153414;9676.9032309676;9895.703128906145;9946.35348702332;3312.8840490582716;3878.495795546561;3884.7280690351968;3892.805359732002;4015.15681706839;827.4414770030326;880.9163129502687;964.4936408385654;968.2109081098882;972.3533849989819;18173;20719.378418647466;21323.57352852934;21830.23594174196;21842.017109230124;929.2751653710117;931.3620170183447;938.5494962418078;950.14486961735;1001.7345403468087;10868.130549556017;10946.507407166537;11317.981381675689;11434.599789007345;11779.941100294443;581835;592966;634999;635514;642596;87019;110694.44652776685;129319;133901;134090;991.7173605522536;1002.0780881588955;1034.4930983817876;1039.2569312941284;1039.740064983754;18959.715604266225;19951.700724489434;20241.79758202458;20284.79715202829;20331.288404905757;51087;54150;56131.71934140303;56327.43672563222;56512.044156908625;79047.60476197582;79306.0346982637;79594.6020269895;79854;80003.19996799958;7766.378689704923;7923.445358824827;8009.038915329965;8028.397870159644;8105.27052565272;10822.837657434988;11647.71875093756;12465.626031219077;12683.429245683781;12828.615141545768;10326.741831454274;10918.781624367628;11234.887651123385;11291.378974156172;11320;10559.630412935818;10900.781984360427;10901.454927253448;11068.391238481961;11221.382823944761;8979.865302020344;10598.25812193139;10729.796727813584;11235.887641123483;11336.979671829575;6314.684265786784;7024.894626580503;7922.524648521107;8305.46014509055;8316.41785075039;8670.393072484865;9727.319087663796;9759.828055881724;10918;11380.715482112682 07:57:49 INFO - PROCESS | 1667 | |3;mootools.html;1498.992505037475;1549.883758718096;1749.3964582219137;1764.7617571627827;1808.8965730904154;7945.967024286849;8233.794155146124;8404.789880252996;8927.464352138868;8951.776205594864;8342.249197572217;8662.220400163984;8703.129687031294;8719.956400218005;8790;2316.3051084674617;2370.8696021718797;2372.6559648850907;2373.0389192377115;2374.4538756086113;2344.6248600223967;2359.056377449017;2373.9762602373926;2392.377981724747;2398.59223931932;2371.4545654499475;2372.988135059331;2373.169390713254;2378.2389635316704;2380.452495925938;2318.431984163874;2332.6734257204007;2342.6603142544323;2360.8229382796358;2360.834741568091;2335.8248131390214;2350.8119350452093;2354.329016230366;2357.905683772647;2358.50471401006;8698.956505217497;8756.211940925283;8804.295656347476;8810.559472026373;8818.162274583969;5204.9739751301;5354.544863686591;5355.6786592804165;5366.865828354323;5373.435789242151;3546.627604101584;3571.8928432147077;3592.6586974237407;3658.1037645776687;3682.5396825396824;1061.8247989081792;1085.46812062089;1086.940218287994;1090.1714696830386;1094.4363652718855;11209.439528023566;11332.49003794823;11335;11424.771504569862;11431.65705028842;4281.7002809803635;4635.768211589474;4809.495003024702;4821.879453013703;4823.300621409876;9912.851307230398;9979.251556133318;10006.149477294512;10012.749681257881;10022.398656080659;5245.790168393221;5323.653962492425;5331.813386531453;5333.093374126408;5357.464253574612;3959.5644479107277;4346.500152482505;4498.122866041156;4690.507496712796;4868.342773725572;3289;3864.381698928158;3978.8806335809973;4016.4376987221813;4025.1345960618023;1288.207752232389;1317.117531254062;1317.1767645221737;1328.2030781531005;1330.0755974597562;810.5379933437883;812.175641723651;924.6209054287709;925.5048549026238;941.0685714000838;5822.621529600647;5826.242588463472;5870;6036.366181550961;6085.1785009023215;438.6184019902705;442.2989561544923;455.4170661553217;458.8187665871962;461.0295328333876;3863.6909047276713;4002.459667944848;4060.3503439449546;4101.979490102531;4119.434614846339;8831.470111793313;9011.414258073204;10394.532246048946;10603.84094238587;10623.990720881504;10600.88690687482;10606.257561970588;10606.348412896776;10611.681649550526;10643.627473038407;6149.600275982137;8059.032916049994;8123.106458289583;8123.781432785011;8151.429399941947;5948.434898684618;5982.551308651781;6037.667928263988;6097.176881120992;6185.257769067741;9397.342186046912;9605.903940960501;9870.901290987285;9890;9931.404115753077;9878.506074696093;9971.700848974542;10022.24833137503;10098.394096354243;10214.897851021395;5964.463198312173;7411.221821708643;7478.364339031244;7529.058867641545;7586.393088553013;8321.08468068524;8377.70678026266;8392.20274073962;8394.11861754507;8409.789755256168;6353.555251132377;7289.307515785992;7400.370968467741;7458.5151965122095;7548.773536793905;7140.46446516503;7276.745313913988;7311.4516411268305;7326;7375.078115235596;6271.62370257786;6360.554761166674;6657.3009833966735;6720.529562930548;6785.93214067853;5026.924596131134;5055.974720126523;5064.797408103635;5094.2613321068975;5120.74396280177;4920.384951881015;5022.121128802518;5077.162268225701;5179.274901513866;5192.45479224676 07:57:49 INFO - PROCESS | 1667 | |4;prototype.html;260.95824668053103;304.9801762885412;311.18004059303735;313.5469246938175;315.71585572984327;5678.744456499458;5708.200851880735;5709.400512946143;5727.195933281014;5753.626014309072;4735.90528189436;5045.896773484308;5201.713905735183;5590.413006634306;5721.256236689224;3491.9175055732676;3552.591451983016;4057.6348128668415;4218.683598730092;4240.173166232586;3806.838914131189;3970.325044742386;4273.209456250588;4276.722013069156;4287.306513926997;3729.645683660048;3939.8771350165207;4241.215375155606;4241.27898257297;4283.821948964044;3711.2948539777626;3807.971847601132;3948.5853985331414;4159.896002599929;4269.4022836802715;3047.527633216855;3652.5616925969052;3673.155174309897;3696.890932720173;3701.722370822199;4616.792244349012;4687.015726697397;5380.4350543192795;5487.2317875497465;5500.257465242181;3736.9439458408146;4325;4871.147549178915;4904.975475122602;5047.848564543069;5117.872053198663;5687.004774164546;5920.644761314335;5924.703764811742;5985.670788106652;3411.812350320731;3576.3741345264475;3911.7457365271166;4050.2911990401562;4070.653994410449;5364.383095943938;5679.432056794329;6068.271807383142;6210.975189093794;6286.9999999999545;3155.911210632328;3156.3845050214986;3630.5098811660614;4060.573639767782;4100.835966561363;3300.7029367357054;3542.8051457170104;3776.5263752242327;4198.958010419857;4200.642945349679;4874.5856602188505;4900.558949694545;4917.852464426073;4920.769807548113;4944.703317800943;5726.0838265877255;5915.792947246911;5921.703914804329;5941.58408911372;5962.568983443943;1247.2454165230088;1430.7782293744485;1432.8495507971722;1439.7264519741425;1441.704450587627;735.3822788857385;804.9718259860994;871.6818361298081;894.5571941888677;905.4024343933104;4899.579122054564;4966.503349665005;5342.759575819097;5385.542228910508;5516.620844788803;5353.330833645794;5388;5502.944970550323;6051.818445446644;6152.477039451607;4741.312509686076;5209.609279304067;5250.684958902478;5618;5665.745041473144;2827.9575806362923;3035.711607397294;3170.682931706811;3243.854026568763;3253.755968302387;5386.353637563518;6267.781026352824;6416.967915160394;6452.838679033061;6490.65731148459;5250.65870718402;5529.751161197756;5971.5222782177325;6260.342664020303;6315.494760419156;6414.679266036679;6424.196975378078;6433.228012638513;6445.194350706068;6464.935350646621;5915.953284058627;6071.726772295169;6103.755849765959;6166.414190651881;6185.938140618537;6049.51969692424;6099.512039036955;6121.510279177566;6130.356312587114;6186.381361863778;4972.864063526263;6291.937080629135;6404.423601875854;6407.796101949026;6458.22501299847;3285.507173923835;3386.6444023378176;3487.197944472735;3509.9294715112137;3521.3485505180597;4172;4740.478547359857;5093.694378337312;5169.896602067863;5196.350456192976;4315.892102697458;4490.461144662661;4837.274408838703;5099.3370861787735;5106.412762532208;4116.814743336617;4832.782524786323;5075.644704870623;5123.231515272739;5129;3943.5662077170914;4138.006878349234;4626.537346265347;4653.906332011907;4985.526374994515;4993.725345106054;5375.489328513785;5751.4536119068625;5835.124731290341;5958.791442299499;5795.594308398372;5843.824685259449;5867.73595188209;5873.853153671193;5892.381299963442 07:57:49 INFO - PROCESS | 1667 | |5;yui.html;617.4751461257929;633.5596760251625;684.5447777228144;770.1374460604125;793.8372633610109;12375.133740638148;12633;13984.160950342974;15160.848391516081;16271.186440677979;12405.697401772819;14630.34163462644;14793;14796;14848.406063757437;666.1273731411866;689.1557841643996;735.3602365941638;830.1076342931373;853.7652145659962;832.2842355574202;843.3379796859435;858.2661824140332;860.1785295043219;862.3489265604492;885.1060428966758;895.7805337692242;900.617237673986;902.4720538484961;904.5703290936818;877.3025444771422;889.7063968890283;898.0166717444388;898.0346127912455;898.6450352110912;654.6464908949162;665.5806841689705;667.1194023888469;676.1345477788439;677.664556044761;14994.925025374803;15092.849071509254;15127.168005759679;15141.242937853063;15160;14534.418623255056;14535.055221410681;14569.490067847575;14659.92670036643;14761.77857332141;11800.46897889597;12038.4582693779;12327.198732082386;12345.444454999457;12542.74914501714;3149.2599239178776;3723.210771001618;3869.4582758413844;3869.825857836376;3943.191645712651;118317;119550.40224798907;119668;119739;123308.38345808303;11285.266457680225;11289.604863829727;11348.602798901999;11365.431728413547;11848;23866;24054;24144.7585524146;24199.51600968006;24206.273811785675;7852.528848269122;7869.960650196713;7917.564533950573;7931.762047138595;8107.756767296991;4062.410950412174;4285.785710714452;4338.414314067623;4384.0355121873135;4428.1180498288095;3623.438367053111;3667.2298817248143;3692.446133080006;3727.1613886875234;3738.775673459601;605.742559412248;648.8929326661142;651.5146216069058;662.592505609047;672.4754691340762;640.7244884699624;656.3797211634959;674.3256743256743;678.7692184657142;678.8235058884726;9160.083991600786;9232.815343693088;9262.814743705088;9631.133198012212;9688.515574221261;573.5755540899704;606.4299558415164;644.6776611694153;658.3646780856496;669.8727241824038;4381.18947994622;4618.237990731559;4652.232381657056;4786.95213047865;4842.104210721028;91040.26879193635;138197.3090134563;145818.27090864477;155989;157785;101114.49442752739;111582;131982;138815;140525.29737351247;120014;121110.39444802896;121444;123574;124498.37750811188;8813.515256660945;9032.32257580671;9036;9086.415808366535;9310.115539023782;49842.75078624584;60001.39998599958;60679.69660151671;63149.789757358274;64288.678556606916;98946;102263;102916.48541757243;103019.484902575;103633.48183259036;12037.398130093636;12134.878651213376;12405.069619778373;12593.992480601346;12630.936845315715;13001.544945927226;13264;13285.867141328463;13380.866191337962;13749.931250343685;12035.45840437165;12136.271823690608;12337.938310308391;12554.309512976874;12641.431135598736;12167.634970950885;12448.502059917502;12536.435860386122;12669.556565520532;12727.172733772275;11833.349165795964;12295.200811947196;12386.876131238572;12497.812532812197;12520.561780337644;10216.233782466195;10310.07209351132;10521.000504952017;10592.89407105919;10714.732131696459;9645.987171347188;9748.268879834182;10310.484475776331;10481.475926203811;10592.205584581034 07:57:49 INFO - PROCESS | 1667 | __end_tp_report 07:57:49 INFO - PROCESS | 1667 | __start_cc_report 07:57:49 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,354 07:57:49 INFO - PROCESS | 1667 | __end_cc_report 07:57:49 INFO - PROCESS | 1667 | __startTimestamp1475852269681__endTimestamp 07:57:49 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | ------- Summary: start ------- 07:57:49 INFO - PROCESS | 1667 | Number of tests: 6 07:57:49 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5707.52 Median:5910.50 stddev:663.40 (11.2%) stddev-sans-first:627.07 07:57:49 INFO - PROCESS | 1667 | Values: 2873.2 3361.7 3385.1 3426.6 3442.3 4218.1 5464.1 5497.6 5633.0 5657.4 5646.4 5665.7 5689.2 5765.7 5776.7 5157.9 5222.7 5261.9 5311.9 5328.6 5252.7 5310.2 5330.5 5353.3 5361.0 5135.2 5198.3 5209.5 5265.1 5278.2 5170.8 5204.9 5254.0 5277.3 5301.0 5149.3 5187.2 5208.7 5232.3 5283.5 5350.3 5566.7 5571.2 5764.8 5802.5 6120.7 6131.2 6134.5 6157.1 6171.8 6168.6 6207.8 6261.4 6278.0 6282.7 5842.7 5855.6 5866.3 5880.1 5885.6 6164.2 6177.2 6221.3 6292.7 6295.9 6155.0 6165.4 6166.3 6174.2 6186.5 6108.9 6165.8 6276.6 6278.4 6283.9 6132.0 6174.1 6176.0 6199.4 6227.0 5847.6 6187.9 6258.3 6272.5 6272.6 4839.6 4941.8 4968.1 4974.3 4993.1 4400.8 4415.3 4431.0 4462.6 4555.5 5724.5 5822.3 5823.5 5831.3 5850.4 6233.5 6239.8 6275.3 6286.7 6303.7 5656.6 5713.6 5742.4 5770.7 5804.2 5415.4 5418.4 5431.0 5433.6 5449.1 6320.3 6344.8 6356.0 6357.0 6366.6 6096.3 6146.5 6164.2 6190.6 6269.8 6255.7 6291.7 6292.7 6302.2 6306.4 6044.2 6167.5 6176.2 6192.0 6196.3 6105.5 6123.3 6146.3 6256.6 6268.1 5668.3 5916.8 6136.9 6144.9 6145.2 6093.1 6102.9 6106.8 6108.5 6139.1 3824.3 5428.9 5910.5 5941.6 5942.6 5084.5 5213.4 5909.6 6118.1 6133.8 5930.8 5937.1 5971.6 5975.0 6001.7 07:57:49 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:65405.86 Median:21471.03 stddev:140428.11 (654.0%) stddev-sans-first:140737.32 07:57:49 INFO - PROCESS | 1667 | Values: 436.4 460.7 495.7 503.9 511.5 29198.7 33170.0 39790.2 39840.8 40663.6 41142.2 41695.0 41714.6 41734.0 41741.8 8182.9 9915.0 9927.3 9934.2 9986.6 9803.0 9881.4 9891.9 9905.8 9908.1 9871.0 9905.2 9908.4 9913.7 9928.5 9858.6 9912.3 9927.3 9930.6 9962.2 9025.7 9209.6 9749.9 9807.0 9836.8 38316.0 38321.2 38493.6 38554.6 38581.8 3468.5 3522.6 3556.3 3958.4 4176.6 29410.7 30269.5 30761.2 30791.7 30847.4 3137.8 3265.2 3297.2 3325.7 3333.4 439472.0 441157.0 445216.0 445234.0 445564.0 53445.7 53928.9 53949.5 53968.7 54017.0 55923.2 56863.4 56993.1 57067.0 57117.7 15622.9 15781.1 15822.5 15840.7 15910.0 11598.3 12367.3 12397.0 12403.4 12404.3 11931.6 12047.8 14340.2 14637.5 14700.3 6330.9 6336.7 7197.4 7267.9 7298.3 799.5 856.4 952.2 963.8 1066.7 25007.0 28127.0 28252.0 29932.4 29990.3 1012.2 1030.7 1051.1 1075.2 1092.9 14789.5 15150.0 15182.3 15503.0 15586.5 536332.0 641933.0 653562.0 654635.0 658097.0 54992.2 55242.2 55335.4 55350.0 55409.7 1332.7 1373.8 1379.4 1392.5 1398.1 28515.6 29197.0 29278.0 29777.9 29908.7 47141.3 47346.1 47347.3 47366.8 47528.3 483013.0 486344.0 486383.0 487079.0 487528.0 37904.8 38264.4 38689.2 38702.2 38720.4 44063.1 45150.5 45447.8 45848.8 46238.8 37255.8 37379.7 38197.2 38434.6 38697.6 35209.5 36768.8 36854.6 37305.8 37741.8 36795.0 37212.6 37713.6 37873.9 38983.6 30721.7 34137.0 34365.1 34382.8 34425.8 24555.5 24997.5 25296.9 25426.7 25627.5 07:57:49 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:37137.73 Median:1861.59 stddev:102781.33 (5521.2%) stddev-sans-first:103032.49 07:57:49 INFO - PROCESS | 1667 | Values: 322.9 339.0 373.6 384.7 401.9 19682.7 20611.7 20719.9 21484.2 21578.2 20263.8 20456.2 20559.1 20652.4 20831.0 5579.7 5956.3 6082.4 6183.6 6322.7 6092.2 6227.2 6230.6 6245.2 6249.5 6192.2 7548.5 7968.5 7975.1 8025.5 6389.5 6569.6 7630.8 7931.7 8080.0 6553.6 7768.4 7776.5 7777.8 7798.9 27175.5 27468.9 28210.7 28389.4 28487.7 2530.8 2627.2 3039.7 3159.2 3384.2 16871.8 16982.7 17098.4 17301.3 17647.6 1950.3 2017.7 2021.4 2023.2 2086.0 115484.8 117785.8 119291.0 133846.0 146707.0 17451.4 20276.4 21447.6 21633.8 21728.7 34408.1 34787.5 34828.5 34943.0 35020.0 9203.0 10317.1 10337.7 10485.4 10711.9 8548.1 8592.2 8684.3 8770.6 8793.5 9608.4 9637.5 9676.9 9895.7 9946.4 3312.9 3878.5 3884.7 3892.8 4015.2 827.4 880.9 964.5 968.2 972.4 18173.0 20719.4 21323.6 21830.2 21842.0 929.3 931.4 938.5 950.1 1001.7 10868.1 10946.5 11318.0 11434.6 11779.9 581835.0 592966.0 634999.0 635514.0 642596.0 87019.0 110694.4 129319.0 133901.0 134090.0 991.7 1002.1 1034.5 1039.3 1039.7 18959.7 19951.7 20241.8 20284.8 20331.3 51087.0 54150.0 56131.7 56327.4 56512.0 79047.6 79306.0 79594.6 79854.0 80003.2 7766.4 7923.4 8009.0 8028.4 8105.3 10822.8 11647.7 12465.6 12683.4 12828.6 10326.7 10918.8 11234.9 11291.4 11320.0 10559.6 10900.8 10901.5 11068.4 11221.4 8979.9 10598.3 10729.8 11235.9 11337.0 6314.7 7024.9 7922.5 8305.5 8316.4 8670.4 9727.3 9759.8 10918.0 11380.7 07:57:49 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5624.82 Median:5024.52 stddev:3135.43 (62.4%) stddev-sans-first:3128.90 07:57:49 INFO - PROCESS | 1667 | Values: 1499.0 1549.9 1749.4 1764.8 1808.9 7946.0 8233.8 8404.8 8927.5 8951.8 8342.2 8662.2 8703.1 8720.0 8790.0 2316.3 2370.9 2372.7 2373.0 2374.5 2344.6 2359.1 2374.0 2392.4 2398.6 2371.5 2373.0 2373.2 2378.2 2380.5 2318.4 2332.7 2342.7 2360.8 2360.8 2335.8 2350.8 2354.3 2357.9 2358.5 8699.0 8756.2 8804.3 8810.6 8818.2 5205.0 5354.5 5355.7 5366.9 5373.4 3546.6 3571.9 3592.7 3658.1 3682.5 1061.8 1085.5 1086.9 1090.2 1094.4 11209.4 11332.5 11335.0 11424.8 11431.7 4281.7 4635.8 4809.5 4821.9 4823.3 9912.9 9979.3 10006.1 10012.7 10022.4 5245.8 5323.7 5331.8 5333.1 5357.5 3959.6 4346.5 4498.1 4690.5 4868.3 3289.0 3864.4 3978.9 4016.4 4025.1 1288.2 1317.1 1317.2 1328.2 1330.1 810.5 812.2 924.6 925.5 941.1 5822.6 5826.2 5870.0 6036.4 6085.2 438.6 442.3 455.4 458.8 461.0 3863.7 4002.5 4060.4 4102.0 4119.4 8831.5 9011.4 10394.5 10603.8 10624.0 10600.9 10606.3 10606.3 10611.7 10643.6 6149.6 8059.0 8123.1 8123.8 8151.4 5948.4 5982.6 6037.7 6097.2 6185.3 9397.3 9605.9 9870.9 9890.0 9931.4 9878.5 9971.7 10022.2 10098.4 10214.9 5964.5 7411.2 7478.4 7529.1 7586.4 8321.1 8377.7 8392.2 8394.1 8409.8 6353.6 7289.3 7400.4 7458.5 7548.8 7140.5 7276.7 7311.5 7326.0 7375.1 6271.6 6360.6 6657.3 6720.5 6785.9 5026.9 5056.0 5064.8 5094.3 5120.7 4920.4 5022.1 5077.2 5179.3 5192.5 07:57:49 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4626.28 Median:5061.75 stddev:1495.36 (29.5%) stddev-sans-first:1463.22 07:57:49 INFO - PROCESS | 1667 | Values: 261.0 305.0 311.2 313.5 315.7 5678.7 5708.2 5709.4 5727.2 5753.6 4735.9 5045.9 5201.7 5590.4 5721.3 3491.9 3552.6 4057.6 4218.7 4240.2 3806.8 3970.3 4273.2 4276.7 4287.3 3729.6 3939.9 4241.2 4241.3 4283.8 3711.3 3808.0 3948.6 4159.9 4269.4 3047.5 3652.6 3673.2 3696.9 3701.7 4616.8 4687.0 5380.4 5487.2 5500.3 3736.9 4325.0 4871.1 4905.0 5047.8 5117.9 5687.0 5920.6 5924.7 5985.7 3411.8 3576.4 3911.7 4050.3 4070.7 5364.4 5679.4 6068.3 6211.0 6287.0 3155.9 3156.4 3630.5 4060.6 4100.8 3300.7 3542.8 3776.5 4199.0 4200.6 4874.6 4900.6 4917.9 4920.8 4944.7 5726.1 5915.8 5921.7 5941.6 5962.6 1247.2 1430.8 1432.8 1439.7 1441.7 735.4 805.0 871.7 894.6 905.4 4899.6 4966.5 5342.8 5385.5 5516.6 5353.3 5388.0 5502.9 6051.8 6152.5 4741.3 5209.6 5250.7 5618.0 5665.7 2828.0 3035.7 3170.7 3243.9 3253.8 5386.4 6267.8 6417.0 6452.8 6490.7 5250.7 5529.8 5971.5 6260.3 6315.5 6414.7 6424.2 6433.2 6445.2 6464.9 5916.0 6071.7 6103.8 6166.4 6185.9 6049.5 6099.5 6121.5 6130.4 6186.4 4972.9 6291.9 6404.4 6407.8 6458.2 3285.5 3386.6 3487.2 3509.9 3521.3 4172.0 4740.5 5093.7 5169.9 5196.4 4315.9 4490.5 4837.3 5099.3 5106.4 4116.8 4832.8 5075.6 5123.2 5129.0 3943.6 4138.0 4626.5 4653.9 4985.5 4993.7 5375.5 5751.5 5835.1 5958.8 5795.6 5843.8 5867.7 5873.9 5892.4 07:57:49 INFO - PROCESS | 1667 | 07:57:49 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:25147.88 Median:13677.77 stddev:40600.87 (296.8%) stddev-sans-first:40672.99 07:57:49 INFO - PROCESS | 1667 | Values: 617.5 633.6 684.5 770.1 793.8 12375.1 12633.0 13984.2 15160.8 16271.2 12405.7 14630.3 14793.0 14796.0 14848.4 666.1 689.2 735.4 830.1 853.8 832.3 843.3 858.3 860.2 862.3 885.1 895.8 900.6 902.5 904.6 877.3 889.7 898.0 898.0 898.6 654.6 665.6 667.1 676.1 677.7 14994.9 15092.8 15127.2 15141.2 15160.0 14534.4 14535.1 14569.5 14659.9 14761.8 11800.5 12038.5 12327.2 12345.4 12542.7 3149.3 3723.2 3869.5 3869.8 3943.2 118317.0 119550.4 119668.0 119739.0 123308.4 11285.3 11289.6 11348.6 11365.4 11848.0 23866.0 24054.0 24144.8 24199.5 24206.3 7852.5 7870.0 7917.6 7931.8 8107.8 4062.4 4285.8 4338.4 4384.0 4428.1 3623.4 3667.2 3692.4 3727.2 3738.8 605.7 648.9 651.5 662.6 672.5 640.7 656.4 674.3 678.8 678.8 9160.1 9232.8 9262.8 9631.1 9688.5 573.6 606.4 644.7 658.4 669.9 4381.2 4618.2 4652.2 4787.0 4842.1 91040.3 138197.3 145818.3 155989.0 157785.0 101114.5 111582.0 131982.0 138815.0 140525.3 120014.0 121110.4 121444.0 123574.0 124498.4 8813.5 9032.3 9036.0 9086.4 9310.1 49842.8 60001.4 60679.7 63149.8 64288.7 98946.0 102263.0 102916.5 103019.5 103633.5 12037.4 12134.9 12405.1 12594.0 12630.9 13001.5 13264.0 13285.9 13380.9 13749.9 12035.5 12136.3 12337.9 12554.3 12641.4 12167.6 12448.5 12536.4 12669.6 12727.2 11833.3 12295.2 12386.9 12497.8 12520.6 10216.2 10310.1 10521.0 10592.9 10714.7 9646.0 9748.3 10310.5 10481.5 10592.2 07:57:49 INFO - PROCESS | 1667 | -------- Summary: end -------- 07:57:49 INFO - PROCESS | 1667 | 07:57:50 INFO - TEST-INFO | 1667: exit 0 07:57:50 INFO - TEST-OK | dromaeo_css | took 1115442ms 07:57:50 INFO - TEST-START | kraken 07:57:50 INFO - operating with platform_type : mac_ 07:57:50 INFO - Initialising browser for kraken test... 07:57:50 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmp50rcmU/profile http://localhost:49272/getInfo.html) 07:57:52 INFO - PROCESS | 1674 | 2016-10-07 07:57:52.791 plugin-container[1675:10792] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.tsm.portname' 07:57:52 INFO - PROCESS | 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:57:52 INFO - PROCESS | 1674 | 2016-10-07 07:57:52.794 plugin-container[1675:10792] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f1b, name = 'com.apple.CFPasteboardClient' 07:57:52 INFO - PROCESS | 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:57:52 INFO - PROCESS | 1674 | 2016-10-07 07:57:52.794 plugin-container[1675:10792] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:57:52 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 07:57:52 INFO - PROCESS | 1674 | colorDepth:24 07:57:52 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 07:57:52 INFO - PROCESS | 1674 | __metrics 07:57:52 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3079: TypeError: this.tabs is undefined 07:57:53 INFO - TEST-INFO | 1674: exit 0 07:57:53 INFO - Browser initialized. 07:57:53 INFO - Running cycle 1/1 for kraken test... 07:57:53 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmp50rcmU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:57:55 INFO - PROCESS | 1676 | 2016-10-07 07:57:55.859 plugin-container[1677:10903] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d2f, name = 'com.apple.tsm.portname' 07:57:55 INFO - PROCESS | 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:57:55 INFO - PROCESS | 1676 | 2016-10-07 07:57:55.862 plugin-container[1677:10903] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9303, name = 'com.apple.CFPasteboardClient' 07:57:55 INFO - PROCESS | 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:57:55 INFO - PROCESS | 1676 | 2016-10-07 07:57:55.862 plugin-container[1677:10903] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:58:05 INFO - PROCESS | 1676 | RSS: Main: 239198208 07:58:05 INFO - PROCESS | 1676 | 07:58:08 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 07:58:09 INFO - PROCESS | 1676 | RSS: Main: 252641280 07:58:09 INFO - PROCESS | 1676 | 07:58:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 07:58:12 INFO - PROCESS | 1676 | RSS: Main: 260169728 07:58:12 INFO - PROCESS | 1676 | 07:58:16 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 07:58:17 INFO - PROCESS | 1676 | RSS: Main: 261263360 07:58:17 INFO - PROCESS | 1676 | 07:58:19 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 07:58:19 INFO - PROCESS | 1676 | RSS: Main: 263299072 07:58:19 INFO - PROCESS | 1676 | 07:58:21 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:58:22 INFO - PROCESS | 1676 | RSS: Main: 263815168 07:58:22 INFO - PROCESS | 1676 | 07:58:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 07:58:25 INFO - PROCESS | 1676 | RSS: Main: 264208384 07:58:25 INFO - PROCESS | 1676 | 07:58:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 07:58:29 INFO - PROCESS | 1676 | RSS: Main: 264400896 07:58:29 INFO - PROCESS | 1676 | 07:58:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 07:58:33 INFO - PROCESS | 1676 | RSS: Main: 264355840 07:58:33 INFO - PROCESS | 1676 | 07:58:34 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:58:35 INFO - PROCESS | 1676 | RSS: Main: 264261632 07:58:35 INFO - PROCESS | 1676 | 07:58:37 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:58:37 INFO - PROCESS | 1676 | RSS: Main: 264306688 07:58:37 INFO - PROCESS | 1676 | 07:58:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:58:40 INFO - PROCESS | 1676 | RSS: Main: 264364032 07:58:40 INFO - PROCESS | 1676 | 07:58:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:58:44 INFO - PROCESS | 1676 | RSS: Main: 264892416 07:58:44 INFO - PROCESS | 1676 | 07:58:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:58:48 INFO - PROCESS | 1676 | RSS: Main: 264798208 07:58:48 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 07:58:50 INFO - PROCESS | 1676 | RSS: Main: 266539008 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | __start_tp_report 07:58:50 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 07:58:50 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 07:58:50 INFO - PROCESS | 1676 | |i|pagename|runs| 07:58:50 INFO - PROCESS | 1676 | |0;ai-astar;110;118;119;119;117;117;120;119;166;136 07:58:50 INFO - PROCESS | 1676 | |1;audio-beat-detection;168;145;136;181;145;102;141;104;168;146 07:58:50 INFO - PROCESS | 1676 | |2;audio-dft;204;218;244;234;248;218;200;200;199;198 07:58:50 INFO - PROCESS | 1676 | |3;audio-fft;83;59;57;84;61;54;82;81;71;73 07:58:50 INFO - PROCESS | 1676 | |4;audio-oscillator;88;71;87;71;68;68;85;69;71;89 07:58:50 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;93;89;91;88;122;85;122;131;124;87 07:58:50 INFO - PROCESS | 1676 | |6;imaging-darkroom;134;101;101;151;101;99;101;99;100;102 07:58:50 INFO - PROCESS | 1676 | |7;imaging-desaturate;100;128;104;96;89;113;153;81;127;133 07:58:50 INFO - PROCESS | 1676 | |8;json-parse-financial;56;58;56;57;57;56;59;57;57;56 07:58:50 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;69;76;64;92;89;94;95;60;59;68 07:58:50 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;89;113;108;108;84;90;90;86;97;95 07:58:50 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;143;147;159;156;168;154;159;155;214;146 07:58:50 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;189;242;172;235;240;182;174;175;175;174 07:58:50 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;70;71;69;70;72;70;69;69;81;81 07:58:50 INFO - PROCESS | 1676 | __end_tp_report 07:58:50 INFO - PROCESS | 1676 | __start_cc_report 07:58:50 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,911 07:58:50 INFO - PROCESS | 1676 | __end_cc_report 07:58:50 INFO - PROCESS | 1676 | __startTimestamp1475852330518__endTimestamp 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | ------- Summary: start ------- 07:58:50 INFO - PROCESS | 1676 | Number of tests: 14 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:124.10 Median:119.00 stddev:16.09 (13.5%) stddev-sans-first:16.23 07:58:50 INFO - PROCESS | 1676 | Values: 110.0 118.0 119.0 119.0 117.0 117.0 120.0 119.0 166.0 136.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:145.50 stddev:25.74 (17.7%) stddev-sans-first:25.74 07:58:50 INFO - PROCESS | 1676 | Values: 168.0 145.0 136.0 181.0 145.0 102.0 141.0 104.0 168.0 146.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:216.30 Median:218.00 stddev:19.46 (8.9%) stddev-sans-first:20.12 07:58:50 INFO - PROCESS | 1676 | Values: 204.0 218.0 244.0 234.0 248.0 218.0 200.0 200.0 199.0 198.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:70.50 Median:77.00 stddev:11.85 (15.4%) stddev-sans-first:11.68 07:58:50 INFO - PROCESS | 1676 | Values: 83.0 59.0 57.0 84.0 61.0 54.0 82.0 81.0 71.0 73.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:78.00 stddev:9.20 (11.8%) stddev-sans-first:8.80 07:58:50 INFO - PROCESS | 1676 | Values: 88.0 71.0 87.0 71.0 68.0 68.0 85.0 69.0 71.0 89.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:103.20 Median:87.50 stddev:18.83 (21.5%) stddev-sans-first:19.61 07:58:50 INFO - PROCESS | 1676 | Values: 93.0 89.0 91.0 88.0 122.0 85.0 122.0 131.0 124.0 87.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:108.90 Median:118.00 stddev:18.18 (15.4%) stddev-sans-first:16.86 07:58:50 INFO - PROCESS | 1676 | Values: 134.0 101.0 101.0 151.0 101.0 99.0 101.0 99.0 100.0 102.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:112.40 Median:143.00 stddev:22.50 (15.7%) stddev-sans-first:23.41 07:58:50 INFO - PROCESS | 1676 | Values: 100.0 128.0 104.0 96.0 89.0 113.0 153.0 81.0 127.0 133.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:0.99 (1.7%) stddev-sans-first:1.00 07:58:50 INFO - PROCESS | 1676 | Values: 56.0 58.0 56.0 57.0 57.0 56.0 59.0 57.0 57.0 56.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:76.60 Median:82.50 stddev:14.56 (17.7%) stddev-sans-first:15.18 07:58:50 INFO - PROCESS | 1676 | Values: 69.0 76.0 64.0 92.0 89.0 94.0 95.0 60.0 59.0 68.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:96.00 Median:89.50 stddev:10.24 (11.4%) stddev-sans-first:10.54 07:58:50 INFO - PROCESS | 1676 | Values: 89.0 113.0 108.0 108.0 84.0 90.0 90.0 86.0 97.0 95.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:160.10 Median:157.50 stddev:20.31 (12.9%) stddev-sans-first:20.58 07:58:50 INFO - PROCESS | 1676 | Values: 143.0 147.0 159.0 156.0 168.0 154.0 159.0 155.0 214.0 146.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.80 Median:185.50 stddev:30.26 (16.3%) stddev-sans-first:32.00 07:58:50 INFO - PROCESS | 1676 | Values: 189.0 242.0 172.0 235.0 240.0 182.0 174.0 175.0 175.0 174.0 07:58:50 INFO - PROCESS | 1676 | 07:58:50 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:70.50 stddev:4.73 (6.7%) stddev-sans-first:4.95 07:58:50 INFO - PROCESS | 1676 | Values: 70.0 71.0 69.0 70.0 72.0 70.0 69.0 69.0 81.0 81.0 07:58:50 INFO - PROCESS | 1676 | -------- Summary: end -------- 07:58:50 INFO - PROCESS | 1676 | 07:58:51 INFO - TEST-INFO | 1676: exit 0 07:58:51 INFO - TEST-OK | kraken | took 61158ms 07:58:51 INFO - SUITE-END | took 1176s 07:58:51 INFO - Completed test suite (00:19:36) 07:58:51 INFO - javascript benchmark 07:58:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7462.945318328733, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2873.1955052585276, 3361.6974472297475, 3385.069105995852, 3426.5888093428794, 3442.3115376924616, 4218.093109981353, 5464.0711079116545, 5497.642653227537, 5633.042382794924, 5657.377688454267, 5646.378898321192, 5665.688387138705, 5689.203511508382, 5765.740541675625, 5776.740046697898, 5157.948420515794, 5222.686638801665, 5261.947380526203, 5311.920321195166, 5328.6269961102735, 5252.737363131847, 5310.15037593985, 5330.546903513206, 5353.277307563468, 5361.008213480519, 5135.152699804527, 5198.2722418861385, 5209.531142197183, 5265.104932161504, 5278.155495120763, 5170.767315470775, 5204.9479505204845, 5254.0280048190825, 5277.261183434322, 5300.973495132538, 5149.2791009258735, 5187.221916712486, 5208.739563021872, 5232.293640358579, 5283.471652834724, 5350.250964864922, 5566.721663916828, 5571.164325351189, 5764.798232061899, 5802.506786923117, 6120.69094295093, 6131.202943617346, 6134.5092392608485, 6157.138000679908, 6171.814845554641, 6168.599041062316, 6207.844803879894, 6261.436470717657, 6278.0, 6282.654454005028, 5842.660872073097, 5855.560832937546, 5866.325372582208, 5880.088586269135, 5885.588008839383, 6164.167837341991, 6177.166082578794, 6221.2845522764555, 6292.748290068346, 6295.874082518324, 6154.969225153845, 6165.414285642857, 6166.321704612489, 6174.197354343906, 6186.515236343245, 6108.949222962717, 6165.8458538536, 6276.592021518587, 6278.434940855345, 6283.931731605638, 6132.01899668756, 6174.104754810617, 6176.042713379434, 6199.442050215412, 6227.034809604518, 5847.649141051551, 6187.907181392283, 6258.311585725566, 6272.498200143978, 6272.560920735505, 4839.564439200419, 4941.752912354368, 4968.056069346812, 4974.253861920669, 4993.051320249141, 4400.779961001937, 4415.337699345124, 4431.0251744616135, 4462.598366147063, 4555.476120246148, 5724.513416359656, 5822.301323841166, 5823.5341172706085, 5831.329397119385, 5850.3564607893095, 6233.501319894398, 6239.812805615748, 6275.309715931244, 6286.748530058747, 6303.747850086037, 5656.60403771732, 5713.600047996601, 5742.425757424224, 5770.740316685759, 5804.245448091721, 5415.377231618336, 5418.4039755626845, 5431.0, 5433.592480563973, 5449.12813949774, 6320.336364681734, 6344.809655710336, 6356.0465930109, 6356.968215158895, 6366.618002919932, 6096.315032485105, 6146.477549408171, 6164.167837341902, 6190.566660333733, 6269.843253918689, 6255.655938923403, 6291.748330066746, 6292.748290068346, 6302.212223472066, 6306.432421082125, 6044.184035155374, 6167.506599471942, 6176.197094377702, 6191.9690401547705, 6196.349383314687, 6105.520399749808, 6123.295820980644, 6146.340488068286, 6256.624602523863, 6268.091126786681, 5668.3198016236665, 5916.763329466773, 6136.929937132656, 6144.941444615925, 6145.187169784835, 6093.116498107838, 6102.941543873452, 6106.847328816815, 6108.541859360655, 6139.140520327247, 3824.330742120062, 5428.941356435457, 5910.497607703393, 5941.643501389931, 5942.584019118794, 5084.542391184663, 5213.40045894727, 5909.6158749681135, 6118.143459915526, 6133.84665383369, 5930.7924222652, 5937.079752638348, 5971.641701497924, 5975.0, 6001.699915004319], "value": 5665.959877506684, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.3694461503125, 460.68212933076177, 495.66047257628526, 503.88158782686037, 511.51915205480145, 29198.708012919866, 33170.0, 39790.20419591606, 39840.80079599605, 40663.59336406635, 41142.177156456855, 41695.0, 41714.58285417145, 41734.0, 41741.791291043504, 8182.877256841152, 9915.008499150063, 9927.305088643761, 9934.155596774246, 9986.600535978589, 9803.019698030175, 9881.357711748713, 9891.851622225673, 9905.801883962316, 9908.058734420218, 9870.950645246763, 9905.15806156474, 9908.355956862817, 9913.702588922344, 9928.45393503357, 9858.605655773761, 9912.256580756472, 9927.305088643761, 9930.602775888956, 9962.203023758082, 9025.729228123168, 9209.585568649427, 9749.902500974971, 9806.970268121815, 9836.803263934753, 38316.0, 38321.23357532862, 38493.61506384928, 38554.614453855385, 38581.80709096465, 3468.5317482140094, 3522.6301238370115, 3556.270964452281, 3958.4458175855116, 4176.561461046576, 29410.705892941223, 30269.545956810667, 30761.230969225726, 30791.692083079106, 30847.383052339053, 3137.8117312961067, 3265.248992731685, 3297.1757060734817, 3325.7339412846914, 3333.4333163362285, 439472.0, 441157.0, 445216.0, 445233.99999999674, 445564.0, 53445.7327713359, 53928.92142157214, 53949.46050539445, 53968.73015634897, 54017.0, 55923.16115258275, 56863.431365685814, 56993.14510282349, 57067.0, 57117.71441142851, 15622.9218853905, 15781.053136811828, 15822.52532424029, 15840.683186336439, 15910.04539727597, 11598.304101753922, 12367.257964522158, 12397.00823934083, 12403.379831008593, 12404.317762522967, 11931.58239461632, 12047.81928271059, 14340.21128837924, 14637.487687930872, 14700.264986750619, 6330.905036424458, 6336.746530138835, 7197.424206063502, 7267.854642907112, 7298.2701729826595, 799.4523751230425, 856.3748463621466, 952.2382094324513, 963.7711917305492, 1066.7039546950493, 25007.0, 28127.0, 28252.0, 29932.40135197284, 29990.25024374408, 1012.1953047327393, 1030.7320096774893, 1051.1380667852288, 1075.1506310015156, 1092.9289596176222, 14789.482368117064, 15150.0, 15182.316795744218, 15503.0, 15586.532404027897, 536332.0, 641933.0, 653562.0, 654635.0, 658097.0, 54992.17511737327, 55242.171367429524, 55335.44664553383, 55350.0, 55409.72295138579, 1332.733453309342, 1373.7527245095778, 1379.3930670504947, 1392.463901397969, 1398.1052126638756, 28515.572266415606, 29197.0, 29278.0, 29777.85111074431, 29908.700912990593, 47141.29288060751, 47346.053078938916, 47347.28979065386, 47366.76316618395, 47528.2870756932, 483013.0, 486344.0, 486383.0, 487079.0, 487528.0, 37904.810475947445, 38264.42603361008, 38689.2262154761, 38702.2259554813, 38720.41919371268, 44063.11873762443, 45150.54849451463, 45447.772761137305, 45848.770756146005, 46238.84402889955, 37255.81372093122, 37379.684975367105, 38197.23605527818, 38434.6156538431, 38697.6130238694, 35209.47185792266, 36768.81615592012, 36854.63145368512, 37305.81347093247, 37741.81129094337, 36795.0, 37212.627873720914, 37713.62286377211, 37873.86378408652, 38983.61016389913, 30721.69278307188, 34136.97589072332, 34365.14087147841, 34382.82808585941, 34425.827870860485, 24555.508889821747, 24997.500049998533, 25296.86164122582, 25426.745732542437, 25627.487450251265], "value": 19424.840092172002, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.8763901622354, 339.0, 373.6487701560533, 384.73453317211107, 401.89148929788956, 19682.70475942862, 20611.69082463764, 20719.896400518013, 21484.248051318205, 21578.244761433314, 20263.797362026413, 20456.18175272991, 20559.074841632126, 20652.380428587167, 20831.0, 5579.69311687857, 5956.315023772257, 6082.391760823926, 6183.567150299481, 6322.747090116412, 6092.238470191226, 6227.221597300338, 6230.5638605297645, 6245.188125543673, 6249.531285153609, 6192.225971753531, 7548.547087174759, 7968.521888686669, 7975.122736498981, 8025.478343907627, 6389.520785941073, 6569.638669873155, 7630.771076867675, 7931.682732690685, 8080.030396352417, 6553.6395498247575, 7768.41736869737, 7776.494527855728, 7777.766666999943, 7798.922010779933, 27175.45649087007, 27468.862655686793, 28210.717892821016, 28389.432211355863, 28487.720982321363, 2530.784883284923, 2627.2249686342575, 3039.6504401993834, 3159.162821852211, 3384.170878134848, 16871.831281687148, 16982.66034679312, 17098.401555945606, 17301.30794768208, 17647.5588110297, 1950.2686492565288, 2017.7376940997576, 2021.3632705697657, 2023.1705000949687, 2086.013142281661, 115484.84515154824, 117785.82214177834, 119291.0, 133846.0, 146707.0, 17451.38920137789, 20276.391708248775, 21447.571048578942, 21633.783662163176, 21728.654269146235, 34408.13979650479, 34787.478187827204, 34828.477572836426, 34943.0, 35020.0, 9203.033681463483, 10317.071463568314, 10337.689869303782, 10485.423301718327, 10711.892881071246, 8548.145185481526, 8592.157333013485, 8684.305255579664, 8770.561471926378, 8793.472391656522, 9608.423494590346, 9637.469939153414, 9676.9032309676, 9895.703128906145, 9946.35348702332, 3312.8840490582716, 3878.495795546561, 3884.7280690351968, 3892.805359732002, 4015.15681706839, 827.4414770030326, 880.9163129502687, 964.4936408385654, 968.2109081098882, 972.3533849989819, 18173.0, 20719.378418647466, 21323.57352852934, 21830.23594174196, 21842.017109230124, 929.2751653710117, 931.3620170183447, 938.5494962418078, 950.14486961735, 1001.7345403468087, 10868.130549556017, 10946.507407166537, 11317.981381675689, 11434.599789007345, 11779.941100294443, 581835.0, 592966.0, 634999.0, 635514.0, 642596.0, 87019.0, 110694.44652776685, 129319.0, 133901.0, 134090.0, 991.7173605522536, 1002.0780881588955, 1034.4930983817876, 1039.2569312941284, 1039.740064983754, 18959.715604266225, 19951.700724489434, 20241.79758202458, 20284.79715202829, 20331.288404905757, 51087.0, 54150.0, 56131.71934140303, 56327.43672563222, 56512.044156908625, 79047.60476197582, 79306.0346982637, 79594.6020269895, 79854.0, 80003.19996799958, 7766.378689704923, 7923.445358824827, 8009.038915329965, 8028.397870159644, 8105.27052565272, 10822.837657434988, 11647.71875093756, 12465.626031219077, 12683.429245683781, 12828.615141545768, 10326.741831454274, 10918.781624367628, 11234.887651123385, 11291.378974156172, 11320.0, 10559.630412935818, 10900.781984360427, 10901.454927253448, 11068.391238481961, 11221.382823944761, 8979.865302020344, 10598.25812193139, 10729.796727813584, 11235.887641123483, 11336.979671829575, 6314.684265786784, 7024.894626580503, 7922.524648521107, 8305.46014509055, 8316.41785075039, 8670.393072484865, 9727.319087663796, 9759.828055881724, 10918.0, 11380.715482112682], "value": 10947.681911225303, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.992505037475, 1549.883758718096, 1749.3964582219137, 1764.7617571627827, 1808.8965730904154, 7945.967024286849, 8233.794155146124, 8404.789880252996, 8927.464352138868, 8951.776205594864, 8342.249197572217, 8662.220400163984, 8703.129687031294, 8719.956400218005, 8790.0, 2316.3051084674617, 2370.8696021718797, 2372.6559648850907, 2373.0389192377115, 2374.4538756086113, 2344.6248600223967, 2359.056377449017, 2373.9762602373926, 2392.377981724747, 2398.59223931932, 2371.4545654499475, 2372.988135059331, 2373.169390713254, 2378.2389635316704, 2380.452495925938, 2318.431984163874, 2332.6734257204007, 2342.6603142544323, 2360.8229382796358, 2360.834741568091, 2335.8248131390214, 2350.8119350452093, 2354.329016230366, 2357.905683772647, 2358.50471401006, 8698.956505217497, 8756.211940925283, 8804.295656347476, 8810.559472026373, 8818.162274583969, 5204.9739751301, 5354.544863686591, 5355.6786592804165, 5366.865828354323, 5373.435789242151, 3546.627604101584, 3571.8928432147077, 3592.6586974237407, 3658.1037645776687, 3682.5396825396824, 1061.8247989081792, 1085.46812062089, 1086.940218287994, 1090.1714696830386, 1094.4363652718855, 11209.439528023566, 11332.49003794823, 11335.0, 11424.771504569862, 11431.65705028842, 4281.7002809803635, 4635.768211589474, 4809.495003024702, 4821.879453013703, 4823.300621409876, 9912.851307230398, 9979.251556133318, 10006.149477294512, 10012.749681257881, 10022.398656080659, 5245.790168393221, 5323.653962492425, 5331.813386531453, 5333.093374126408, 5357.464253574612, 3959.5644479107277, 4346.500152482505, 4498.122866041156, 4690.507496712796, 4868.342773725572, 3289.0, 3864.381698928158, 3978.8806335809973, 4016.4376987221813, 4025.1345960618023, 1288.207752232389, 1317.117531254062, 1317.1767645221737, 1328.2030781531005, 1330.0755974597562, 810.5379933437883, 812.175641723651, 924.6209054287709, 925.5048549026238, 941.0685714000838, 5822.621529600647, 5826.242588463472, 5870.0, 6036.366181550961, 6085.1785009023215, 438.6184019902705, 442.2989561544923, 455.4170661553217, 458.8187665871962, 461.0295328333876, 3863.6909047276713, 4002.459667944848, 4060.3503439449546, 4101.979490102531, 4119.434614846339, 8831.470111793313, 9011.414258073204, 10394.532246048946, 10603.84094238587, 10623.990720881504, 10600.88690687482, 10606.257561970588, 10606.348412896776, 10611.681649550526, 10643.627473038407, 6149.600275982137, 8059.032916049994, 8123.106458289583, 8123.781432785011, 8151.429399941947, 5948.434898684618, 5982.551308651781, 6037.667928263988, 6097.176881120992, 6185.257769067741, 9397.342186046912, 9605.903940960501, 9870.901290987285, 9890.0, 9931.404115753077, 9878.506074696093, 9971.700848974542, 10022.24833137503, 10098.394096354243, 10214.897851021395, 5964.463198312173, 7411.221821708643, 7478.364339031244, 7529.058867641545, 7586.393088553013, 8321.08468068524, 8377.70678026266, 8392.20274073962, 8394.11861754507, 8409.789755256168, 6353.555251132377, 7289.307515785992, 7400.370968467741, 7458.5151965122095, 7548.773536793905, 7140.46446516503, 7276.745313913988, 7311.4516411268305, 7326.0, 7375.078115235596, 6271.62370257786, 6360.554761166674, 6657.3009833966735, 6720.529562930548, 6785.93214067853, 5026.924596131134, 5055.974720126523, 5064.797408103635, 5094.2613321068975, 5120.74396280177, 4920.384951881015, 5022.121128802518, 5077.162268225701, 5179.274901513866, 5192.45479224676], "value": 4469.79949381781, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [260.95824668053103, 304.9801762885412, 311.18004059303735, 313.5469246938175, 315.71585572984327, 5678.744456499458, 5708.200851880735, 5709.400512946143, 5727.195933281014, 5753.626014309072, 4735.90528189436, 5045.896773484308, 5201.713905735183, 5590.413006634306, 5721.256236689224, 3491.9175055732676, 3552.591451983016, 4057.6348128668415, 4218.683598730092, 4240.173166232586, 3806.838914131189, 3970.325044742386, 4273.209456250588, 4276.722013069156, 4287.306513926997, 3729.645683660048, 3939.8771350165207, 4241.215375155606, 4241.27898257297, 4283.821948964044, 3711.2948539777626, 3807.971847601132, 3948.5853985331414, 4159.896002599929, 4269.4022836802715, 3047.527633216855, 3652.5616925969052, 3673.155174309897, 3696.890932720173, 3701.722370822199, 4616.792244349012, 4687.015726697397, 5380.4350543192795, 5487.2317875497465, 5500.257465242181, 3736.9439458408146, 4325.0, 4871.147549178915, 4904.975475122602, 5047.848564543069, 5117.872053198663, 5687.004774164546, 5920.644761314335, 5924.703764811742, 5985.670788106652, 3411.812350320731, 3576.3741345264475, 3911.7457365271166, 4050.2911990401562, 4070.653994410449, 5364.383095943938, 5679.432056794329, 6068.271807383142, 6210.975189093794, 6286.9999999999545, 3155.911210632328, 3156.3845050214986, 3630.5098811660614, 4060.573639767782, 4100.835966561363, 3300.7029367357054, 3542.8051457170104, 3776.5263752242327, 4198.958010419857, 4200.642945349679, 4874.5856602188505, 4900.558949694545, 4917.852464426073, 4920.769807548113, 4944.703317800943, 5726.0838265877255, 5915.792947246911, 5921.703914804329, 5941.58408911372, 5962.568983443943, 1247.2454165230088, 1430.7782293744485, 1432.8495507971722, 1439.7264519741425, 1441.704450587627, 735.3822788857385, 804.9718259860994, 871.6818361298081, 894.5571941888677, 905.4024343933104, 4899.579122054564, 4966.503349665005, 5342.759575819097, 5385.542228910508, 5516.620844788803, 5353.330833645794, 5388.0, 5502.944970550323, 6051.818445446644, 6152.477039451607, 4741.312509686076, 5209.609279304067, 5250.684958902478, 5618.0, 5665.745041473144, 2827.9575806362923, 3035.711607397294, 3170.682931706811, 3243.854026568763, 3253.755968302387, 5386.353637563518, 6267.781026352824, 6416.967915160394, 6452.838679033061, 6490.65731148459, 5250.65870718402, 5529.751161197756, 5971.5222782177325, 6260.342664020303, 6315.494760419156, 6414.679266036679, 6424.196975378078, 6433.228012638513, 6445.194350706068, 6464.935350646621, 5915.953284058627, 6071.726772295169, 6103.755849765959, 6166.414190651881, 6185.938140618537, 6049.51969692424, 6099.512039036955, 6121.510279177566, 6130.356312587114, 6186.381361863778, 4972.864063526263, 6291.937080629135, 6404.423601875854, 6407.796101949026, 6458.22501299847, 3285.507173923835, 3386.6444023378176, 3487.197944472735, 3509.9294715112137, 3521.3485505180597, 4172.0, 4740.478547359857, 5093.694378337312, 5169.896602067863, 5196.350456192976, 4315.892102697458, 4490.461144662661, 4837.274408838703, 5099.3370861787735, 5106.412762532208, 4116.814743336617, 4832.782524786323, 5075.644704870623, 5123.231515272739, 5129.0, 3943.5662077170914, 4138.006878349234, 4626.537346265347, 4653.906332011907, 4985.526374994515, 4993.725345106054, 5375.489328513785, 5751.4536119068625, 5835.124731290341, 5958.791442299499, 5795.594308398372, 5843.824685259449, 5867.73595188209, 5873.853153671193, 5892.381299963442], "value": 4150.3246297836895, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [617.4751461257929, 633.5596760251625, 684.5447777228144, 770.1374460604125, 793.8372633610109, 12375.133740638148, 12633.0, 13984.160950342974, 15160.848391516081, 16271.186440677979, 12405.697401772819, 14630.34163462644, 14793.0, 14796.0, 14848.406063757437, 666.1273731411866, 689.1557841643996, 735.3602365941638, 830.1076342931373, 853.7652145659962, 832.2842355574202, 843.3379796859435, 858.2661824140332, 860.1785295043219, 862.3489265604492, 885.1060428966758, 895.7805337692242, 900.617237673986, 902.4720538484961, 904.5703290936818, 877.3025444771422, 889.7063968890283, 898.0166717444388, 898.0346127912455, 898.6450352110912, 654.6464908949162, 665.5806841689705, 667.1194023888469, 676.1345477788439, 677.664556044761, 14994.925025374803, 15092.849071509254, 15127.168005759679, 15141.242937853063, 15160.0, 14534.418623255056, 14535.055221410681, 14569.490067847575, 14659.92670036643, 14761.77857332141, 11800.46897889597, 12038.4582693779, 12327.198732082386, 12345.444454999457, 12542.74914501714, 3149.2599239178776, 3723.210771001618, 3869.4582758413844, 3869.825857836376, 3943.191645712651, 118317.0, 119550.40224798907, 119668.0, 119739.0, 123308.38345808303, 11285.266457680225, 11289.604863829727, 11348.602798901999, 11365.431728413547, 11848.0, 23866.0, 24054.0, 24144.7585524146, 24199.51600968006, 24206.273811785675, 7852.528848269122, 7869.960650196713, 7917.564533950573, 7931.762047138595, 8107.756767296991, 4062.410950412174, 4285.785710714452, 4338.414314067623, 4384.0355121873135, 4428.1180498288095, 3623.438367053111, 3667.2298817248143, 3692.446133080006, 3727.1613886875234, 3738.775673459601, 605.742559412248, 648.8929326661142, 651.5146216069058, 662.592505609047, 672.4754691340762, 640.7244884699624, 656.3797211634959, 674.3256743256743, 678.7692184657142, 678.8235058884726, 9160.083991600786, 9232.815343693088, 9262.814743705088, 9631.133198012212, 9688.515574221261, 573.5755540899704, 606.4299558415164, 644.6776611694153, 658.3646780856496, 669.8727241824038, 4381.18947994622, 4618.237990731559, 4652.232381657056, 4786.95213047865, 4842.104210721028, 91040.26879193635, 138197.3090134563, 145818.27090864477, 155989.0, 157785.0, 101114.49442752739, 111582.0, 131982.0, 138815.0, 140525.29737351247, 120014.0, 121110.39444802896, 121444.0, 123574.0, 124498.37750811188, 8813.515256660945, 9032.32257580671, 9036.0, 9086.415808366535, 9310.115539023782, 49842.75078624584, 60001.39998599958, 60679.69660151671, 63149.789757358274, 64288.678556606916, 98946.0, 102263.0, 102916.48541757243, 103019.484902575, 103633.48183259036, 12037.398130093636, 12134.878651213376, 12405.069619778373, 12593.992480601346, 12630.936845315715, 13001.544945927226, 13264.0, 13285.867141328463, 13380.866191337962, 13749.931250343685, 12035.45840437165, 12136.271823690608, 12337.938310308391, 12554.309512976874, 12641.431135598736, 12167.634970950885, 12448.502059917502, 12536.435860386122, 12669.556565520532, 12727.172733772275, 11833.349165795964, 12295.200811947196, 12386.876131238572, 12497.812532812197, 12520.561780337644, 10216.233782466195, 10310.07209351132, 10521.000504952017, 10592.89407105919, 10714.732131696459, 9645.987171347188, 9748.268879834182, 10310.484475776331, 10481.475926203811, 10592.205584581034], "value": 7728.42200777943, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1613.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 118.0, 119.0, 119.0, 117.0, 117.0, 120.0, 119.0, 166.0, 136.0], "value": 124.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 145.0, 136.0, 181.0, 145.0, 102.0, 141.0, 104.0, 168.0, 146.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 218.0, 244.0, 234.0, 248.0, 218.0, 200.0, 200.0, 199.0, 198.0], "value": 216.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 57.0, 84.0, 61.0, 54.0, 82.0, 81.0, 71.0, 73.0], "value": 70.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 87.0, 71.0, 68.0, 68.0, 85.0, 69.0, 71.0, 89.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 91.0, 88.0, 122.0, 85.0, 122.0, 131.0, 124.0, 87.0], "value": 103.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 101.0, 101.0, 151.0, 101.0, 99.0, 101.0, 99.0, 100.0, 102.0], "value": 108.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 128.0, 104.0, 96.0, 89.0, 113.0, 153.0, 81.0, 127.0, 133.0], "value": 112.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 56.0, 57.0, 57.0, 56.0, 59.0, 57.0, 57.0, 56.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 64.0, 92.0, 89.0, 94.0, 95.0, 60.0, 59.0, 68.0], "value": 76.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 113.0, 108.0, 108.0, 84.0, 90.0, 90.0, 86.0, 97.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 147.0, 159.0, 156.0, 168.0, 154.0, 159.0, 155.0, 214.0, 146.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 242.0, 172.0, 235.0, 240.0, 182.0, 174.0, 175.0, 175.0, 174.0], "value": 195.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 69.0, 70.0, 72.0, 70.0, 69.0, 69.0, 81.0, 81.0], "value": 72.2, "unit": "score"}]}]} 07:58:51 INFO - Return code: 0 07:58:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:58:51 ERROR - # TBPL SUCCESS # 07:58:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:58:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:58:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:58:51 INFO - 2016-10-07 07:58:51.804 screenresolution[1681:11292] starting screenresolution argv=screenresolution get 07:58:51 INFO - 2016-10-07 07:58:51.808 screenresolution[1681:11292] Display 0: 1600x1200x32@60 07:58:51 INFO - 2016-10-07 07:58:51.814 screenresolution[1682:11295] starting screenresolution argv=screenresolution list 07:58:51 INFO - Available Modes on Display 0 07:58:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:58:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:58:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:58:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:58:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:58:52 INFO - Intel Iris: 07:58:52 INFO - Chipset Model: Intel Iris 07:58:52 INFO - Type: GPU 07:58:52 INFO - Bus: Built-In 07:58:52 INFO - VRAM (Dynamic, Max): 1536 MB 07:58:52 INFO - Vendor: Intel (0x8086) 07:58:52 INFO - Device ID: 0x0a2e 07:58:52 INFO - Revision ID: 0x0009 07:58:52 INFO - Displays: 07:58:52 INFO - Display: 07:58:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:58:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:58:52 INFO - Display Serial Number: 1600x1200 60 07:58:52 INFO - Main Display: Yes 07:58:52 INFO - Mirror: Off 07:58:52 INFO - Online: Yes 07:58:52 INFO - Rotation: Supported 07:58:52 INFO - Return code: 0 07:58:52 INFO - Running post-action listener: _resource_record_post_action 07:58:52 INFO - [mozharness: 2016-10-07 14:58:52.056396Z] Finished run-tests step (success) 07:58:52 INFO - Running post-run listener: _resource_record_post_run 07:58:52 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 32764416; Write bytes: 709865984; Read time: 193; Write time: 1981 07:58:52 INFO - TinderboxPrint: CPU usage
54.6% 07:58:52 INFO - TinderboxPrint: I/O read bytes / time
32,764,416 / 193 07:58:52 INFO - TinderboxPrint: I/O write bytes / time
709,865,984 / 1,981 07:58:52 INFO - TinderboxPrint: CPU idle
2,201.5 (45.4%) 07:58:52 INFO - TinderboxPrint: CPU system
986.4 (20.3%) 07:58:52 INFO - TinderboxPrint: CPU user
1,661.8 (34.3%) 07:58:52 INFO - TinderboxPrint: Swap in / out
436,736,000 / 0 07:58:52 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 394045952; Write bytes: 389612544; Read time: 29683; Write time: 788 07:58:52 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 31842816; Write bytes: 297987584; Read time: 183; Write time: 1138 07:58:52 INFO - Running post-run listener: _upload_blobber_files 07:58:52 INFO - Blob upload gear active. 07:58:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:58:52 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 07:58:52 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'] 07:58:52 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 07:58:52 INFO - (blobuploader) - INFO - Open directory for files ... 07:58:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:58:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:58:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:58:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:58:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:58:54 INFO - (blobuploader) - INFO - Done attempting. 07:58:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:58:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:58:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:58:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:58:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:58:55 INFO - (blobuploader) - INFO - Done attempting. 07:58:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:58:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:58:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:58:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:58:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:58:57 INFO - (blobuploader) - INFO - Done attempting. 07:58:57 INFO - (blobuploader) - INFO - Iteration through files over. 07:58:57 INFO - Return code: 0 07:58:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:58:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:58:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41429ad55f7ef35ba0f24d7e1f4a8ec194756bf72ec8e02261ed0538b83dc89690ee570b37a1ddb122c26856dd82cfe2b1d33fa918bf7bb7f4fd6e1c38b037c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0abc5f8b6f825760454640e01bef86a265752825621e957fe2285f2b5bc8974c681e5891075c257018bd2b6184c0297c5bb1bf562f333712def777a7f21d9a8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2e3653912f71036075462d8b6320cb04d09ac1beea09815f5752d8a65f9b155da5024e519c4c5d2b3a72a230cbd2ea45c935fb3140fc005243929fbc6e118a0"} 07:58:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:58:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:58:57 INFO - Contents: 07:58:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/41429ad55f7ef35ba0f24d7e1f4a8ec194756bf72ec8e02261ed0538b83dc89690ee570b37a1ddb122c26856dd82cfe2b1d33fa918bf7bb7f4fd6e1c38b037c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0abc5f8b6f825760454640e01bef86a265752825621e957fe2285f2b5bc8974c681e5891075c257018bd2b6184c0297c5bb1bf562f333712def777a7f21d9a8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2e3653912f71036075462d8b6320cb04d09ac1beea09815f5752d8a65f9b155da5024e519c4c5d2b3a72a230cbd2ea45c935fb3140fc005243929fbc6e118a0"} 07:58:57 INFO - Running post-run listener: copy_logs_to_upload_dir 07:58:57 INFO - Copying logs to upload dir... 07:58:57 INFO - mkdir: /builds/slave/test/build/upload/logs 07:58:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.069278 ========= master_lag: 1.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-07 07:58:58.892198) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 07:58:58.895677) ========= 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.vgWhXrZVU5/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.kGlU15p77O/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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/41429ad55f7ef35ba0f24d7e1f4a8ec194756bf72ec8e02261ed0538b83dc89690ee570b37a1ddb122c26856dd82cfe2b1d33fa918bf7bb7f4fd6e1c38b037c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0abc5f8b6f825760454640e01bef86a265752825621e957fe2285f2b5bc8974c681e5891075c257018bd2b6184c0297c5bb1bf562f333712def777a7f21d9a8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2e3653912f71036075462d8b6320cb04d09ac1beea09815f5752d8a65f9b155da5024e519c4c5d2b3a72a230cbd2ea45c935fb3140fc005243929fbc6e118a0"} build_url:https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011305 build_url: 'https://queue.taskcluster.net/v1/task/F6xc2pDFQJu8t08XMmKumg/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/41429ad55f7ef35ba0f24d7e1f4a8ec194756bf72ec8e02261ed0538b83dc89690ee570b37a1ddb122c26856dd82cfe2b1d33fa918bf7bb7f4fd6e1c38b037c2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0abc5f8b6f825760454640e01bef86a265752825621e957fe2285f2b5bc8974c681e5891075c257018bd2b6184c0297c5bb1bf562f333712def777a7f21d9a8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2e3653912f71036075462d8b6320cb04d09ac1beea09815f5752d8a65f9b155da5024e519c4c5d2b3a72a230cbd2ea45c935fb3140fc005243929fbc6e118a0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-07 07:58:58.933461) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 07:58:58.933808) ========= 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.vgWhXrZVU5/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.kGlU15p77O/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.004946 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-07 07:58:58.982243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 07:58:58.982564) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-07 07:58:58.982868) ========= ========= Total master_lag: 3.38 =========