builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0140 starttime: 1479456378.16 results: success (0) buildid: 20161117214728 builduid: 88557487dd484f7d81b3057640d563aa revision: 28e2a6dde76ab6ad4464a3662df1bd57af04398a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:18.162584) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:18.163024) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:18.163313) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:18.186483) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:18.186783) ========= 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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-18 00:06:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.18M=0.001s 2016-11-18 00:06:18 (8.18 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104692 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:18.313066) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:18.313375) ========= 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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.019683 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:18.368496) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 00:06:18.368844) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 28e2a6dde76ab6ad4464a3662df1bd57af04398a --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 28e2a6dde76ab6ad4464a3662df1bd57af04398a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Nh4t626o2Z/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.Il3Rz9tahy/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-18 00:06:18,447 truncating revision to first 12 chars 2016-11-18 00:06:18,447 Setting DEBUG logging. 2016-11-18 00:06:18,447 attempt 1/10 2016-11-18 00:06:18,448 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/28e2a6dde76a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-18 00:06:19,227 unpacking tar archive at: mozilla-central-28e2a6dde76a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.029814 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-18 00:06:19.420921) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:19.421257) ========= script_repo_revision: 28e2a6dde76ab6ad4464a3662df1bd57af04398a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:19.421642) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:19.421953) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-18 00:06:19.451801) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-18 00:06:19.452269) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Nh4t626o2Z/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.Il3Rz9tahy/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:06:19 INFO - MultiFileLogger online at 20161118 00:06:19 in /builds/slave/test 00:06:19 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 00:06:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:06:19 INFO - {'append_to_log': False, 00:06:19 INFO - 'base_work_dir': '/builds/slave/test', 00:06:19 INFO - 'blob_upload_branch': 'Firefox', 00:06:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:06:19 INFO - 'branch': 'Firefox', 00:06:19 INFO - 'buildbot_json_path': 'buildprops.json', 00:06:19 INFO - 'config_files': ('talos/mac_config.py',), 00:06:19 INFO - 'default_actions': ('clobber', 00:06:19 INFO - 'read-buildbot-config', 00:06:19 INFO - 'download-and-extract', 00:06:19 INFO - 'populate-webroot', 00:06:19 INFO - 'create-virtualenv', 00:06:19 INFO - 'install', 00:06:19 INFO - 'run-tests'), 00:06:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:06:19 INFO - 'download_minidump_stackwalk': True, 00:06:19 INFO - 'download_symbols': 'ondemand', 00:06:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:06:19 INFO - 'tooltool.py': '/tools/tooltool.py', 00:06:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:06:19 INFO - '/tools/misc-python/virtualenv.py')}, 00:06:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:06:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:06:19 INFO - 'installer_path': 'installer.exe', 00:06:19 INFO - 'log_level': 'info', 00:06:19 INFO - 'log_name': 'talos', 00:06:19 INFO - 'log_to_console': True, 00:06:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:06:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:06:19 INFO - 'opt_config_files': (), 00:06:19 INFO - 'pip_index': False, 00:06:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:06:19 INFO - 'cmd': ('bash', 00:06:19 INFO - '-c', 00:06:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:06:19 INFO - 'enabled': True, 00:06:19 INFO - 'halt_on_failure': False, 00:06:19 INFO - 'name': 'check_screen_resolution'},), 00:06:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:06:19 INFO - 'cmd': ('bash', 00:06:19 INFO - '-c', 00:06:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:06:19 INFO - 'enabled': True, 00:06:19 INFO - 'halt_on_failure': False, 00:06:19 INFO - 'name': 'check_screen_resolution'},), 00:06:19 INFO - 'run_cmd_checks_enabled': True, 00:06:19 INFO - 'sps_profile': False, 00:06:19 INFO - 'sps_profile_interval': 0, 00:06:19 INFO - 'suite': 'dromaeojs-e10s', 00:06:19 INFO - 'system_bits': '32', 00:06:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:06:19 INFO - 'title': 't-yosemite-r7-0140', 00:06:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:06:19 INFO - 'use_talos_json': True, 00:06:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:06:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:06:19 INFO - 'work_dir': 'build'} 00:06:19 INFO - [mozharness: 2016-11-18 08:06:19.659844Z] Running clobber step. 00:06:19 INFO - Running pre-action listener: _resource_record_pre_action 00:06:19 INFO - Running main action method: clobber 00:06:19 INFO - rmtree: /builds/slave/test/build 00:06:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:06:21 INFO - Running post-action listener: _resource_record_post_action 00:06:21 INFO - [mozharness: 2016-11-18 08:06:21.858580Z] Finished clobber step (success) 00:06:21 INFO - [mozharness: 2016-11-18 08:06:21.858709Z] Running read-buildbot-config step. 00:06:21 INFO - Running pre-action listener: _resource_record_pre_action 00:06:21 INFO - Running main action method: read_buildbot_config 00:06:21 INFO - Using buildbot properties: 00:06:21 INFO - { 00:06:21 INFO - "project": "", 00:06:21 INFO - "product": "firefox", 00:06:21 INFO - "script_repo_revision": "production", 00:06:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 00:06:21 INFO - "repository": "", 00:06:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 00:06:21 INFO - "buildid": "20161117214728", 00:06:21 INFO - "pgo_build": "False", 00:06:21 INFO - "basedir": "/builds/slave/test", 00:06:21 INFO - "buildnumber": 28, 00:06:21 INFO - "slavename": "t-yosemite-r7-0140", 00:06:21 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 00:06:21 INFO - "platform": "yosemite_r7", 00:06:21 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 00:06:21 INFO - "branch": "mozilla-central", 00:06:21 INFO - "repo_path": "mozilla-central", 00:06:21 INFO - "revision": "28e2a6dde76ab6ad4464a3662df1bd57af04398a", 00:06:21 INFO - "stage_platform": "macosx64", 00:06:21 INFO - "builduid": "88557487dd484f7d81b3057640d563aa", 00:06:21 INFO - "slavebuilddir": "test" 00:06:21 INFO - } 00:06:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 00:06:21 INFO - Running post-action listener: _resource_record_post_action 00:06:21 INFO - [mozharness: 2016-11-18 08:06:21.864400Z] Finished read-buildbot-config step (success) 00:06:21 INFO - [mozharness: 2016-11-18 08:06:21.864549Z] Running download-and-extract step. 00:06:21 INFO - Running pre-action listener: _resource_record_pre_action 00:06:21 INFO - Running main action method: download_and_extract 00:06:21 INFO - mkdir: /builds/slave/test/build/tests 00:06:21 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')]} 00:06:21 INFO - trying https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 00:06:21 INFO - Downloading https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 00:06:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 00:06:23 INFO - Downloaded 1199 bytes. 00:06:23 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 00:06:23 INFO - Using the following test package requirements: 00:06:23 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 00:06:23 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:06:23 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 00:06:23 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:06:23 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 00:06:23 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:06:23 INFO - u'jsshell-mac.zip'], 00:06:23 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:06:23 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 00:06:23 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 00:06:23 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:06:23 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 00:06:23 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:06:23 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 00:06:23 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:06:23 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 00:06:23 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 00:06:23 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 00:06:23 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 00:06:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 00:06:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:06:23 INFO - Fetch https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 00:06:23 INFO - Http code: 200 00:06:23 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 00:06:23 INFO - x-amz-cf-id: PY_V2UgOpao0QzUlBaKF1UJcib9W9NWWKZvD86O8kX0YeGK9cbZiEQ== 00:06:23 INFO - x-amz-version-id: BFaPz3kl7LHctEMezEN2.3aUAQ7wmRtd 00:06:24 INFO - Expected file size: 16223066 00:06:24 INFO - Obtained file size: 16223066 00:06:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 00:06:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 00:06:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 00:06:30 INFO - Fetch https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 00:06:31 INFO - Http code: 200 00:06:31 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 00:06:31 INFO - x-amz-cf-id: 0YjwJqWWxe3VOhMExZ1-g7MwJSh8wBH-_HVej-FPQa3MM5ABxdaaEQ== 00:06:31 INFO - x-amz-version-id: BFaPz3kl7LHctEMezEN2.3aUAQ7wmRtd 00:06:31 INFO - Expected file size: 16223066 00:06:31 INFO - Obtained file size: 16223066 00:06:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 00:06:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 00:06:38 INFO - Fetch https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 00:06:38 INFO - Http code: 200 00:06:38 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 00:06:38 INFO - x-amz-cf-id: 7GIy2rqt5sBJktFQGNmWVlwV-PKt1zkIH7umigXggJy88P8AoCPwUg== 00:06:38 INFO - x-amz-version-id: 70gvka9lVaag1fSJ73C23rjrW86k.r6v 00:06:39 INFO - Expected file size: 14409392 00:06:39 INFO - Obtained file size: 14409392 00:06: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')]} 00:06:39 INFO - trying https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:06:39 INFO - Downloading https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 00:06:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 00:06:42 INFO - Downloaded 107369530 bytes. 00:06:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:06:42 INFO - mkdir: /builds/slave/test/properties 00:06:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:06:42 INFO - Writing to file /builds/slave/test/properties/build_url 00:06:42 INFO - Contents: 00:06:42 INFO - build_url:https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 00:06:43 INFO - Running post-action listener: _resource_record_post_action 00:06:43 INFO - Running post-action listener: set_extra_try_arguments 00:06:43 INFO - [mozharness: 2016-11-18 08:06:43.562409Z] Finished download-and-extract step (success) 00:06:43 INFO - [mozharness: 2016-11-18 08:06:43.562538Z] Running populate-webroot step. 00:06:43 INFO - Running pre-action listener: _resource_record_pre_action 00:06:43 INFO - Running main action method: populate_webroot 00:06:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:06:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:06:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:06:43 INFO - '--tppagecycles', 00:06:43 INFO - '7'], 00:06:43 INFO - 'tests': ['tsvgm']}}, 00:06:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:06:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:06:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:06:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:06:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 00:06:43 INFO - 'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:06:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 00:06:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:06:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 00:06:43 INFO - 'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['damp', 'tps']}, 00:06:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 00:06:43 INFO - 'tests': ['damp', 'tps']}, 00:06:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['dromaeo_dom']}, 00:06:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:06:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['basic_compositor_video']}, 00:06:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 00:06:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['a11yr', 00:06:43 INFO - 'ts_paint', 00:06:43 INFO - 'tpaint', 00:06:43 INFO - 'sessionrestore', 00:06:43 INFO - 'sessionrestore_no_auto_restore', 00:06:43 INFO - 'tabpaint']}, 00:06:43 INFO - 'other-e10s': {'tests': ['a11yr', 00:06:43 INFO - 'ts_paint', 00:06:43 INFO - 'tpaint', 00:06:43 INFO - 'sessionrestore', 00:06:43 INFO - 'sessionrestore_no_auto_restore', 00:06:43 INFO - 'tabpaint']}, 00:06:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:06:43 INFO - 'ts_paint', 00:06:43 INFO - 'tpaint', 00:06:43 INFO - 'sessionrestore', 00:06:43 INFO - 'sessionrestore_no_auto_restore', 00:06:43 INFO - 'tabpaint']}, 00:06:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:06:43 INFO - 'ts_paint', 00:06:43 INFO - 'tpaint', 00:06:43 INFO - 'sessionrestore', 00:06:43 INFO - 'sessionrestore_no_auto_restore', 00:06:43 INFO - 'tabpaint']}, 00:06:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['a11yr', 00:06:43 INFO - 'ts_paint', 00:06:43 INFO - 'tpaint', 00:06:43 INFO - 'sessionrestore', 00:06:43 INFO - 'sessionrestore_no_auto_restore', 00:06:43 INFO - 'tabpaint']}, 00:06:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['a11yr', 00:06:43 INFO - 'ts_paint', 00:06:43 INFO - 'tpaint', 00:06:43 INFO - 'sessionrestore', 00:06:43 INFO - 'sessionrestore_no_auto_restore', 00:06:43 INFO - 'tabpaint']}, 00:06:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['tsvgx', 00:06:43 INFO - 'tsvgr_opacity', 00:06:43 INFO - 'tart', 00:06:43 INFO - 'tscrollx', 00:06:43 INFO - 'cart']}, 00:06:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:06:43 INFO - 'tsvgr_opacity', 00:06:43 INFO - 'tart', 00:06:43 INFO - 'tscrollx', 00:06:43 INFO - 'cart']}, 00:06:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 00:06:43 INFO - 'talos_options': ['--disable-e10s'], 00:06:43 INFO - 'tests': ['tp5o']}, 00:06:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 00:06:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 00:06:43 INFO - 'talos_options': ['--disable-e10s', 00:06:43 INFO - '--xperf_path', 00:06:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:06:43 INFO - 'tests': ['tp5n']}, 00:06:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 00:06:43 INFO - 'talos_options': ['--xperf_path', 00:06:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:06:43 INFO - 'tests': ['tp5n']}}, 00:06:43 INFO - 'talos.zip': {'path': '', 00:06:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:06:43 INFO - Running post-action listener: _resource_record_post_action 00:06:43 INFO - [mozharness: 2016-11-18 08:06:43.580612Z] Finished populate-webroot step (success) 00:06:43 INFO - [mozharness: 2016-11-18 08:06:43.580718Z] Running create-virtualenv step. 00:06:43 INFO - Running pre-action listener: _resource_record_pre_action 00:06:43 INFO - Running main action method: create_virtualenv 00:06:43 INFO - Creating virtualenv /builds/slave/test/build/venv 00:06:43 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 00:06:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:06:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:06:43 INFO - Using real prefix '/tools/python27' 00:06:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:06:44 INFO - Installing distribute.............................................................................................................................................................................................done. 00:06:47 INFO - Installing pip.................done. 00:06:47 INFO - Return code: 0 00:06:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 00:06:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 00:06:47 INFO - Reading from file tmpfile_stdout 00:06:47 INFO - Output received: 00:06:47 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) 00:06:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:47 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:06:47 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 00:06:47 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 00:06:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:06:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:06:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:06:47 INFO - 'HOME': '/Users/cltbld', 00:06:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:06:47 INFO - 'LOGNAME': 'cltbld', 00:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:47 INFO - 'MOZ_NO_REMOTE': '1', 00:06:47 INFO - 'NO_EM_RESTART': '1', 00:06:47 INFO - 'PAGER': '/bin/cat', 00:06:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:47 INFO - 'PWD': '/builds/slave/test', 00:06:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:06:47 INFO - 'SHELL': '/bin/bash', 00:06:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:06:47 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:06:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:06:47 INFO - 'USER': 'cltbld', 00:06:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:06:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:47 INFO - 'XPC_FLAGS': '0x0', 00:06:47 INFO - 'XPC_SERVICE_NAME': '0', 00:06:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:06:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:47 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 00:06:47 INFO - Cleaning up... 00:06:47 INFO - Return code: 0 00:06:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 00:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:47 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:06:47 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 00:06:47 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 00:06:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:06:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:06:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:06:47 INFO - 'HOME': '/Users/cltbld', 00:06:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:06:47 INFO - 'LOGNAME': 'cltbld', 00:06:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:47 INFO - 'MOZ_NO_REMOTE': '1', 00:06:47 INFO - 'NO_EM_RESTART': '1', 00:06:47 INFO - 'PAGER': '/bin/cat', 00:06:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:06:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:47 INFO - 'PWD': '/builds/slave/test', 00:06:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:06:47 INFO - 'SHELL': '/bin/bash', 00:06:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:06:47 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:06:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:06:47 INFO - 'USER': 'cltbld', 00:06:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:06:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:06:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:47 INFO - 'XPC_FLAGS': '0x0', 00:06:47 INFO - 'XPC_SERVICE_NAME': '0', 00:06:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:06:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:48 INFO - Downloading/unpacking psutil>=3.1.1 00:06:48 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 00:06:48 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 00:06:48 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 00:06:48 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 00:06:48 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 00:06:48 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 00:06:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:06:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:06:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:06:51 INFO - Installing collected packages: psutil 00:06:51 INFO - Running setup.py install for psutil 00:06:51 INFO - building 'psutil._psutil_osx' extension 00:06:51 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 00:06:51 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 00:06:51 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 00:06:51 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 00:06:52 INFO - building 'psutil._psutil_posix' extension 00:06:52 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 00:06:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:06:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:06:52 INFO - ^ 00:06:52 INFO - 1 warning generated. 00:06:52 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 00:06:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:06:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:06:52 INFO - Successfully installed psutil 00:06:52 INFO - Cleaning up... 00:06:52 INFO - Return code: 0 00:06:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 00:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:06:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:52 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:06:52 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 00:06:52 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 00:06:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:06:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:06:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:06:52 INFO - 'HOME': '/Users/cltbld', 00:06:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:06:52 INFO - 'LOGNAME': 'cltbld', 00:06:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:52 INFO - 'MOZ_NO_REMOTE': '1', 00:06:52 INFO - 'NO_EM_RESTART': '1', 00:06:52 INFO - 'PAGER': '/bin/cat', 00:06:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:06:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:52 INFO - 'PWD': '/builds/slave/test', 00:06:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:06:52 INFO - 'SHELL': '/bin/bash', 00:06:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:06:52 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:06:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:06:52 INFO - 'USER': 'cltbld', 00:06:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:06:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:06:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:52 INFO - 'XPC_FLAGS': '0x0', 00:06:52 INFO - 'XPC_SERVICE_NAME': '0', 00:06:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:06:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 00:06:52 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 00:06:52 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 00:06:52 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 00:06:52 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 00:06:52 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 00:06:52 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 00:06:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 00:06:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:06:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 00:06:55 INFO - Installing collected packages: mozsystemmonitor 00:06:55 INFO - Running setup.py install for mozsystemmonitor 00:06:55 INFO - Successfully installed mozsystemmonitor 00:06:55 INFO - Cleaning up... 00:06:55 INFO - Return code: 0 00:06:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 00:06:55 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')]} 00:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:55 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')]} 00:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:55 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:06:55 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 00:06:55 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 00:06:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:06:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:06:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:06:55 INFO - 'HOME': '/Users/cltbld', 00:06:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:06:55 INFO - 'LOGNAME': 'cltbld', 00:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:55 INFO - 'MOZ_NO_REMOTE': '1', 00:06:55 INFO - 'NO_EM_RESTART': '1', 00:06:55 INFO - 'PAGER': '/bin/cat', 00:06:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:55 INFO - 'PWD': '/builds/slave/test', 00:06:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:06:55 INFO - 'SHELL': '/bin/bash', 00:06:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:06:55 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:06:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:06:55 INFO - 'USER': 'cltbld', 00:06:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:06:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:55 INFO - 'XPC_FLAGS': '0x0', 00:06:55 INFO - 'XPC_SERVICE_NAME': '0', 00:06:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:55 INFO - Downloading/unpacking jsonschema==2.5.1 00:06:55 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 00:06:55 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 00:06:55 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 00:06:55 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 00:06:55 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 00:06:55 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 00:06:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:06:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 00:06:58 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 00:06:58 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 00:06:58 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 00:06:58 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 00:06:58 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 00:06:58 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 00:06:58 INFO - Downloading functools32-3.2.3-2.tar.gz 00:06:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:06:58 INFO - warning: no files found matching '*.txt' 00:06:58 INFO - no previously-included directories found matching 'build' 00:06:58 INFO - no previously-included directories found matching 'dist' 00:06:58 INFO - no previously-included directories found matching '.git*' 00:06:58 INFO - Installing collected packages: jsonschema, functools32 00:06:58 INFO - Running setup.py install for functools32 00:06:58 INFO - warning: no files found matching '*.txt' 00:06:58 INFO - no previously-included directories found matching 'build' 00:06:58 INFO - no previously-included directories found matching 'dist' 00:06:58 INFO - no previously-included directories found matching '.git*' 00:06:58 INFO - Successfully installed jsonschema functools32 00:06:58 INFO - Cleaning up... 00:06:59 INFO - Return code: 0 00:06:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 00:06:59 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')]} 00:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:59 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')]} 00:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:59 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:06:59 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 00:06:59 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 00:06:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:06:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:06:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:06:59 INFO - 'HOME': '/Users/cltbld', 00:06:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:06:59 INFO - 'LOGNAME': 'cltbld', 00:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:59 INFO - 'MOZ_NO_REMOTE': '1', 00:06:59 INFO - 'NO_EM_RESTART': '1', 00:06:59 INFO - 'PAGER': '/bin/cat', 00:06:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:59 INFO - 'PWD': '/builds/slave/test', 00:06:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:06:59 INFO - 'SHELL': '/bin/bash', 00:06:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:06:59 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:06:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:06:59 INFO - 'USER': 'cltbld', 00:06:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:06:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:59 INFO - 'XPC_FLAGS': '0x0', 00:06:59 INFO - 'XPC_SERVICE_NAME': '0', 00:06:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 00:06:59 INFO - Cleaning up... 00:06:59 INFO - Return code: 0 00:06:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:06:59 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')]} 00:06:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:06:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:59 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')]} 00:06:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:06:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:06:59 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:06:59 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 00:06:59 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 00:06:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:06:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:06:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:06:59 INFO - 'HOME': '/Users/cltbld', 00:06:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:06:59 INFO - 'LOGNAME': 'cltbld', 00:06:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:06:59 INFO - 'MOZ_NO_REMOTE': '1', 00:06:59 INFO - 'NO_EM_RESTART': '1', 00:06:59 INFO - 'PAGER': '/bin/cat', 00:06:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:06:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:06:59 INFO - 'PWD': '/builds/slave/test', 00:06:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:06:59 INFO - 'SHELL': '/bin/bash', 00:06:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:06:59 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:06:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:06:59 INFO - 'USER': 'cltbld', 00:06:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:06:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:06:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:06:59 INFO - 'XPC_FLAGS': '0x0', 00:06:59 INFO - 'XPC_SERVICE_NAME': '0', 00:06:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:06:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:06:59 INFO - Downloading/unpacking blobuploader==1.2.4 00:06:59 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 00:06:59 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 00:06:59 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 00:06:59 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 00:06:59 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 00:06:59 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 00:07:02 INFO - Downloading blobuploader-1.2.4.tar.gz 00:07:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:07:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:07:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:07:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:07:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:07:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:07:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:07:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:07:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:07:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:07:03 INFO - Downloading docopt-0.6.1.tar.gz 00:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:07:03 INFO - Installing collected packages: blobuploader, requests, docopt 00:07:03 INFO - Running setup.py install for blobuploader 00:07:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:07:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:07:03 INFO - Running setup.py install for requests 00:07:04 INFO - Running setup.py install for docopt 00:07:04 INFO - Successfully installed blobuploader requests docopt 00:07:04 INFO - Cleaning up... 00:07:04 INFO - Return code: 0 00:07:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:07:04 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')]} 00:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07:04 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')]} 00:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07:04 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:07:04 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 00:07:04 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 00:07:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:07:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:07:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:07:04 INFO - 'HOME': '/Users/cltbld', 00:07:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:07:04 INFO - 'LOGNAME': 'cltbld', 00:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:04 INFO - 'MOZ_NO_REMOTE': '1', 00:07:04 INFO - 'NO_EM_RESTART': '1', 00:07:04 INFO - 'PAGER': '/bin/cat', 00:07:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:04 INFO - 'PWD': '/builds/slave/test', 00:07:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:07:04 INFO - 'SHELL': '/bin/bash', 00:07:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:07:04 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:07:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:07:04 INFO - 'USER': 'cltbld', 00:07:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:07:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:04 INFO - 'XPC_FLAGS': '0x0', 00:07:04 INFO - 'XPC_SERVICE_NAME': '0', 00:07:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:07:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:07:04 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-kYMUWv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:07:04 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-48fP4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:07:04 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-ejQkvy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:07:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:07:04 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-YblBLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-WfBCoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-fZ6YMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-nQiFqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-WMYP9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-LuwDm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-QcJRot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-qBosMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-k5EDIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-qHEkKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:07:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:07:05 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-6dlZdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:07:06 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-GUEXNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:07:06 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-HOXQ0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:07:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:07:06 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-WfMxzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:07:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:07:06 INFO - Running setup.py install for manifestparser 00:07:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:07:06 INFO - Running setup.py install for mozcrash 00:07:06 INFO - Running setup.py install for mozdebug 00:07:06 INFO - Running setup.py install for mozdevice 00:07:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:07:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:07:07 INFO - Running setup.py install for mozfile 00:07:07 INFO - Running setup.py install for mozhttpd 00:07:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:07:07 INFO - Running setup.py install for mozinfo 00:07:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:07:07 INFO - Running setup.py install for mozInstall 00:07:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:07:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:07:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:07:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:07:07 INFO - Running setup.py install for mozleak 00:07:07 INFO - Running setup.py install for mozlog 00:07:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:07:07 INFO - Running setup.py install for moznetwork 00:07:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:07:08 INFO - Running setup.py install for mozprocess 00:07:08 INFO - Running setup.py install for mozprofile 00:07:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:07:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:07:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:07:08 INFO - Running setup.py install for mozrunner 00:07:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:07:08 INFO - Running setup.py install for mozscreenshot 00:07:08 INFO - Running setup.py install for moztest 00:07:08 INFO - Running setup.py install for mozversion 00:07:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:07:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:07:08 INFO - Cleaning up... 00:07:08 INFO - Return code: 0 00:07:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:07:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 00:07:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:07:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07:08 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:07:08 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 00:07:08 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 00:07:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:07:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:07:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:07:08 INFO - 'HOME': '/Users/cltbld', 00:07:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:07:08 INFO - 'LOGNAME': 'cltbld', 00:07:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:08 INFO - 'MOZ_NO_REMOTE': '1', 00:07:08 INFO - 'NO_EM_RESTART': '1', 00:07:08 INFO - 'PAGER': '/bin/cat', 00:07:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:07:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:08 INFO - 'PWD': '/builds/slave/test', 00:07:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:07:08 INFO - 'SHELL': '/bin/bash', 00:07:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:07:08 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:07:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:07:08 INFO - 'USER': 'cltbld', 00:07:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:07:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:07:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:08 INFO - 'XPC_FLAGS': '0x0', 00:07:08 INFO - 'XPC_SERVICE_NAME': '0', 00:07:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:07:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:07:09 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-Y0krYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:07:09 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)) 00:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:07:09 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-2QY2Fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:07:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:07:09 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-9ci5ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:07:09 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)) 00:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:07:09 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-gfEf4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:07:09 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)) 00:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:07:09 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-xUC1_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:07:09 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)) 00:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:07:09 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-jmM8Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:07:09 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)) 00:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:07:09 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-UW8b3R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:07:09 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)) 00:07:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:07:09 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-3JTJ1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:07:10 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)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-BhTW0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:07:10 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)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-xeQ_QA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:07:10 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)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-EnRo8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:07:10 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)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-vL3UGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:07:10 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)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-F96V6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:07:10 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)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-3rmmtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:07:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-fE8ZLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:07:10 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)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-qstPaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:07:10 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)) 00:07:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:07:10 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-Nc6s3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:07:11 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)) 00:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 00:07:11 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)) 00:07:11 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)) 00:07:11 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)) 00:07:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:07: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 00:07: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 00:07: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 00:07: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 00:07: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 00:07: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 00:07:13 INFO - Downloading blessings-1.6.tar.gz 00:07:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:07:13 INFO - Installing collected packages: blessings 00:07:13 INFO - Running setup.py install for blessings 00:07:13 INFO - Successfully installed blessings 00:07:13 INFO - Cleaning up... 00:07:13 INFO - Return code: 0 00:07:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:07:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:07:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:07:14 INFO - Reading from file tmpfile_stdout 00:07:14 INFO - Current package versions: 00:07:14 INFO - blessings == 1.6 00:07:14 INFO - blobuploader == 1.2.4 00:07:14 INFO - docopt == 0.6.1 00:07:14 INFO - functools32 == 3.2.3-2 00:07:14 INFO - jsonschema == 2.5.1 00:07:14 INFO - manifestparser == 1.1 00:07:14 INFO - mozInstall == 1.12 00:07:14 INFO - mozcrash == 1.0 00:07:14 INFO - mozdebug == 0.1 00:07:14 INFO - mozdevice == 0.48 00:07:14 INFO - mozfile == 1.2 00:07:14 INFO - mozhttpd == 0.7 00:07:14 INFO - mozinfo == 0.9 00:07:14 INFO - mozleak == 0.1 00:07:14 INFO - mozlog == 3.4 00:07:14 INFO - moznetwork == 0.27 00:07:14 INFO - mozprocess == 0.23 00:07:14 INFO - mozprofile == 0.28 00:07:14 INFO - mozrunner == 6.13 00:07:14 INFO - mozscreenshot == 0.1 00:07:14 INFO - mozsystemmonitor == 0.3 00:07:14 INFO - moztest == 0.7 00:07:14 INFO - mozversion == 1.4 00:07:14 INFO - psutil == 3.1.1 00:07:14 INFO - requests == 1.2.3 00:07:14 INFO - wsgiref == 0.1.2 00:07:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:07: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')]} 00:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07: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')]} 00:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07:14 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:07:14 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 00:07:14 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 00:07:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:07:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:07:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:07:14 INFO - 'HOME': '/Users/cltbld', 00:07:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:07:14 INFO - 'LOGNAME': 'cltbld', 00:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:14 INFO - 'MOZ_NO_REMOTE': '1', 00:07:14 INFO - 'NO_EM_RESTART': '1', 00:07:14 INFO - 'PAGER': '/bin/cat', 00:07:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:14 INFO - 'PWD': '/builds/slave/test', 00:07:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:07:14 INFO - 'SHELL': '/bin/bash', 00:07:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:07:14 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:07:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:07:14 INFO - 'USER': 'cltbld', 00:07:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:07:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:14 INFO - 'XPC_FLAGS': '0x0', 00:07:14 INFO - 'XPC_SERVICE_NAME': '0', 00:07:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:07:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 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)) 00:07:14 INFO - Cleaning up... 00:07:14 INFO - Return code: 0 00:07:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:07: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')]} 00:07:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:07:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07: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')]} 00:07:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:07:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:07:14 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 0x10b0253f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10af9b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94f2d7e5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, '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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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 00:07:14 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 00:07:14 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 00:07:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:07:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:07:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:07:14 INFO - 'HOME': '/Users/cltbld', 00:07:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:07:14 INFO - 'LOGNAME': 'cltbld', 00:07:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:14 INFO - 'MOZ_NO_REMOTE': '1', 00:07:14 INFO - 'NO_EM_RESTART': '1', 00:07:14 INFO - 'PAGER': '/bin/cat', 00:07:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:07:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:14 INFO - 'PWD': '/builds/slave/test', 00:07:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:07:14 INFO - 'SHELL': '/bin/bash', 00:07:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:07:14 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:07:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:07:14 INFO - 'USER': 'cltbld', 00:07:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:07:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:07:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:14 INFO - 'XPC_FLAGS': '0x0', 00:07:14 INFO - 'XPC_SERVICE_NAME': '0', 00:07:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:07:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:07:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 00:07:15 INFO - Cleaning up... 00:07:15 INFO - Return code: 0 00:07:15 INFO - Running post-action listener: _resource_record_post_action 00:07:15 INFO - Running post-action listener: _start_resource_monitoring 00:07:15 INFO - Starting resource monitoring. 00:07:15 INFO - [mozharness: 2016-11-18 08:07:15.254264Z] Finished create-virtualenv step (success) 00:07:15 INFO - [mozharness: 2016-11-18 08:07:15.255308Z] Running install step. 00:07:15 INFO - Running pre-action listener: _resource_record_pre_action 00:07:15 INFO - Running main action method: install 00:07:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:07:15 INFO - Reading from file tmpfile_stdout 00:07:15 INFO - Detecting whether we're running mozinstall >=1.0... 00:07:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:07:15 INFO - Reading from file tmpfile_stdout 00:07:15 INFO - Output received: 00:07:15 INFO - Usage: mozinstall [options] installer 00:07:15 INFO - Options: 00:07:15 INFO - -h, --help show this help message and exit 00:07:15 INFO - -d DEST, --destination=DEST 00:07:15 INFO - Directory to install application into. [default: 00:07:15 INFO - "/builds/slave/test"] 00:07:15 INFO - --app=APP Application being installed. [default: firefox] 00:07:15 INFO - mkdir: /builds/slave/test/build/application 00:07:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 00:07:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 00:07:48 INFO - Reading from file tmpfile_stdout 00:07:48 INFO - Output received: 00:07:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 00:07:48 INFO - Running post-action listener: _resource_record_post_action 00:07:48 INFO - [mozharness: 2016-11-18 08:07:48.796637Z] Finished install step (success) 00:07:48 INFO - [mozharness: 2016-11-18 08:07:48.796793Z] Running run-tests step. 00:07:48 INFO - Running pre-action listener: _resource_record_pre_action 00:07:48 INFO - Running main action method: run_tests 00:07:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:07:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:07:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:07:48 INFO - 2016-11-18 00:07:48.811 screenresolution[1958:10118] starting screenresolution argv=screenresolution get 00:07:48 INFO - 2016-11-18 00:07:48.814 screenresolution[1958:10118] Display 0: 1600x1200x32@60 00:07:48 INFO - 2016-11-18 00:07:48.820 screenresolution[1959:10120] starting screenresolution argv=screenresolution list 00:07:48 INFO - Available Modes on Display 0 00:07:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:07:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:07:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:07:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:07:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:07:49 INFO - Intel Iris: 00:07:49 INFO - Chipset Model: Intel Iris 00:07:49 INFO - Type: GPU 00:07:49 INFO - Bus: Built-In 00:07:49 INFO - VRAM (Dynamic, Max): 1536 MB 00:07:49 INFO - Vendor: Intel (0x8086) 00:07:49 INFO - Device ID: 0x0a2e 00:07:49 INFO - Revision ID: 0x0009 00:07:49 INFO - Displays: 00:07:49 INFO - Display: 00:07:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:07:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:07:49 INFO - Display Serial Number: 1600x1200 60 00:07:49 INFO - Main Display: Yes 00:07:49 INFO - Mirror: Off 00:07:49 INFO - Online: Yes 00:07:49 INFO - Rotation: Supported 00:07:49 INFO - Return code: 0 00:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:07:49 INFO - Python 2.7.3 00:07:49 INFO - Return code: 0 00:07:49 INFO - grabbing minidump binary from tooltool 00:07:49 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')]} 00:07:49 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 0x10ae3bd30>, '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 0x10aca2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ae33b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:07:49 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 00:07:49 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 00:07:49 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 00:07:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:07:49 INFO - Return code: 0 00:07:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:07:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:07:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:07:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:07:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:07:49 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/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--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 00:07:49 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/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0140 --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 00:07:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Nh4t626o2Z/Render', 00:07:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:07:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:07:49 INFO - 'HOME': '/Users/cltbld', 00:07:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:07:49 INFO - 'LOGNAME': 'cltbld', 00:07:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:07:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:07:49 INFO - 'MOZ_NO_REMOTE': '1', 00:07:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:07:49 INFO - 'NO_EM_RESTART': '1', 00:07:49 INFO - 'PAGER': '/bin/cat', 00:07:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:07:49 INFO - 'PWD': '/builds/slave/test', 00:07:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:07:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:07:49 INFO - 'SHELL': '/bin/bash', 00:07:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Il3Rz9tahy/Listeners', 00:07:49 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 00:07:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:07:49 INFO - 'USER': 'cltbld', 00:07:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:07:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:07:49 INFO - 'XPC_FLAGS': '0x0', 00:07:49 INFO - 'XPC_SERVICE_NAME': '0', 00:07:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:07:49 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/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--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 00:07:49 INFO - mozversion application_buildid: 20161117214728 00:07:49 INFO - mozversion application_changeset: 28e2a6dde76ab6ad4464a3662df1bd57af04398a 00:07:49 INFO - mozversion application_display_name: Nightly 00:07:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:07:49 INFO - mozversion application_name: Firefox 00:07:49 INFO - mozversion application_remotingname: firefox 00:07:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 00:07:49 INFO - mozversion application_vendor: Mozilla 00:07:49 INFO - mozversion application_version: 53.0a1 00:07:49 INFO - mozversion platform_buildid: 20161117214728 00:07:49 INFO - mozversion platform_changeset: 28e2a6dde76ab6ad4464a3662df1bd57af04398a 00:07:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 00:07:49 INFO - mozversion platform_version: 53.0a1 00:07:49 INFO - using testdate: 1479456469 00:07:49 INFO - actual date: 1479456469 00:07:49 INFO - starting webserver on 'localhost:49327' 00:07:49 INFO - SUITE-START | Running 2 tests 00:07:49 INFO - TEST-START | dromaeo_css 00:07:49 INFO - operating with platform_type : mac_ 00:07:49 INFO - Initialising browser for dromaeo_css test... 00:07:49 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpwA_sTw/profile http://localhost:49327/getInfo.html) 00:07:52 INFO - PROCESS | 1969 | 2016-11-18 00:07:52.350 plugin-container[1970:10268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8937, name = 'com.apple.tsm.portname' 00:07:52 INFO - PROCESS | 1969 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:07:52 INFO - PROCESS | 1969 | 2016-11-18 00:07:52.353 plugin-container[1970:10268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 00:07:52 INFO - PROCESS | 1969 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:07:52 INFO - PROCESS | 1969 | 2016-11-18 00:07:52.353 plugin-container[1970:10268] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:07:52 INFO - PROCESS | 1969 | __metrics Screen width/height:1600/1200 00:07:52 INFO - PROCESS | 1969 | colorDepth:24 00:07:52 INFO - PROCESS | 1969 | Browser inner width/height: 1024/689 00:07:52 INFO - PROCESS | 1969 | __metrics 00:07:52 ERROR - PROCESS | 1969 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 00:07:53 INFO - TEST-INFO | 1969: exit 0 00:07:53 INFO - Browser initialized. 00:07:53 INFO - Running cycle 1/1 for dromaeo_css test... 00:07:53 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpwA_sTw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:07:55 INFO - PROCESS | 1971 | 2016-11-18 00:07:55.937 plugin-container[1972:10391] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 00:07:55 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:07:55 INFO - PROCESS | 1971 | 2016-11-18 00:07:55.938 plugin-container[1972:10391] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 00:07:55 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:07:55 INFO - PROCESS | 1971 | 2016-11-18 00:07:55.938 plugin-container[1972:10391] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:08:05 INFO - PROCESS | 1971 | RSS: Main: 242999296 00:08:05 INFO - PROCESS | 1971 | 00:08:55 INFO - PROCESS | 1971 | 1479456535289 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] 00:11:08 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49327/tests/dromaeo/cssquery-ext.html) 00:11:10 INFO - PROCESS | 1971 | RSS: Main: 248078336 00:11:10 INFO - PROCESS | 1971 | 00:14:12 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-ext.html (next: http://localhost:49327/tests/dromaeo/cssquery-jquery.html) 00:14:13 INFO - PROCESS | 1971 | RSS: Main: 259469312 00:14:13 INFO - PROCESS | 1971 | 00:17:15 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49327/tests/dromaeo/cssquery-mootools.html) 00:17:16 INFO - PROCESS | 1971 | RSS: Main: 264024064 00:17:16 INFO - PROCESS | 1971 | 00:20:18 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49327/tests/dromaeo/cssquery-prototype.html) 00:20:18 INFO - PROCESS | 1971 | RSS: Main: 262770688 00:20:18 INFO - PROCESS | 1971 | 00:23:21 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49327/tests/dromaeo/cssquery-yui.html) 00:23:22 INFO - PROCESS | 1971 | RSS: Main: 267120640 00:23:22 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-yui.html (next: http://localhost:49327/tests/dromaeo/cssquery-dojo.html) 00:26:25 INFO - PROCESS | 1971 | RSS: Main: 269135872 00:26:25 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | __start_tp_report 00:26:25 INFO - PROCESS | 1971 | _x_x_mozilla_page_load 00:26:25 INFO - PROCESS | 1971 | _x_x_mozilla_page_load_details 00:26:25 INFO - PROCESS | 1971 | |i|pagename|runs| 00:26:25 INFO - PROCESS | 1971 | |0;dojo.html;2748.477789220048;3282.4748040313552;3301.059198128533;3302.653221411752;3375.628680845105;4086.8160932758096;4937.963027764174;5459.235707001018;5533.3083364579425;5572.359178694452;5549.500544950954;5639.464250896169;5642.943570564293;5667.943320566793;5691.8577035574135;4896.926546101811;5126.5642420394315;5164.2770012198325;5214.739263036852;5232.764525596357;5153.948460515385;5204.167333226685;5235.685858848462;5258.553022993031;5262.2632831403635;5217.739113044351;5222.242774797652;5231.529162375386;5231.633785635007;5234.214867769846;5191.117510023286;5219.869503262411;5225.163973764217;5232.6075544334135;5249.291345668324;5095.286659867622;5181.326427564392;5187.170052791535;5188.740562971836;5207.323048003773;5639.887202255973;5706.087026075849;5709.200711900337;5735.655860648374;5797.159411885253;5981.312149102865;6194.628322300677;6196.411340922605;6198.535109866733;6214.65819379934;5653.519450846642;5685.943140568583;5692.886142277172;5736.512396446307;6260.935640941051;5389.568834493231;5693.117566777156;5755.395683453261;5783.045797443375;5838.941610583925;6248.625082494974;6278.434940855345;6291.716872740738;6300.873982520324;6320.525960552977;6123.050927106306;6138.754449821957;6139.201903752483;6154.323024467305;6162.568620196544;5919.260092488439;6103.6948152592195;6121.510279177655;6128.289244084599;6139.185504090456;4409.36064270679;4986.426560945465;5434.429384914527;6181.938180618136;6316.178896743486;5538.778448862;6094.238220222473;6120;6193.256809182927;6207.875842483125;4236.872893813191;4693.826543364159;4804.8318308859025;4866.4403593586485;4879.926801097987;3990.38149086892;4330.025744207591;4355.912881742347;4419.955800442019;4477.1269602427865;5616.297962754656;5823.82528524145;5832.88334233313;5838.5037271831525;5841.7371218295275;6145.354737752472;6179.011358182669;6179.752809887555;6266.530010249249;6271.184745982993;5610.1584762285165;5780.71096445176;5788.131780232915;5810.448007439287;5828;5265.104932161542;5385.676859388449;5393.379761327419;5412.972935135378;5417.810376636878;6213.440790328892;6218.844528886814;6253;6295.716692748746;6297.811065668037;6206.875862482815;6252.343503932204;6263.592866463665;6275.937240627535;6288.5598008138995;6168.753249869955;6192.102145188832;6266.404691554295;6268.717907694256;6279.560430769985;5120.257562653469;6095.05526643371;6171.413715697002;6188.473979711594;6212.81361559154;6212.937870621417;6229.50163986889;6237.03325984455;6239.968800156152;6244.188255526934;5898.203742494708;5905.793297234576;6069.241344831896;6070.605410648293;6079.1793107931635;5405.837824865261;5947.3457919627945;6038.456538911519;6076.149339092619;6090.056041313604;5704.914426283692;5816.720321529257;5836.824895253149;5890.882182356415;5914.467697907209;6056.152138700673;6063.181470501426;6064.909026364519;6110.175126358063;6113.23584551931;5712.143178523254;5756.165356023437;5786.103154011135;5795.956727789038;5871.94128058714 00:26:25 INFO - PROCESS | 1971 | |1;ext.html;450.75433888530745;457.7070674768147;472.9901659955993;523.1159340714192;529.5975058955192;29033.27416814578;36038.81980590097;39660;39704.8088557343;39764.20471590567;40117.79646610606;40133;40435;40484.797576012155;40557.59442405575;9253.444793312388;9324.953375233114;9405.059494050609;9415.85876211854;9435.481048542328;9281.72154835356;9282.350235483518;9339;9358.625654973794;9361.531923403836;9302.488363140024;9364.204042656349;9382.765430864249;9397.76505587359;9405.623775049024;9210.539473026322;9265.675701350454;9350.766230844216;9368.203702685247;9400.542915848055;9269.258459323239;9368.67209647659;9368.859467107999;9371.859422108673;9391.013943535898;38940.80529597362;38978.41532377017;39036.41445378322;39060.41409378862;39124.608753912384;3207.615086189672;3239.82180980046;3269.264415506516;3361.798292102482;3381.763276570641;29200.41599168005;29645.555316670267;30004.399912001856;30663.08010759681;30771.230719231975;3128.4838001729686;3163.620365556125;3182.7612929030415;3192.170035790688;3224.580804495424;429903.85048074875;433046;433913;435969;437132;55130.44869551333;55487.445125549035;55568.72215638896;55574.16638750422;55574.72212638911;56554;56940.71529642408;57353.42646573564;57587.71206143942;57642.7117864408;15267.92366038185;15416.768748468781;15601.687966240612;15643.530694079196;15649.921750391175;12097.637070887888;12215.083868709882;12230.510779568718;12256.264624122581;12273.38633068343;14410.567682969528;14920.552383428514;15125.697486050218;15134.772978405334;15240.542783716506;6700.497462690318;6839.418649414756;6864.176298844171;6923.9653801730665;6940.70956584326;815.1685281013333;869.2915274051628;908.4594666173616;952.8284908716497;1072.2387105155426;28383.716162838107;28993.98521051753;29079;29175.562366564518;29611.851940740158;911.252772726358;947.251671179774;1016.7051555049101;1053.2942928238099;1109.1681239070697;14291.356888940023;14733.600307970726;14874.058374530541;15178.696426071418;15318.693626127415;630589;630781;635485;636842;637462;52234.47765522372;52264.21603675948;52585.948281034165;52680.47319526832;52824.94350112976;1213.9939300303442;1304.9543265985644;1338.6653336665834;1359.0554564577524;1360.401423373732;21588.92055397255;22792.430189245402;25236;26028.698565071292;27550.724492754816;45631.77184114058;46199.53800461952;46225.30662040005;46338.76830615825;46399.07201856012;468950;469432;471149;472389;473524;33109.337813244085;33692.98921032373;37176.07059823526;37188.6281137196;37445.625543744216;35420.82289588536;42928.14143717046;43719.78140109279;44448.33327500155;44666.55333446624;35707.92876213718;36911;38045.619543804205;38053.809730951165;38177;36721;36891.81554092212;36981.63018369889;37984.430233545965;38199.80900095482;31524.00521950411;35789.82105089458;36529;37076.629233708394;37248.6275137256;27100.05149819747;33179.336413271114;33582.49626255657;34625.307493849476;34700.82649586736;24610.384740381633;24959.500809984063;25455.745442545336;25480.362990925376;25500.872495637403 00:26:25 INFO - PROCESS | 1971 | |2;jquery.html;297.86149440510167;357.3496236848936;359.9388104022317;369.796611863475;389.3808843938138;18663.973481458514;19425.902870485646;20438.875861827593;20553.38339849803;20725.274615388465;19229.61540769184;19590.314338998138;19765.407037788853;20240.08919598618;20280.898595507002;4679.923617567962;5579.972100139504;5866;5904.675242861641;5924.289085309769;5740.512056475204;5816.941830581683;5863.706814659271;5866.6480011199255;5879.0593505039205;5514.338279406476;5686.0617998030275;5755.395683453219;5816.563757718167;5844;5632.971835140818;5648.943510564883;5696.11710184922;5703.230063941377;5708.800191993302;4779.211430114027;4970.950290497121;4991.32617096691;4993;5040.722760248185;24619.13833015836;24637.753622463726;24731.381715457257;24843.378915527075;25120.36717613349;2197.857139285957;2233.0712404190854;2340.9180678676335;2697.689765676933;2721.2244510314467;14676.853231467656;14989.550313490612;15062.178280912374;15442.536723898302;15444.36668999307;1789.2127463915836;1825.9908700456544;1830.1215416599973;1905.7808352039553;1921.1162865082078;109894.35158472629;113749;113944;114303.28422084133;114680;10198.745031374126;10442.477876106164;10461.26771126014;10889;11147.327900814942;13363.398647060905;13447.394867230998;13469.259190744604;13484.056116071977;13499.932500337633;6948.5483443576;7967.880481792778;8287.8342433151;8317.459365141247;8367.414281000394;8359.58202089893;8364.874526882102;8368.623411946477;8519.148085191431;8524.531150786643;5365.022412561066;6252.811965726497;6498.740050397932;6532.379423954717;6653.667316634246;1611.6696077304125;1618.6438983423627;1651.4302565615085;1752.491777384544;1876.1088482970479;710.925352837955;946.068122898944;966.2801213096147;969.5733877094056;1001.4241810958728;21192.15231390758;21842.303904660574;21881.059152240883;21972.560548788933;22260.214053978474;799.194826160182;924.1082355526949;941.5010044676333;947.9810403792023;968.7820240445843;9289.860652090225;11300.773984520263;11377.260478069065;11477.50792396983;11553.480093395649;535498;541399;557349;572432;577637;109265;113825;115819.42090289494;123808.38095809464;132855.3357233227;947.8767760191131;954.2127744610724;1007.345225603349;1013.0679774607379;1015.8374660054355;15718.742500600154;17123;19332.613347733248;19385.51536211606;19469;53892.19161712499;56189;56733.86532269249;57065;57627;78255.6087219583;83281;83406.58296708477;84009.57995209986;84220.31559368901;5206.479352064764;5220.9216861746345;7659.314950710835;7858.62079305904;8200.712975045844;8442.746717598482;11303.547858085585;12466.376681166086;12867.292298923274;12881.291528965998;9853.261005424765;10299;10554.947225263824;10761.30051546647;10956.56173753041;10620.09729173029;10709.785804283714;10823.567057317934;10929.07102896231;11002.239641657296;10522.579096836042;10882.129429645767;10956.233063685784;11392.886071139183;11411.828822567833;7975.282224599814;7977.441579089408;8104.756857294291;8125.30934870519;8137.918620813716;8628.352873534384;10559.155267578422;10795.784084318113;11057.778844422906;11061.336319820837 00:26:25 INFO - PROCESS | 1971 | |3;mootools.html;1475.5425817996418;1503.1432083712284;1684.898906065636;1797.8471829894459;1819.7543331650231;7464.104307483096;7717.614119294041;7985.321247693946;8450.154984501547;8457.746267611974;7948.801279967989;8237.711680091197;8240.011198656155;8367.790805229872;8369.414141010131;2267.2178098556014;2314.351981445198;2316.6061769499224;2317.1889838556453;2325.9069637214493;2301.919432819852;2333.4166458385403;2344.2732752846673;2363.72817126031;2369.3484291819714;2241.23797908711;2254.301166638339;2286.7713228677167;2293.5642227976714;2293.575688497633;2247.0450058724978;2289.496310811627;2316.30510846747;2316.988415057931;2318.3392733071078;2311.15642790382;2317.9652305215436;2338.590746619335;2340.3447034830274;2340.707411573553;8140.715074972407;8197.016358037074;8232.423730338878;8242.17578242171;8277.130901255341;5049.091163590552;5156.046131465653;5201.037808005531;5207.921881171786;5212.635115541913;3275;3448.896533104011;3548.716102711777;3632.291703117893;3640.453931910208;1003.4230317567428;1032.3908893752646;1079.2175672637275;1093.7211011192096;1094.5949998500525;8719.433236839648;9160.8625870612;9777.90222097777;10798.784024319548;10841.512131954001;3993.6006399360413;4432.667549933768;4652.1626107300335;4653.069386122721;4679.719216847;7867.842643147105;9259.537023148816;9375.718728438158;9408.482533460588;9410.247180225566;5166.689998600096;5179.378474582999;5179.8446046618665;5180.948190518046;5191.480851914854;4228.936565951513;4364.5635436456105;4548.363229147922;4679.953200467951;4760.285957106462;3178.3166619176986;3665.138692407282;3735.9252814943547;3816.904577385532;3823.044238940265;1145.9743529541015;1261.5395380686166;1262.3120399382358;1267.2269915351628;1273.5096987659817;854.5641722721457;863.3395452478859;874.1171416868881;902.120432578244;921.0697195832124;5646.717664116778;5702.344230413555;5728.885422291531;5743.684097374685;5960.493358064527;867.7310033889514;875.0156074416282;895.91936725695;920.4891285336703;920.6869664314165;3719.739618226698;3759.342115129841;3778.7732736035928;3858.2090671412293;3869.400242962289;9790.804183916423;10135.797284054277;10136.493175341204;10156.39061656303;10191.388516689023;8853.645854165761;9497.762555936157;10041.799164016678;10063.144632706155;10104.090631843022;7303.853922921512;7821.178776228414;7826.608669566499;7829.177936316719;7830.647620857075;5260.710660913686;5391.838244852661;5514.558835293247;5532.446755324436;5554.694491802989;7657;8980.326475514494;9181.311401644769;9293.30300227472;9455.290853186176;9333.739945107594;9351.298652600946;9389.201917837061;9498.857517137387;9563.952180239054;6609.3060228675295;6747.224069232101;6888.827779305558;7080.150381954199;7220.1696804866015;7742.458027938215;7957.721479748181;7958.960205199169;7962.7213047543055;7980.561069141253;7067.752628657972;7178.533395329287;7200.855982880208;7258.963705181441;7310.415166786538;6977.860442791014;7023.297670232936;7035.085438892911;7139.250378710369;7150.821229469305;6629.6685165740555;6636.568623039487;6709.329067093252;6718.764843230463;6728.360805723449;4487.977560112178;4704.623630109514;4781.641376896677;4812.8796780080775;4851.684640498356;4292.699511034322;4659.44086709597;4754.881127971828;4841.515848415131;4926.236433352836 00:26:25 INFO - PROCESS | 1971 | |4;prototype.html;299.88904105480975;300.8811519449817;306.8266429467352;306.940146671399;313.17790799152226;4337.544557821423;4485.439320084989;4869.78255436141;5343.358796944367;5469.917951230735;4357.586029327209;4526.642007397784;4931.531504507075;5470.343558772953;5525.778968841241;3553.8400771965194;3700.8149592520267;4013.5785742497055;4193.790310484479;4252.766097864616;3358.966410335902;3517.331706975679;3848.747808102149;4217.978910105445;4260.552641972595;3380.7633465657414;3701.870434534792;4080.9797550612348;4092.7953602319913;4095.692823038284;4074.7351422157467;4078.8164532595806;4085.9591404085877;4088.2641124597562;4109.6438175402045;3527.753057285991;3548.840302186408;3557;3567.8037707926055;3584.8386822593047;4401;4618.284165954283;4836.419629644465;5053.898922021539;5167.527254732383;4075.2868248056766;4674.509176536449;4688.437387513486;4698.976505117486;4701.78841952113;4640.257558790611;4972.83138462461;5566.248556444869;5771.163181338724;5824.475797178275;3771.679407250387;3878.8448462061483;3885.378339465672;3905.2775236581324;3926.6269704378324;4595;5940.821775346746;5974;5997.430244126801;6030.728617212236;3950.5259368875345;3960.960390396059;3972.0069982504374;3974.900627484278;3977.900552486211;3358.8152651604396;3826.0243637872163;3864.265789500042;4015.8996025099605;4028.879133625996;4252.340887162495;4452.465704115527;4661.417322834645;4665.95334046662;4673.392458980311;5464.1257398816;5783.16144159103;5819.068948968229;5819.970900145472;5879.676617786063;1082.6481393547128;1204.403820109034;1231.9137660363688;1249.6126200877757;1295.8121072444442;745.9482130196398;765.5674543882689;861.4615865084322;872.3457406944791;884.9247813935759;5091.338126043591;5112.795488180505;5120.052790233819;5166.3025491558155;5173.068847607392;4810.735409552438;4968.503149685075;5305.010621017119;5616.410276920865;5719.399463056319;4286.635635970915;4536.729715679615;4959.553640172402;4968.1554135797005;5121.644043739847;2601.791856651463;2901.129661101661;2937;2989.3110392628096;3002.324476992703;4208.516020657602;5029.698218106925;5323.920141197885;5713.914356272378;5732.799352022659;5295.4439783822145;5718.768111597388;5747.6838773867;5763.4812866842185;5771.374024818781;5323.0152421802095;5820.679862607512;5842.678652674143;5855.94144058554;5857.7949771757785;5346.732663366894;5572.665640061609;5605.514538647343;5639.96961094014;5663.753974125686;4777.355057067251;4956.504349565159;5042.520960508746;5571.272905399339;5611.831645050655;4825.782839772149;5059.9627076448505;5188.247704082812;5289.047971365191;5843.620164689281;3464.930701385908;3700.130469339757;3725.5343082114737;3738.8691395801015;3769.0388950817364;4971.502849715;5014.222795466709;5409.459054094559;5499.312585926759;5662.745176467151;4694.3427920090535;5222.325266002394;5315.468453154654;5504.394516603091;5515.7242137893745;4622.884427889329;5371.785128594813;5374.274472946102;5414.350277966511;5452.372977107683;4639.953600463952;4789.760511974457;5289.47105289468;5586.469285417878;5645.322561292671;5109.850283686141;5861.179434879035;5870.9412905872105;5890.381509941566;5942.494887934574;5378.220158077135;5678.602497825278;5723.513501352431;5740.827775166752;5742.684152371493 00:26:25 INFO - PROCESS | 1971 | |5;yui.html;671.9832004199891;673.2594146438914;705.7812078255741;714.1251966341233;809.5183365897291;13699.520516781915;14649;14658.267086645665;14715.55853324402;14843.332050057747;13080.869191308084;13244.006699497551;13451.529196478125;13519.256440895746;13587.932060339685;799.7200979657117;819.7827575692446;822.3092602214139;822.6585966823761;822.8601137806558;650.9739610415578;673.6497021548792;803.4094940218935;810.1979040749645;815.6655771133836;857.7684025313162;866.3675516872687;866.9133086691344;867.0852250875324;867.700643278068;745.3329270303076;827.0571548434789;852.910444403335;855.4183155454289;857.995710021449;630.8580569371901;633.8922383194868;636.688022868791;638.3616383616384;638.9904151437732;13986.650333741636;14381.137131772022;14428.927855360656;14580.781288280685;14597.562073137822;9664.806703865954;11038.448077596087;12867.970562355084;13348.93325533366;13382.464701411931;11038.944805276002;11097.57315489868;11187.44062796865;11198.776024479546;11557.479913403917;3303.8436547208526;3519.225770330523;3603.297357015383;3623.0398944279787;3637.1270894985278;55389;69777.65111174462;91835;92129.61805572922;92626;10074.597016119273;10099.920602819366;10138.543765530569;10180.083792458714;10310.587576496857;18930.95879726628;19985.400437986882;20143.39569812908;20292.695609565868;20302.59394812125;6347.143135676624;6386.233651961795;6677.799666010027;7686.192949740309;7748.806279842936;4132.132252227006;4164.167166566638;4193.601607847249;4272.5086615039045;4351.3472979053395;3214.2767877227902;3284.1297056280105;3295.2915123248135;3298.950515742266;3306.9503957440656;579.5827004556712;632.077167335695;635.2186810223451;647.1457675867904;652.7552167937024;574.5920396518519;589.9233099697012;646.3245908026124;668.518666560076;670.8457054877406;8784.516851573097;8788.384813063025;8997.145271199226;9057.864132038025;9119.81760364789;570.1277046119444;620.5873094392269;624.3974564545235;637.1892213785898;655.4101308822098;4393.384926110347;4409;4415.183191109655;4459.375687403766;4510.1656193604285;75516.48967020628;92792.53603731938;118166.4091679536;119230.40384798021;126557.36721316335;68966.31033689599;78281;104147;104298;105406.47296763468;89241;90037;91713;93478;97097.5145124284;8664.523451210243;8679.566021698764;8709.738707838775;8798.12018798115;8887;48749.75625121852;57404;58368.708156458946;58531.70734146302;58583;64642;67430.66284668545;80579.19420805718;80829.19170808452;82596.17403825885;11888.108391870817;12003.879961200277;12117.515299387926;12126.878731212928;12569.057320700802;13831.930840345734;14120.717585648435;14121;14374.209418482083;14448.422063117358;12549.93725031369;12787.808182877077;12838.743225135631;12863.614091577268;12897.613071607866;12351.25892446456;12664.050196235503;12877.678058048623;12936.22382657006;12959.481620735442;12467.812982805446;12942.870571294543;12976.8053479196;13116.540921067717;13194.604161875159;10583.31208471447;10916.454177291262;10931.125509959344;11168.888311117109;11587.304761714324;9322.207612352755;9571.33000689945;9923.900760992297;11066.723331916766;11131 00:26:25 INFO - PROCESS | 1971 | __end_tp_report 00:26:25 INFO - PROCESS | 1971 | __start_cc_report 00:26:25 INFO - PROCESS | 1971 | _x_x_mozilla_cycle_collect,370 00:26:25 INFO - PROCESS | 1971 | __end_cc_report 00:26:25 INFO - PROCESS | 1971 | __startTimestamp1479457585463__endTimestamp 00:26:25 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | ------- Summary: start ------- 00:26:25 INFO - PROCESS | 1971 | Number of tests: 6 00:26:25 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | [#0] dojo.html Cycles:165 Average:5636.87 Median:5797.16 stddev:678.74 (11.7%) stddev-sans-first:641.89 00:26:25 INFO - PROCESS | 1971 | Values: 2748.5 3282.5 3301.1 3302.7 3375.6 4086.8 4938.0 5459.2 5533.3 5572.4 5549.5 5639.5 5642.9 5667.9 5691.9 4896.9 5126.6 5164.3 5214.7 5232.8 5153.9 5204.2 5235.7 5258.6 5262.3 5217.7 5222.2 5231.5 5231.6 5234.2 5191.1 5219.9 5225.2 5232.6 5249.3 5095.3 5181.3 5187.2 5188.7 5207.3 5639.9 5706.1 5709.2 5735.7 5797.2 5981.3 6194.6 6196.4 6198.5 6214.7 5653.5 5685.9 5692.9 5736.5 6260.9 5389.6 5693.1 5755.4 5783.0 5838.9 6248.6 6278.4 6291.7 6300.9 6320.5 6123.1 6138.8 6139.2 6154.3 6162.6 5919.3 6103.7 6121.5 6128.3 6139.2 4409.4 4986.4 5434.4 6181.9 6316.2 5538.8 6094.2 6120.0 6193.3 6207.9 4236.9 4693.8 4804.8 4866.4 4879.9 3990.4 4330.0 4355.9 4420.0 4477.1 5616.3 5823.8 5832.9 5838.5 5841.7 6145.4 6179.0 6179.8 6266.5 6271.2 5610.2 5780.7 5788.1 5810.4 5828.0 5265.1 5385.7 5393.4 5413.0 5417.8 6213.4 6218.8 6253.0 6295.7 6297.8 6206.9 6252.3 6263.6 6275.9 6288.6 6168.8 6192.1 6266.4 6268.7 6279.6 5120.3 6095.1 6171.4 6188.5 6212.8 6212.9 6229.5 6237.0 6240.0 6244.2 5898.2 5905.8 6069.2 6070.6 6079.2 5405.8 5947.3 6038.5 6076.1 6090.1 5704.9 5816.7 5836.8 5890.9 5914.5 6056.2 6063.2 6064.9 6110.2 6113.2 5712.1 5756.2 5786.1 5796.0 5871.9 00:26:25 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | [#1] ext.html Cycles:180 Average:64393.55 Median:39092.51 stddev:138989.59 (355.5%) stddev-sans-first:139296.58 00:26:25 INFO - PROCESS | 1971 | Values: 450.8 457.7 473.0 523.1 529.6 29033.3 36038.8 39660.0 39704.8 39764.2 40117.8 40133.0 40435.0 40484.8 40557.6 9253.4 9325.0 9405.1 9415.9 9435.5 9281.7 9282.4 9339.0 9358.6 9361.5 9302.5 9364.2 9382.8 9397.8 9405.6 9210.5 9265.7 9350.8 9368.2 9400.5 9269.3 9368.7 9368.9 9371.9 9391.0 38940.8 38978.4 39036.4 39060.4 39124.6 3207.6 3239.8 3269.3 3361.8 3381.8 29200.4 29645.6 30004.4 30663.1 30771.2 3128.5 3163.6 3182.8 3192.2 3224.6 429903.9 433046.0 433913.0 435969.0 437132.0 55130.4 55487.4 55568.7 55574.2 55574.7 56554.0 56940.7 57353.4 57587.7 57642.7 15267.9 15416.8 15601.7 15643.5 15649.9 12097.6 12215.1 12230.5 12256.3 12273.4 14410.6 14920.6 15125.7 15134.8 15240.5 6700.5 6839.4 6864.2 6924.0 6940.7 815.2 869.3 908.5 952.8 1072.2 28383.7 28994.0 29079.0 29175.6 29611.9 911.3 947.3 1016.7 1053.3 1109.2 14291.4 14733.6 14874.1 15178.7 15318.7 630589.0 630781.0 635485.0 636842.0 637462.0 52234.5 52264.2 52585.9 52680.5 52824.9 1214.0 1305.0 1338.7 1359.1 1360.4 21588.9 22792.4 25236.0 26028.7 27550.7 45631.8 46199.5 46225.3 46338.8 46399.1 468950.0 469432.0 471149.0 472389.0 473524.0 33109.3 33693.0 37176.1 37188.6 37445.6 35420.8 42928.1 43719.8 44448.3 44666.6 35707.9 36911.0 38045.6 38053.8 38177.0 36721.0 36891.8 36981.6 37984.4 38199.8 31524.0 35789.8 36529.0 37076.6 37248.6 27100.1 33179.3 33582.5 34625.3 34700.8 24610.4 24959.5 25455.7 25480.4 25500.9 00:26:25 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | [#2] jquery.html Cycles:180 Average:33663.88 Median:24787.38 stddev:93144.05 (375.8%) stddev-sans-first:93371.65 00:26:25 INFO - PROCESS | 1971 | Values: 297.9 357.3 359.9 369.8 389.4 18664.0 19425.9 20438.9 20553.4 20725.3 19229.6 19590.3 19765.4 20240.1 20280.9 4679.9 5580.0 5866.0 5904.7 5924.3 5740.5 5816.9 5863.7 5866.6 5879.1 5514.3 5686.1 5755.4 5816.6 5844.0 5633.0 5648.9 5696.1 5703.2 5708.8 4779.2 4971.0 4991.3 4993.0 5040.7 24619.1 24637.8 24731.4 24843.4 25120.4 2197.9 2233.1 2340.9 2697.7 2721.2 14676.9 14989.6 15062.2 15442.5 15444.4 1789.2 1826.0 1830.1 1905.8 1921.1 109894.4 113749.0 113944.0 114303.3 114680.0 10198.7 10442.5 10461.3 10889.0 11147.3 13363.4 13447.4 13469.3 13484.1 13499.9 6948.5 7967.9 8287.8 8317.5 8367.4 8359.6 8364.9 8368.6 8519.1 8524.5 5365.0 6252.8 6498.7 6532.4 6653.7 1611.7 1618.6 1651.4 1752.5 1876.1 710.9 946.1 966.3 969.6 1001.4 21192.2 21842.3 21881.1 21972.6 22260.2 799.2 924.1 941.5 948.0 968.8 9289.9 11300.8 11377.3 11477.5 11553.5 535498.0 541399.0 557349.0 572432.0 577637.0 109265.0 113825.0 115819.4 123808.4 132855.3 947.9 954.2 1007.3 1013.1 1015.8 15718.7 17123.0 19332.6 19385.5 19469.0 53892.2 56189.0 56733.9 57065.0 57627.0 78255.6 83281.0 83406.6 84009.6 84220.3 5206.5 5220.9 7659.3 7858.6 8200.7 8442.7 11303.5 12466.4 12867.3 12881.3 9853.3 10299.0 10554.9 10761.3 10956.6 10620.1 10709.8 10823.6 10929.1 11002.2 10522.6 10882.1 10956.2 11392.9 11411.8 7975.3 7977.4 8104.8 8125.3 8137.9 8628.4 10559.2 10795.8 11057.8 11061.3 00:26:25 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | [#3] mootools.html Cycles:180 Average:5367.88 Median:5196.26 stddev:2893.94 (55.7%) stddev-sans-first:2887.28 00:26:25 INFO - PROCESS | 1971 | Values: 1475.5 1503.1 1684.9 1797.8 1819.8 7464.1 7717.6 7985.3 8450.2 8457.7 7948.8 8237.7 8240.0 8367.8 8369.4 2267.2 2314.4 2316.6 2317.2 2325.9 2301.9 2333.4 2344.3 2363.7 2369.3 2241.2 2254.3 2286.8 2293.6 2293.6 2247.0 2289.5 2316.3 2317.0 2318.3 2311.2 2318.0 2338.6 2340.3 2340.7 8140.7 8197.0 8232.4 8242.2 8277.1 5049.1 5156.0 5201.0 5207.9 5212.6 3275.0 3448.9 3548.7 3632.3 3640.5 1003.4 1032.4 1079.2 1093.7 1094.6 8719.4 9160.9 9777.9 10798.8 10841.5 3993.6 4432.7 4652.2 4653.1 4679.7 7867.8 9259.5 9375.7 9408.5 9410.2 5166.7 5179.4 5179.8 5180.9 5191.5 4228.9 4364.6 4548.4 4680.0 4760.3 3178.3 3665.1 3735.9 3816.9 3823.0 1146.0 1261.5 1262.3 1267.2 1273.5 854.6 863.3 874.1 902.1 921.1 5646.7 5702.3 5728.9 5743.7 5960.5 867.7 875.0 895.9 920.5 920.7 3719.7 3759.3 3778.8 3858.2 3869.4 9790.8 10135.8 10136.5 10156.4 10191.4 8853.6 9497.8 10041.8 10063.1 10104.1 7303.9 7821.2 7826.6 7829.2 7830.6 5260.7 5391.8 5514.6 5532.4 5554.7 7657.0 8980.3 9181.3 9293.3 9455.3 9333.7 9351.3 9389.2 9498.9 9564.0 6609.3 6747.2 6888.8 7080.2 7220.2 7742.5 7957.7 7959.0 7962.7 7980.6 7067.8 7178.5 7200.9 7259.0 7310.4 6977.9 7023.3 7035.1 7139.3 7150.8 6629.7 6636.6 6709.3 6718.8 6728.4 4488.0 4704.6 4781.6 4812.9 4851.7 4292.7 4659.4 4754.9 4841.5 4926.2 00:26:25 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | [#4] prototype.html Cycles:180 Average:4462.53 Median:4944.02 stddev:1371.98 (27.8%) stddev-sans-first:1339.78 00:26:25 INFO - PROCESS | 1971 | Values: 299.9 300.9 306.8 306.9 313.2 4337.5 4485.4 4869.8 5343.4 5469.9 4357.6 4526.6 4931.5 5470.3 5525.8 3553.8 3700.8 4013.6 4193.8 4252.8 3359.0 3517.3 3848.7 4218.0 4260.6 3380.8 3701.9 4081.0 4092.8 4095.7 4074.7 4078.8 4086.0 4088.3 4109.6 3527.8 3548.8 3557.0 3567.8 3584.8 4401.0 4618.3 4836.4 5053.9 5167.5 4075.3 4674.5 4688.4 4699.0 4701.8 4640.3 4972.8 5566.2 5771.2 5824.5 3771.7 3878.8 3885.4 3905.3 3926.6 4595.0 5940.8 5974.0 5997.4 6030.7 3950.5 3961.0 3972.0 3974.9 3977.9 3358.8 3826.0 3864.3 4015.9 4028.9 4252.3 4452.5 4661.4 4666.0 4673.4 5464.1 5783.2 5819.1 5820.0 5879.7 1082.6 1204.4 1231.9 1249.6 1295.8 745.9 765.6 861.5 872.3 884.9 5091.3 5112.8 5120.1 5166.3 5173.1 4810.7 4968.5 5305.0 5616.4 5719.4 4286.6 4536.7 4959.6 4968.2 5121.6 2601.8 2901.1 2937.0 2989.3 3002.3 4208.5 5029.7 5323.9 5713.9 5732.8 5295.4 5718.8 5747.7 5763.5 5771.4 5323.0 5820.7 5842.7 5855.9 5857.8 5346.7 5572.7 5605.5 5640.0 5663.8 4777.4 4956.5 5042.5 5571.3 5611.8 4825.8 5060.0 5188.2 5289.0 5843.6 3464.9 3700.1 3725.5 3738.9 3769.0 4971.5 5014.2 5409.5 5499.3 5662.7 4694.3 5222.3 5315.5 5504.4 5515.7 4622.9 5371.8 5374.3 5414.4 5452.4 4640.0 4789.8 5289.5 5586.5 5645.3 5109.9 5861.2 5870.9 5890.4 5942.5 5378.2 5678.6 5723.5 5740.8 5742.7 00:26:25 INFO - PROCESS | 1971 | 00:26:25 INFO - PROCESS | 1971 | [#5] yui.html Cycles:180 Average:20311.55 Median:4484.77 stddev:30094.99 (671.0%) stddev-sans-first:30143.28 00:26:25 INFO - PROCESS | 1971 | Values: 672.0 673.3 705.8 714.1 809.5 13699.5 14649.0 14658.3 14715.6 14843.3 13080.9 13244.0 13451.5 13519.3 13587.9 799.7 819.8 822.3 822.7 822.9 651.0 673.6 803.4 810.2 815.7 857.8 866.4 866.9 867.1 867.7 745.3 827.1 852.9 855.4 858.0 630.9 633.9 636.7 638.4 639.0 13986.7 14381.1 14428.9 14580.8 14597.6 9664.8 11038.4 12868.0 13348.9 13382.5 11038.9 11097.6 11187.4 11198.8 11557.5 3303.8 3519.2 3603.3 3623.0 3637.1 55389.0 69777.7 91835.0 92129.6 92626.0 10074.6 10099.9 10138.5 10180.1 10310.6 18931.0 19985.4 20143.4 20292.7 20302.6 6347.1 6386.2 6677.8 7686.2 7748.8 4132.1 4164.2 4193.6 4272.5 4351.3 3214.3 3284.1 3295.3 3299.0 3307.0 579.6 632.1 635.2 647.1 652.8 574.6 589.9 646.3 668.5 670.8 8784.5 8788.4 8997.1 9057.9 9119.8 570.1 620.6 624.4 637.2 655.4 4393.4 4409.0 4415.2 4459.4 4510.2 75516.5 92792.5 118166.4 119230.4 126557.4 68966.3 78281.0 104147.0 104298.0 105406.5 89241.0 90037.0 91713.0 93478.0 97097.5 8664.5 8679.6 8709.7 8798.1 8887.0 48749.8 57404.0 58368.7 58531.7 58583.0 64642.0 67430.7 80579.2 80829.2 82596.2 11888.1 12003.9 12117.5 12126.9 12569.1 13831.9 14120.7 14121.0 14374.2 14448.4 12549.9 12787.8 12838.7 12863.6 12897.6 12351.3 12664.1 12877.7 12936.2 12959.5 12467.8 12942.9 12976.8 13116.5 13194.6 10583.3 10916.5 10931.1 11168.9 11587.3 9322.2 9571.3 9923.9 11066.7 11131.0 00:26:25 INFO - PROCESS | 1971 | -------- Summary: end -------- 00:26:25 INFO - PROCESS | 1971 | 00:26:26 INFO - TEST-INFO | 1971: exit 0 00:26:26 INFO - TEST-OK | dromaeo_css | took 1116715ms 00:26:26 INFO - TEST-START | kraken 00:26:26 INFO - operating with platform_type : mac_ 00:26:26 INFO - Initialising browser for kraken test... 00:26:26 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmp06mrEd/profile http://localhost:49327/getInfo.html) 00:26:29 INFO - PROCESS | 1974 | 2016-11-18 00:26:29.022 plugin-container[1975:12392] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 00:26:29 INFO - PROCESS | 1974 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:26:29 INFO - PROCESS | 1974 | 2016-11-18 00:26:29.027 plugin-container[1975:12392] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c27, name = 'com.apple.CFPasteboardClient' 00:26:29 INFO - PROCESS | 1974 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:26:29 INFO - PROCESS | 1974 | 2016-11-18 00:26:29.027 plugin-container[1975:12392] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:26:29 INFO - PROCESS | 1974 | __metrics Screen width/height:1600/1200 00:26:29 INFO - PROCESS | 1974 | colorDepth:24 00:26:29 INFO - PROCESS | 1974 | Browser inner width/height: 1024/689 00:26:29 INFO - PROCESS | 1974 | __metrics 00:26:29 ERROR - PROCESS | 1974 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3179: TypeError: this.tabs is undefined 00:26:30 INFO - TEST-INFO | 1974: exit 0 00:26:30 INFO - Browser initialized. 00:26:30 INFO - Running cycle 1/1 for kraken test... 00:26:30 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmp06mrEd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:26:32 INFO - PROCESS | 1976 | 2016-11-18 00:26:32.687 plugin-container[1977:12511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.tsm.portname' 00:26:32 INFO - PROCESS | 1976 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:26:32 INFO - PROCESS | 1976 | 2016-11-18 00:26:32.687 plugin-container[1977:12511] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 00:26:32 INFO - PROCESS | 1976 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:26:32 INFO - PROCESS | 1976 | 2016-11-18 00:26:32.687 plugin-container[1977:12511] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:26:42 INFO - PROCESS | 1976 | RSS: Main: 242577408 00:26:42 INFO - PROCESS | 1976 | 00:26:45 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49327/tests/kraken/driver.html?testName=audio-beat-detection) 00:26:46 INFO - PROCESS | 1976 | RSS: Main: 254873600 00:26:46 INFO - PROCESS | 1976 | 00:26:49 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49327/tests/kraken/driver.html?testName=audio-dft) 00:26:49 INFO - PROCESS | 1976 | RSS: Main: 262451200 00:26:49 INFO - PROCESS | 1976 | 00:26:53 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49327/tests/kraken/driver.html?testName=audio-fft) 00:26:53 INFO - PROCESS | 1976 | RSS: Main: 265371648 00:26:53 INFO - PROCESS | 1976 | 00:26:55 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49327/tests/kraken/driver.html?testName=audio-oscillator) 00:26:56 INFO - PROCESS | 1976 | RSS: Main: 267595776 00:26:56 INFO - PROCESS | 1976 | 00:26:58 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49327/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:26:59 INFO - PROCESS | 1976 | RSS: Main: 268132352 00:26:59 INFO - PROCESS | 1976 | 00:27:01 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49327/tests/kraken/driver.html?testName=imaging-darkroom) 00:27:02 INFO - PROCESS | 1976 | RSS: Main: 273317888 00:27:02 INFO - PROCESS | 1976 | 00:27:05 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49327/tests/kraken/driver.html?testName=imaging-desaturate) 00:27:06 INFO - PROCESS | 1976 | RSS: Main: 272609280 00:27:06 INFO - PROCESS | 1976 | 00:27:09 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49327/tests/kraken/driver.html?testName=json-parse-financial) 00:27:09 INFO - PROCESS | 1976 | RSS: Main: 272896000 00:27:09 INFO - PROCESS | 1976 | 00:27:11 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49327/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:27:12 INFO - PROCESS | 1976 | RSS: Main: 272011264 00:27:12 INFO - PROCESS | 1976 | 00:27:14 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:27:14 INFO - PROCESS | 1976 | RSS: Main: 272015360 00:27:14 INFO - PROCESS | 1976 | 00:27:17 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:27:17 INFO - PROCESS | 1976 | RSS: Main: 272101376 00:27:17 INFO - PROCESS | 1976 | 00:27:20 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:27:21 INFO - PROCESS | 1976 | RSS: Main: 272547840 00:27:21 INFO - PROCESS | 1976 | 00:27:24 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:27:25 INFO - PROCESS | 1976 | RSS: Main: 272101376 00:27:25 INFO - PROCESS | 1976 | 00:27:26 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49327/tests/kraken/driver.html?testName=ai-astar) 00:27:27 INFO - PROCESS | 1976 | RSS: Main: 273973248 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | __start_tp_report 00:27:27 INFO - PROCESS | 1976 | _x_x_mozilla_page_load 00:27:27 INFO - PROCESS | 1976 | _x_x_mozilla_page_load_details 00:27:27 INFO - PROCESS | 1976 | |i|pagename|runs| 00:27:27 INFO - PROCESS | 1976 | |0;ai-astar;105;116;115;115;116;116;117;119;115;116 00:27:27 INFO - PROCESS | 1976 | |1;audio-beat-detection;150;123;134;194;150;123;146;154;149;148 00:27:27 INFO - PROCESS | 1976 | |2;audio-dft;201;225;197;202;200;201;194;229;183;267 00:27:27 INFO - PROCESS | 1976 | |3;audio-fft;81;50;76;103;86;86;86;64;78;58 00:27:27 INFO - PROCESS | 1976 | |4;audio-oscillator;85;70;87;73;69;74;74;92;58;88 00:27:27 INFO - PROCESS | 1976 | |5;imaging-gaussian-blur;93;89;94;89;102;89;93;90;91;88 00:27:27 INFO - PROCESS | 1976 | |6;imaging-darkroom;98;104;154;104;144;116;97;97;99;97 00:27:27 INFO - PROCESS | 1976 | |7;imaging-desaturate;91;80;154;157;93;92;97;93;98;126 00:27:27 INFO - PROCESS | 1976 | |8;json-parse-financial;58;56;56;58;58;58;56;62;63;88 00:27:27 INFO - PROCESS | 1976 | |9;json-stringify-tinderbox;72;68;82;83;79;69;70;72;99;103 00:27:27 INFO - PROCESS | 1976 | |10;stanford-crypto-aes;90;90;92;90;92;111;80;109;101;84 00:27:27 INFO - PROCESS | 1976 | |11;stanford-crypto-ccm;163;160;167;149;168;175;155;157;220;192 00:27:27 INFO - PROCESS | 1976 | |12;stanford-crypto-pbkdf2;175;181;172;174;179;173;246;175;174;177 00:27:27 INFO - PROCESS | 1976 | |13;stanford-crypto-sha256-iterative;67;71;69;68;72;67;66;68;68;67 00:27:27 INFO - PROCESS | 1976 | __end_tp_report 00:27:27 INFO - PROCESS | 1976 | __start_cc_report 00:27:27 INFO - PROCESS | 1976 | _x_x_mozilla_cycle_collect,973 00:27:27 INFO - PROCESS | 1976 | __end_cc_report 00:27:27 INFO - PROCESS | 1976 | __startTimestamp1479457647142__endTimestamp 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | ------- Summary: start ------- 00:27:27 INFO - PROCESS | 1976 | Number of tests: 14 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#0] ai-astar Cycles:10 Average:115.00 Median:116.00 stddev:3.71 (3.2%) stddev-sans-first:1.27 00:27:27 INFO - PROCESS | 1976 | Values: 105.0 116.0 115.0 115.0 116.0 116.0 117.0 119.0 115.0 116.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:149.50 stddev:20.01 (13.4%) stddev-sans-first:21.19 00:27:27 INFO - PROCESS | 1976 | Values: 150.0 123.0 134.0 194.0 150.0 123.0 146.0 154.0 149.0 148.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#2] audio-dft Cycles:10 Average:209.90 Median:201.50 stddev:24.30 (12.1%) stddev-sans-first:25.56 00:27:27 INFO - PROCESS | 1976 | Values: 201.0 225.0 197.0 202.0 200.0 201.0 194.0 229.0 183.0 267.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#3] audio-fft Cycles:10 Average:76.80 Median:79.50 stddev:15.62 (19.6%) stddev-sans-first:16.49 00:27:27 INFO - PROCESS | 1976 | Values: 81.0 50.0 76.0 103.0 86.0 86.0 86.0 64.0 78.0 58.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:79.50 stddev:10.64 (13.4%) stddev-sans-first:10.88 00:27:27 INFO - PROCESS | 1976 | Values: 85.0 70.0 87.0 73.0 69.0 74.0 74.0 92.0 58.0 88.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#5] imaging-gaussian-blur Cycles:10 Average:91.80 Median:90.50 stddev:4.13 (4.6%) stddev-sans-first:4.36 00:27:27 INFO - PROCESS | 1976 | Values: 93.0 89.0 94.0 89.0 102.0 89.0 93.0 90.0 91.0 88.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#6] imaging-darkroom Cycles:10 Average:111.00 Median:97.00 stddev:20.98 (21.6%) stddev-sans-first:21.72 00:27:27 INFO - PROCESS | 1976 | Values: 98.0 104.0 154.0 104.0 144.0 116.0 97.0 97.0 99.0 97.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#7] imaging-desaturate Cycles:10 Average:108.10 Median:92.50 stddev:27.57 (29.8%) stddev-sans-first:28.54 00:27:27 INFO - PROCESS | 1976 | Values: 91.0 80.0 154.0 157.0 93.0 92.0 97.0 93.0 98.0 126.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:58.00 stddev:9.68 (16.7%) stddev-sans-first:10.20 00:27:27 INFO - PROCESS | 1976 | Values: 58.0 56.0 56.0 58.0 58.0 58.0 56.0 62.0 63.0 88.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#9] json-stringify-tinderbox Cycles:10 Average:79.70 Median:75.50 stddev:12.45 (16.5%) stddev-sans-first:12.89 00:27:27 INFO - PROCESS | 1976 | Values: 72.0 68.0 82.0 83.0 79.0 69.0 70.0 72.0 99.0 103.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:90.00 stddev:10.08 (11.2%) stddev-sans-first:10.59 00:27:27 INFO - PROCESS | 1976 | Values: 90.0 90.0 92.0 90.0 92.0 111.0 80.0 109.0 101.0 84.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#11] stanford-crypto-ccm Cycles:10 Average:170.60 Median:167.50 stddev:21.09 (12.6%) stddev-sans-first:22.19 00:27:27 INFO - PROCESS | 1976 | Values: 163.0 160.0 167.0 149.0 168.0 175.0 155.0 157.0 220.0 192.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.60 Median:176.00 stddev:22.45 (12.8%) stddev-sans-first:23.64 00:27:27 INFO - PROCESS | 1976 | Values: 175.0 181.0 172.0 174.0 179.0 173.0 246.0 175.0 174.0 177.0 00:27:27 INFO - PROCESS | 1976 | 00:27:27 INFO - PROCESS | 1976 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:1.89 (2.8%) stddev-sans-first:1.94 00:27:27 INFO - PROCESS | 1976 | Values: 67.0 71.0 69.0 68.0 72.0 67.0 66.0 68.0 68.0 67.0 00:27:27 INFO - PROCESS | 1976 | -------- Summary: end -------- 00:27:27 INFO - PROCESS | 1976 | 00:27:28 INFO - TEST-INFO | 1976: exit 0 00:27:28 INFO - TEST-OK | kraken | took 62069ms 00:27:28 INFO - SUITE-END | took 1178s 00:27:28 INFO - Completed test suite (00:19:38) 00:27:28 INFO - javascript benchmark 00:27:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7082.232511695108, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2748.477789220048, 3282.4748040313552, 3301.059198128533, 3302.653221411752, 3375.628680845105, 4086.8160932758096, 4937.963027764174, 5459.235707001018, 5533.3083364579425, 5572.359178694452, 5549.500544950954, 5639.464250896169, 5642.943570564293, 5667.943320566793, 5691.8577035574135, 4896.926546101811, 5126.5642420394315, 5164.2770012198325, 5214.739263036852, 5232.764525596357, 5153.948460515385, 5204.167333226685, 5235.685858848462, 5258.553022993031, 5262.2632831403635, 5217.739113044351, 5222.242774797652, 5231.529162375386, 5231.633785635007, 5234.214867769846, 5191.117510023286, 5219.869503262411, 5225.163973764217, 5232.6075544334135, 5249.291345668324, 5095.286659867622, 5181.326427564392, 5187.170052791535, 5188.740562971836, 5207.323048003773, 5639.887202255973, 5706.087026075849, 5709.200711900337, 5735.655860648374, 5797.159411885253, 5981.312149102865, 6194.628322300677, 6196.411340922605, 6198.535109866733, 6214.65819379934, 5653.519450846642, 5685.943140568583, 5692.886142277172, 5736.512396446307, 6260.935640941051, 5389.568834493231, 5693.117566777156, 5755.395683453261, 5783.045797443375, 5838.941610583925, 6248.625082494974, 6278.434940855345, 6291.716872740738, 6300.873982520324, 6320.525960552977, 6123.050927106306, 6138.754449821957, 6139.201903752483, 6154.323024467305, 6162.568620196544, 5919.260092488439, 6103.6948152592195, 6121.510279177655, 6128.289244084599, 6139.185504090456, 4409.36064270679, 4986.426560945465, 5434.429384914527, 6181.938180618136, 6316.178896743486, 5538.778448862, 6094.238220222473, 6120.0, 6193.256809182927, 6207.875842483125, 4236.872893813191, 4693.826543364159, 4804.8318308859025, 4866.4403593586485, 4879.926801097987, 3990.38149086892, 4330.025744207591, 4355.912881742347, 4419.955800442019, 4477.1269602427865, 5616.297962754656, 5823.82528524145, 5832.88334233313, 5838.5037271831525, 5841.7371218295275, 6145.354737752472, 6179.011358182669, 6179.752809887555, 6266.530010249249, 6271.184745982993, 5610.1584762285165, 5780.71096445176, 5788.131780232915, 5810.448007439287, 5828.0, 5265.104932161542, 5385.676859388449, 5393.379761327419, 5412.972935135378, 5417.810376636878, 6213.440790328892, 6218.844528886814, 6253.0, 6295.716692748746, 6297.811065668037, 6206.875862482815, 6252.343503932204, 6263.592866463665, 6275.937240627535, 6288.5598008138995, 6168.753249869955, 6192.102145188832, 6266.404691554295, 6268.717907694256, 6279.560430769985, 5120.257562653469, 6095.05526643371, 6171.413715697002, 6188.473979711594, 6212.81361559154, 6212.937870621417, 6229.50163986889, 6237.03325984455, 6239.968800156152, 6244.188255526934, 5898.203742494708, 5905.793297234576, 6069.241344831896, 6070.605410648293, 6079.1793107931635, 5405.837824865261, 5947.3457919627945, 6038.456538911519, 6076.149339092619, 6090.056041313604, 5704.914426283692, 5816.720321529257, 5836.824895253149, 5890.882182356415, 5914.467697907209, 6056.152138700673, 6063.181470501426, 6064.909026364519, 6110.175126358063, 6113.23584551931, 5712.143178523254, 5756.165356023437, 5786.103154011135, 5795.956727789038, 5871.94128058714], "value": 5592.598784219944, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.75433888530745, 457.7070674768147, 472.9901659955993, 523.1159340714192, 529.5975058955192, 29033.27416814578, 36038.81980590097, 39660.0, 39704.8088557343, 39764.20471590567, 40117.79646610606, 40133.0, 40435.0, 40484.797576012155, 40557.59442405575, 9253.444793312388, 9324.953375233114, 9405.059494050609, 9415.85876211854, 9435.481048542328, 9281.72154835356, 9282.350235483518, 9339.0, 9358.625654973794, 9361.531923403836, 9302.488363140024, 9364.204042656349, 9382.765430864249, 9397.76505587359, 9405.623775049024, 9210.539473026322, 9265.675701350454, 9350.766230844216, 9368.203702685247, 9400.542915848055, 9269.258459323239, 9368.67209647659, 9368.859467107999, 9371.859422108673, 9391.013943535898, 38940.80529597362, 38978.41532377017, 39036.41445378322, 39060.41409378862, 39124.608753912384, 3207.615086189672, 3239.82180980046, 3269.264415506516, 3361.798292102482, 3381.763276570641, 29200.41599168005, 29645.555316670267, 30004.399912001856, 30663.08010759681, 30771.230719231975, 3128.4838001729686, 3163.620365556125, 3182.7612929030415, 3192.170035790688, 3224.580804495424, 429903.85048074875, 433046.0, 433913.0, 435969.0, 437132.0, 55130.44869551333, 55487.445125549035, 55568.72215638896, 55574.16638750422, 55574.72212638911, 56554.0, 56940.71529642408, 57353.42646573564, 57587.71206143942, 57642.7117864408, 15267.92366038185, 15416.768748468781, 15601.687966240612, 15643.530694079196, 15649.921750391175, 12097.637070887888, 12215.083868709882, 12230.510779568718, 12256.264624122581, 12273.38633068343, 14410.567682969528, 14920.552383428514, 15125.697486050218, 15134.772978405334, 15240.542783716506, 6700.497462690318, 6839.418649414756, 6864.176298844171, 6923.9653801730665, 6940.70956584326, 815.1685281013333, 869.2915274051628, 908.4594666173616, 952.8284908716497, 1072.2387105155426, 28383.716162838107, 28993.98521051753, 29079.0, 29175.562366564518, 29611.851940740158, 911.252772726358, 947.251671179774, 1016.7051555049101, 1053.2942928238099, 1109.1681239070697, 14291.356888940023, 14733.600307970726, 14874.058374530541, 15178.696426071418, 15318.693626127415, 630589.0, 630781.0, 635485.0, 636842.0, 637462.0, 52234.47765522372, 52264.21603675948, 52585.948281034165, 52680.47319526832, 52824.94350112976, 1213.9939300303442, 1304.9543265985644, 1338.6653336665834, 1359.0554564577524, 1360.401423373732, 21588.92055397255, 22792.430189245402, 25236.0, 26028.698565071292, 27550.724492754816, 45631.77184114058, 46199.53800461952, 46225.30662040005, 46338.76830615825, 46399.07201856012, 468950.0, 469432.0, 471149.0, 472389.0, 473524.0, 33109.337813244085, 33692.98921032373, 37176.07059823526, 37188.6281137196, 37445.625543744216, 35420.82289588536, 42928.14143717046, 43719.78140109279, 44448.33327500155, 44666.55333446624, 35707.92876213718, 36911.0, 38045.619543804205, 38053.809730951165, 38177.0, 36721.0, 36891.81554092212, 36981.63018369889, 37984.430233545965, 38199.80900095482, 31524.00521950411, 35789.82105089458, 36529.0, 37076.629233708394, 37248.6275137256, 27100.05149819747, 33179.336413271114, 33582.49626255657, 34625.307493849476, 34700.82649586736, 24610.384740381633, 24959.500809984063, 25455.745442545336, 25480.362990925376, 25500.872495637403], "value": 18954.275080104126, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.86149440510167, 357.3496236848936, 359.9388104022317, 369.796611863475, 389.3808843938138, 18663.973481458514, 19425.902870485646, 20438.875861827593, 20553.38339849803, 20725.274615388465, 19229.61540769184, 19590.314338998138, 19765.407037788853, 20240.08919598618, 20280.898595507002, 4679.923617567962, 5579.972100139504, 5866.0, 5904.675242861641, 5924.289085309769, 5740.512056475204, 5816.941830581683, 5863.706814659271, 5866.6480011199255, 5879.0593505039205, 5514.338279406476, 5686.0617998030275, 5755.395683453219, 5816.563757718167, 5844.0, 5632.971835140818, 5648.943510564883, 5696.11710184922, 5703.230063941377, 5708.800191993302, 4779.211430114027, 4970.950290497121, 4991.32617096691, 4993.0, 5040.722760248185, 24619.13833015836, 24637.753622463726, 24731.381715457257, 24843.378915527075, 25120.36717613349, 2197.857139285957, 2233.0712404190854, 2340.9180678676335, 2697.689765676933, 2721.2244510314467, 14676.853231467656, 14989.550313490612, 15062.178280912374, 15442.536723898302, 15444.36668999307, 1789.2127463915836, 1825.9908700456544, 1830.1215416599973, 1905.7808352039553, 1921.1162865082078, 109894.35158472629, 113749.0, 113944.0, 114303.28422084133, 114680.0, 10198.745031374126, 10442.477876106164, 10461.26771126014, 10889.0, 11147.327900814942, 13363.398647060905, 13447.394867230998, 13469.259190744604, 13484.056116071977, 13499.932500337633, 6948.5483443576, 7967.880481792778, 8287.8342433151, 8317.459365141247, 8367.414281000394, 8359.58202089893, 8364.874526882102, 8368.623411946477, 8519.148085191431, 8524.531150786643, 5365.022412561066, 6252.811965726497, 6498.740050397932, 6532.379423954717, 6653.667316634246, 1611.6696077304125, 1618.6438983423627, 1651.4302565615085, 1752.491777384544, 1876.1088482970479, 710.925352837955, 946.068122898944, 966.2801213096147, 969.5733877094056, 1001.4241810958728, 21192.15231390758, 21842.303904660574, 21881.059152240883, 21972.560548788933, 22260.214053978474, 799.194826160182, 924.1082355526949, 941.5010044676333, 947.9810403792023, 968.7820240445843, 9289.860652090225, 11300.773984520263, 11377.260478069065, 11477.50792396983, 11553.480093395649, 535498.0, 541399.0, 557349.0, 572432.0, 577637.0, 109265.0, 113825.0, 115819.42090289494, 123808.38095809464, 132855.3357233227, 947.8767760191131, 954.2127744610724, 1007.345225603349, 1013.0679774607379, 1015.8374660054355, 15718.742500600154, 17123.0, 19332.613347733248, 19385.51536211606, 19469.0, 53892.19161712499, 56189.0, 56733.86532269249, 57065.0, 57627.0, 78255.6087219583, 83281.0, 83406.58296708477, 84009.57995209986, 84220.31559368901, 5206.479352064764, 5220.9216861746345, 7659.314950710835, 7858.62079305904, 8200.712975045844, 8442.746717598482, 11303.547858085585, 12466.376681166086, 12867.292298923274, 12881.291528965998, 9853.261005424765, 10299.0, 10554.947225263824, 10761.30051546647, 10956.56173753041, 10620.09729173029, 10709.785804283714, 10823.567057317934, 10929.07102896231, 11002.239641657296, 10522.579096836042, 10882.129429645767, 10956.233063685784, 11392.886071139183, 11411.828822567833, 7975.282224599814, 7977.441579089408, 8104.756857294291, 8125.30934870519, 8137.918620813716, 8628.352873534384, 10559.155267578422, 10795.784084318113, 11057.778844422906, 11061.336319820837], "value": 9461.556375126604, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.5425817996418, 1503.1432083712284, 1684.898906065636, 1797.8471829894459, 1819.7543331650231, 7464.104307483096, 7717.614119294041, 7985.321247693946, 8450.154984501547, 8457.746267611974, 7948.801279967989, 8237.711680091197, 8240.011198656155, 8367.790805229872, 8369.414141010131, 2267.2178098556014, 2314.351981445198, 2316.6061769499224, 2317.1889838556453, 2325.9069637214493, 2301.919432819852, 2333.4166458385403, 2344.2732752846673, 2363.72817126031, 2369.3484291819714, 2241.23797908711, 2254.301166638339, 2286.7713228677167, 2293.5642227976714, 2293.575688497633, 2247.0450058724978, 2289.496310811627, 2316.30510846747, 2316.988415057931, 2318.3392733071078, 2311.15642790382, 2317.9652305215436, 2338.590746619335, 2340.3447034830274, 2340.707411573553, 8140.715074972407, 8197.016358037074, 8232.423730338878, 8242.17578242171, 8277.130901255341, 5049.091163590552, 5156.046131465653, 5201.037808005531, 5207.921881171786, 5212.635115541913, 3275.0, 3448.896533104011, 3548.716102711777, 3632.291703117893, 3640.453931910208, 1003.4230317567428, 1032.3908893752646, 1079.2175672637275, 1093.7211011192096, 1094.5949998500525, 8719.433236839648, 9160.8625870612, 9777.90222097777, 10798.784024319548, 10841.512131954001, 3993.6006399360413, 4432.667549933768, 4652.1626107300335, 4653.069386122721, 4679.719216847, 7867.842643147105, 9259.537023148816, 9375.718728438158, 9408.482533460588, 9410.247180225566, 5166.689998600096, 5179.378474582999, 5179.8446046618665, 5180.948190518046, 5191.480851914854, 4228.936565951513, 4364.5635436456105, 4548.363229147922, 4679.953200467951, 4760.285957106462, 3178.3166619176986, 3665.138692407282, 3735.9252814943547, 3816.904577385532, 3823.044238940265, 1145.9743529541015, 1261.5395380686166, 1262.3120399382358, 1267.2269915351628, 1273.5096987659817, 854.5641722721457, 863.3395452478859, 874.1171416868881, 902.120432578244, 921.0697195832124, 5646.717664116778, 5702.344230413555, 5728.885422291531, 5743.684097374685, 5960.493358064527, 867.7310033889514, 875.0156074416282, 895.91936725695, 920.4891285336703, 920.6869664314165, 3719.739618226698, 3759.342115129841, 3778.7732736035928, 3858.2090671412293, 3869.400242962289, 9790.804183916423, 10135.797284054277, 10136.493175341204, 10156.39061656303, 10191.388516689023, 8853.645854165761, 9497.762555936157, 10041.799164016678, 10063.144632706155, 10104.090631843022, 7303.853922921512, 7821.178776228414, 7826.608669566499, 7829.177936316719, 7830.647620857075, 5260.710660913686, 5391.838244852661, 5514.558835293247, 5532.446755324436, 5554.694491802989, 7657.0, 8980.326475514494, 9181.311401644769, 9293.30300227472, 9455.290853186176, 9333.739945107594, 9351.298652600946, 9389.201917837061, 9498.857517137387, 9563.952180239054, 6609.3060228675295, 6747.224069232101, 6888.827779305558, 7080.150381954199, 7220.1696804866015, 7742.458027938215, 7957.721479748181, 7958.960205199169, 7962.7213047543055, 7980.561069141253, 7067.752628657972, 7178.533395329287, 7200.855982880208, 7258.963705181441, 7310.415166786538, 6977.860442791014, 7023.297670232936, 7035.085438892911, 7139.250378710369, 7150.821229469305, 6629.6685165740555, 6636.568623039487, 6709.329067093252, 6718.764843230463, 6728.360805723449, 4487.977560112178, 4704.623630109514, 4781.641376896677, 4812.8796780080775, 4851.684640498356, 4292.699511034322, 4659.44086709597, 4754.881127971828, 4841.515848415131, 4926.236433352836], "value": 4375.739707560026, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.88904105480975, 300.8811519449817, 306.8266429467352, 306.940146671399, 313.17790799152226, 4337.544557821423, 4485.439320084989, 4869.78255436141, 5343.358796944367, 5469.917951230735, 4357.586029327209, 4526.642007397784, 4931.531504507075, 5470.343558772953, 5525.778968841241, 3553.8400771965194, 3700.8149592520267, 4013.5785742497055, 4193.790310484479, 4252.766097864616, 3358.966410335902, 3517.331706975679, 3848.747808102149, 4217.978910105445, 4260.552641972595, 3380.7633465657414, 3701.870434534792, 4080.9797550612348, 4092.7953602319913, 4095.692823038284, 4074.7351422157467, 4078.8164532595806, 4085.9591404085877, 4088.2641124597562, 4109.6438175402045, 3527.753057285991, 3548.840302186408, 3557.0, 3567.8037707926055, 3584.8386822593047, 4401.0, 4618.284165954283, 4836.419629644465, 5053.898922021539, 5167.527254732383, 4075.2868248056766, 4674.509176536449, 4688.437387513486, 4698.976505117486, 4701.78841952113, 4640.257558790611, 4972.83138462461, 5566.248556444869, 5771.163181338724, 5824.475797178275, 3771.679407250387, 3878.8448462061483, 3885.378339465672, 3905.2775236581324, 3926.6269704378324, 4595.0, 5940.821775346746, 5974.0, 5997.430244126801, 6030.728617212236, 3950.5259368875345, 3960.960390396059, 3972.0069982504374, 3974.900627484278, 3977.900552486211, 3358.8152651604396, 3826.0243637872163, 3864.265789500042, 4015.8996025099605, 4028.879133625996, 4252.340887162495, 4452.465704115527, 4661.417322834645, 4665.95334046662, 4673.392458980311, 5464.1257398816, 5783.16144159103, 5819.068948968229, 5819.970900145472, 5879.676617786063, 1082.6481393547128, 1204.403820109034, 1231.9137660363688, 1249.6126200877757, 1295.8121072444442, 745.9482130196398, 765.5674543882689, 861.4615865084322, 872.3457406944791, 884.9247813935759, 5091.338126043591, 5112.795488180505, 5120.052790233819, 5166.3025491558155, 5173.068847607392, 4810.735409552438, 4968.503149685075, 5305.010621017119, 5616.410276920865, 5719.399463056319, 4286.635635970915, 4536.729715679615, 4959.553640172402, 4968.1554135797005, 5121.644043739847, 2601.791856651463, 2901.129661101661, 2937.0, 2989.3110392628096, 3002.324476992703, 4208.516020657602, 5029.698218106925, 5323.920141197885, 5713.914356272378, 5732.799352022659, 5295.4439783822145, 5718.768111597388, 5747.6838773867, 5763.4812866842185, 5771.374024818781, 5323.0152421802095, 5820.679862607512, 5842.678652674143, 5855.94144058554, 5857.7949771757785, 5346.732663366894, 5572.665640061609, 5605.514538647343, 5639.96961094014, 5663.753974125686, 4777.355057067251, 4956.504349565159, 5042.520960508746, 5571.272905399339, 5611.831645050655, 4825.782839772149, 5059.9627076448505, 5188.247704082812, 5289.047971365191, 5843.620164689281, 3464.930701385908, 3700.130469339757, 3725.5343082114737, 3738.8691395801015, 3769.0388950817364, 4971.502849715, 5014.222795466709, 5409.459054094559, 5499.312585926759, 5662.745176467151, 4694.3427920090535, 5222.325266002394, 5315.468453154654, 5504.394516603091, 5515.7242137893745, 4622.884427889329, 5371.785128594813, 5374.274472946102, 5414.350277966511, 5452.372977107683, 4639.953600463952, 4789.760511974457, 5289.47105289468, 5586.469285417878, 5645.322561292671, 5109.850283686141, 5861.179434879035, 5870.9412905872105, 5890.381509941566, 5942.494887934574, 5378.220158077135, 5678.602497825278, 5723.513501352431, 5740.827775166752, 5742.684152371493], "value": 4020.715955626793, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.9832004199891, 673.2594146438914, 705.7812078255741, 714.1251966341233, 809.5183365897291, 13699.520516781915, 14649.0, 14658.267086645665, 14715.55853324402, 14843.332050057747, 13080.869191308084, 13244.006699497551, 13451.529196478125, 13519.256440895746, 13587.932060339685, 799.7200979657117, 819.7827575692446, 822.3092602214139, 822.6585966823761, 822.8601137806558, 650.9739610415578, 673.6497021548792, 803.4094940218935, 810.1979040749645, 815.6655771133836, 857.7684025313162, 866.3675516872687, 866.9133086691344, 867.0852250875324, 867.700643278068, 745.3329270303076, 827.0571548434789, 852.910444403335, 855.4183155454289, 857.995710021449, 630.8580569371901, 633.8922383194868, 636.688022868791, 638.3616383616384, 638.9904151437732, 13986.650333741636, 14381.137131772022, 14428.927855360656, 14580.781288280685, 14597.562073137822, 9664.806703865954, 11038.448077596087, 12867.970562355084, 13348.93325533366, 13382.464701411931, 11038.944805276002, 11097.57315489868, 11187.44062796865, 11198.776024479546, 11557.479913403917, 3303.8436547208526, 3519.225770330523, 3603.297357015383, 3623.0398944279787, 3637.1270894985278, 55389.0, 69777.65111174462, 91835.0, 92129.61805572922, 92626.0, 10074.597016119273, 10099.920602819366, 10138.543765530569, 10180.083792458714, 10310.587576496857, 18930.95879726628, 19985.400437986882, 20143.39569812908, 20292.695609565868, 20302.59394812125, 6347.143135676624, 6386.233651961795, 6677.799666010027, 7686.192949740309, 7748.806279842936, 4132.132252227006, 4164.167166566638, 4193.601607847249, 4272.5086615039045, 4351.3472979053395, 3214.2767877227902, 3284.1297056280105, 3295.2915123248135, 3298.950515742266, 3306.9503957440656, 579.5827004556712, 632.077167335695, 635.2186810223451, 647.1457675867904, 652.7552167937024, 574.5920396518519, 589.9233099697012, 646.3245908026124, 668.518666560076, 670.8457054877406, 8784.516851573097, 8788.384813063025, 8997.145271199226, 9057.864132038025, 9119.81760364789, 570.1277046119444, 620.5873094392269, 624.3974564545235, 637.1892213785898, 655.4101308822098, 4393.384926110347, 4409.0, 4415.183191109655, 4459.375687403766, 4510.1656193604285, 75516.48967020628, 92792.53603731938, 118166.4091679536, 119230.40384798021, 126557.36721316335, 68966.31033689599, 78281.0, 104147.0, 104298.0, 105406.47296763468, 89241.0, 90037.0, 91713.0, 93478.0, 97097.5145124284, 8664.523451210243, 8679.566021698764, 8709.738707838775, 8798.12018798115, 8887.0, 48749.75625121852, 57404.0, 58368.708156458946, 58531.70734146302, 58583.0, 64642.0, 67430.66284668545, 80579.19420805718, 80829.19170808452, 82596.17403825885, 11888.108391870817, 12003.879961200277, 12117.515299387926, 12126.878731212928, 12569.057320700802, 13831.930840345734, 14120.717585648435, 14121.0, 14374.209418482083, 14448.422063117358, 12549.93725031369, 12787.808182877077, 12838.743225135631, 12863.614091577268, 12897.613071607866, 12351.25892446456, 12664.050196235503, 12877.678058048623, 12936.22382657006, 12959.481620735442, 12467.812982805446, 12942.870571294543, 12976.8053479196, 13116.540921067717, 13194.604161875159, 10583.31208471447, 10916.454177291262, 10931.125509959344, 11168.888311117109, 11587.304761714324, 9322.207612352755, 9571.33000689945, 9923.900760992297, 11066.723331916766, 11131.0], "value": 7150.497505039034, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1593.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 115.0, 115.0, 116.0, 116.0, 117.0, 119.0, 115.0, 116.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 123.0, 134.0, 194.0, 150.0, 123.0, 146.0, 154.0, 149.0, 148.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 225.0, 197.0, 202.0, 200.0, 201.0, 194.0, 229.0, 183.0, 267.0], "value": 209.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 50.0, 76.0, 103.0, 86.0, 86.0, 86.0, 64.0, 78.0, 58.0], "value": 76.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 70.0, 87.0, 73.0, 69.0, 74.0, 74.0, 92.0, 58.0, 88.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 89.0, 94.0, 89.0, 102.0, 89.0, 93.0, 90.0, 91.0, 88.0], "value": 91.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 104.0, 154.0, 104.0, 144.0, 116.0, 97.0, 97.0, 99.0, 97.0], "value": 111.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 80.0, 154.0, 157.0, 93.0, 92.0, 97.0, 93.0, 98.0, 126.0], "value": 108.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 58.0, 58.0, 58.0, 56.0, 62.0, 63.0, 88.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 82.0, 83.0, 79.0, 69.0, 70.0, 72.0, 99.0, 103.0], "value": 79.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 92.0, 90.0, 92.0, 111.0, 80.0, 109.0, 101.0, 84.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 167.0, 149.0, 168.0, 175.0, 155.0, 157.0, 220.0, 192.0], "value": 170.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 181.0, 172.0, 174.0, 179.0, 173.0, 246.0, 175.0, 174.0, 177.0], "value": 182.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 69.0, 68.0, 72.0, 67.0, 66.0, 68.0, 68.0, 67.0], "value": 68.3, "unit": "score"}]}]} 00:27:28 INFO - Return code: 0 00:27:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:27:28 ERROR - # TBPL SUCCESS # 00:27:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:27:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:27:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:27:28 INFO - 2016-11-18 00:27:28.467 screenresolution[1981:12891] starting screenresolution argv=screenresolution get 00:27:28 INFO - 2016-11-18 00:27:28.469 screenresolution[1981:12891] Display 0: 1600x1200x32@60 00:27:28 INFO - 2016-11-18 00:27:28.476 screenresolution[1982:12894] starting screenresolution argv=screenresolution list 00:27:28 INFO - Available Modes on Display 0 00:27:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:27:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:27:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:27:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:27:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:27:28 INFO - Intel Iris: 00:27:28 INFO - Chipset Model: Intel Iris 00:27:28 INFO - Type: GPU 00:27:28 INFO - Bus: Built-In 00:27:28 INFO - VRAM (Dynamic, Max): 1536 MB 00:27:28 INFO - Vendor: Intel (0x8086) 00:27:28 INFO - Device ID: 0x0a2e 00:27:28 INFO - Revision ID: 0x0009 00:27:28 INFO - Displays: 00:27:28 INFO - Display: 00:27:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:27:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:27:28 INFO - Display Serial Number: 1600x1200 60 00:27:28 INFO - Main Display: Yes 00:27:28 INFO - Mirror: Off 00:27:28 INFO - Online: Yes 00:27:28 INFO - Rotation: Supported 00:27:28 INFO - Return code: 0 00:27:28 INFO - Running post-action listener: _resource_record_post_action 00:27:28 INFO - [mozharness: 2016-11-18 08:27:28.721606Z] Finished run-tests step (success) 00:27:28 INFO - Running post-run listener: _resource_record_post_run 00:27:28 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 196608; Write bytes: 691469824; Read time: 3; Write time: 1997 00:27:28 INFO - TinderboxPrint: CPU usage
54.5% 00:27:28 INFO - TinderboxPrint: I/O read bytes / time
196,608 / 3 00:27:28 INFO - TinderboxPrint: I/O write bytes / time
691,469,824 / 1,997 00:27:28 INFO - TinderboxPrint: CPU idle
2,208.8 (45.5%) 00:27:28 INFO - TinderboxPrint: CPU system
980.1 (20.2%) 00:27:28 INFO - TinderboxPrint: CPU user
1,664.8 (34.3%) 00:27:28 INFO - TinderboxPrint: Swap in / out
382,910,464 / 0 00:27:28 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 373942784; Write bytes: 367252992; Read time: 28524; Write time: 791 00:27:28 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 196608; Write bytes: 304061440; Read time: 3; Write time: 1154 00:27:29 INFO - Running post-run listener: _upload_blobber_files 00:27:29 INFO - Blob upload gear active. 00:27:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:27:29 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 00:27:29 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'] 00:27:29 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 00:27:29 INFO - (blobuploader) - INFO - Open directory for files ... 00:27:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:27:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:27:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:27:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:27:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:27:30 INFO - (blobuploader) - INFO - Done attempting. 00:27:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:27:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:27:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:27:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:27:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:27:32 INFO - (blobuploader) - INFO - Done attempting. 00:27:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:27:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:27:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:27:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:27:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:27:34 INFO - (blobuploader) - INFO - Done attempting. 00:27:34 INFO - (blobuploader) - INFO - Iteration through files over. 00:27:34 INFO - Return code: 0 00:27:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:27:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:27:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68a30ef9ec3695810552c76628679d36e27a5c22d8cb454fb7d8ac3f3792be0feb53e2105dd5478b1801f7fb0d7dda131cab27291e30838bf6e8787d7f26e171", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ffade86dbb846dc9a8d64ae84fcad4caa192bbf90c6b04addde3a9560102073690e3ed0b43fcb44ee0559bb1a031c4f02aa4eea25712a2c1e7d15c034c56bb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fa5629d3bb05059e09b3bcc491520737b3e49707032d33bdcf789f5b12b7e3695c9e3f0fee75317268e6cb8abf898c814f21190c292fd055764dea5108be51f"} 00:27:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:27:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:27:34 INFO - Contents: 00:27:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68a30ef9ec3695810552c76628679d36e27a5c22d8cb454fb7d8ac3f3792be0feb53e2105dd5478b1801f7fb0d7dda131cab27291e30838bf6e8787d7f26e171", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ffade86dbb846dc9a8d64ae84fcad4caa192bbf90c6b04addde3a9560102073690e3ed0b43fcb44ee0559bb1a031c4f02aa4eea25712a2c1e7d15c034c56bb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fa5629d3bb05059e09b3bcc491520737b3e49707032d33bdcf789f5b12b7e3695c9e3f0fee75317268e6cb8abf898c814f21190c292fd055764dea5108be51f"} 00:27:34 INFO - Running post-run listener: copy_logs_to_upload_dir 00:27:34 INFO - Copying logs to upload dir... 00:27:34 INFO - mkdir: /builds/slave/test/build/upload/logs 00:27:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.997288 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-18 00:27:34.487703) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 00:27:34.491465) ========= 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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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/68a30ef9ec3695810552c76628679d36e27a5c22d8cb454fb7d8ac3f3792be0feb53e2105dd5478b1801f7fb0d7dda131cab27291e30838bf6e8787d7f26e171", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ffade86dbb846dc9a8d64ae84fcad4caa192bbf90c6b04addde3a9560102073690e3ed0b43fcb44ee0559bb1a031c4f02aa4eea25712a2c1e7d15c034c56bb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fa5629d3bb05059e09b3bcc491520737b3e49707032d33bdcf789f5b12b7e3695c9e3f0fee75317268e6cb8abf898c814f21190c292fd055764dea5108be51f"} build_url:https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010717 build_url: 'https://queue.taskcluster.net/v1/task/PPpBg56bSOGOZdvpDlUOHQ/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68a30ef9ec3695810552c76628679d36e27a5c22d8cb454fb7d8ac3f3792be0feb53e2105dd5478b1801f7fb0d7dda131cab27291e30838bf6e8787d7f26e171", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ffade86dbb846dc9a8d64ae84fcad4caa192bbf90c6b04addde3a9560102073690e3ed0b43fcb44ee0559bb1a031c4f02aa4eea25712a2c1e7d15c034c56bb0", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fa5629d3bb05059e09b3bcc491520737b3e49707032d33bdcf789f5b12b7e3695c9e3f0fee75317268e6cb8abf898c814f21190c292fd055764dea5108be51f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-18 00:27:34.526301) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 00:27:34.527138) ========= 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.Nh4t626o2Z/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.Il3Rz9tahy/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.005244 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-18 00:27:34.575903) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 00:27:34.576213) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-18 00:27:34.576511) ========= ========= Total master_lag: 0.19 =========