builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0298 starttime: 1495304063.73 results: success (0) revision: 5b74bbf20e803e299790d266fc6ebf5d53b7a1b7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:23.727472) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:23.727932) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:23.728315) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:23.812005) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:23.813058) ========= 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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-20 11:14:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.3M=0.001s 2017-05-20 11:14:24 (17.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.528612 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:24.407938) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:24.408296) ========= 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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.031083 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:24.464096) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-20 11:14:24.464834) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5b74bbf20e803e299790d266fc6ebf5d53b7a1b7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 5b74bbf20e803e299790d266fc6ebf5d53b7a1b7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OIz6uvDLmr/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.pyL3d8fnTC/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-20 11:14:24,566 truncating revision to first 12 chars 2017-05-20 11:14:24,566 Setting DEBUG logging. 2017-05-20 11:14:24,566 attempt 1/10 2017-05-20 11:14:24,566 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5b74bbf20e80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-20 11:14:25,509 unpacking tar archive at: mozilla-central-5b74bbf20e80/testing/mozharness/ program finished with exit code 0 elapsedTime=1.220331 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-20 11:14:25.712007) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:25.712741) ========= script_repo_revision: 5b74bbf20e803e299790d266fc6ebf5d53b7a1b7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:25.713728) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:25.714373) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-20 11:14:25.734281) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-20 11:14:25.734974) ========= /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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:14:25 INFO - MultiFileLogger online at 20170520 11:14:25 in /builds/slave/test 11:14:25 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 11:14:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:14:25 INFO - {'append_to_log': False, 11:14:25 INFO - 'base_work_dir': '/builds/slave/test', 11:14:25 INFO - 'blob_upload_branch': 'Firefox', 11:14:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:14:25 INFO - 'branch': 'Firefox', 11:14:25 INFO - 'buildbot_json_path': 'buildprops.json', 11:14:25 INFO - 'config_files': ('talos/mac_config.py',), 11:14:25 INFO - 'default_actions': ('clobber', 11:14:25 INFO - 'read-buildbot-config', 11:14:25 INFO - 'download-and-extract', 11:14:25 INFO - 'populate-webroot', 11:14:25 INFO - 'create-virtualenv', 11:14:25 INFO - 'install', 11:14:25 INFO - 'run-tests'), 11:14:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:14:25 INFO - 'download_minidump_stackwalk': True, 11:14:25 INFO - 'download_symbols': 'ondemand', 11:14:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:14:25 INFO - 'tooltool.py': '/tools/tooltool.py', 11:14:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:14:25 INFO - '/tools/misc-python/virtualenv.py')}, 11:14:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:14:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:14:25 INFO - 'gecko_profile': False, 11:14:25 INFO - 'gecko_profile_interval': 0, 11:14:25 INFO - 'installer_path': 'installer.exe', 11:14:25 INFO - 'log_level': 'info', 11:14:25 INFO - 'log_name': 'talos', 11:14:25 INFO - 'log_to_console': True, 11:14:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:14:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:14:25 INFO - 'opt_config_files': (), 11:14:25 INFO - 'pip_index': False, 11:14:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:14:25 INFO - 'cmd': ('bash', 11:14:25 INFO - '-c', 11:14:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:14:25 INFO - 'enabled': True, 11:14:25 INFO - 'halt_on_failure': False, 11:14:25 INFO - 'name': 'check_screen_resolution'},), 11:14:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:14:25 INFO - 'cmd': ('bash', 11:14:25 INFO - '-c', 11:14:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:14:25 INFO - 'enabled': True, 11:14:25 INFO - 'halt_on_failure': False, 11:14:25 INFO - 'name': 'check_screen_resolution'},), 11:14:25 INFO - 'run_cmd_checks_enabled': True, 11:14:25 INFO - 'suite': 'dromaeojs-e10s', 11:14:25 INFO - 'system_bits': '32', 11:14:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:14:25 INFO - 'title': 't-yosemite-r7-0298', 11:14:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:14:25 INFO - 'use_talos_json': True, 11:14:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:14:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:14:25 INFO - 'work_dir': 'build'} 11:14:25 INFO - [mozharness: 2017-05-20 18:14:25.942089Z] Running clobber step. 11:14:25 INFO - Running pre-action listener: _resource_record_pre_action 11:14:25 INFO - Running main action method: clobber 11:14:25 INFO - rmtree: /builds/slave/test/build 11:14:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:14:28 INFO - Running post-action listener: _resource_record_post_action 11:14:28 INFO - [mozharness: 2017-05-20 18:14:28.496848Z] Finished clobber step (success) 11:14:28 INFO - [mozharness: 2017-05-20 18:14:28.497010Z] Running read-buildbot-config step. 11:14:28 INFO - Running pre-action listener: _resource_record_pre_action 11:14:28 INFO - Running main action method: read_buildbot_config 11:14:28 INFO - Using buildbot properties: 11:14:28 INFO - { 11:14:28 INFO - "project": "", 11:14:28 INFO - "product": "firefox", 11:14:28 INFO - "who": "archaeopteryx@coole-files.de", 11:14:28 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 11:14:28 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:14:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 11:14:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:14:28 INFO - "stage_platform": "macosx64", 11:14:28 INFO - "basedir": "/builds/slave/test", 11:14:28 INFO - "buildnumber": 60, 11:14:28 INFO - "platform": "yosemite_r7", 11:14:28 INFO - "parent_task_id": "as-j4BcPQfu6JGYK2E6l5A", 11:14:28 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:14:28 INFO - "slavebuilddir": "test", 11:14:28 INFO - "taskId": "b3pV9YGNRFasvI5Brp1WVQ", 11:14:28 INFO - "branch": "mozilla-central", 11:14:28 INFO - "script_repo_revision": "production", 11:14:28 INFO - "revision": "5b74bbf20e803e299790d266fc6ebf5d53b7a1b7", 11:14:28 INFO - "slavename": "t-yosemite-r7-0298", 11:14:28 INFO - "repo_path": "mozilla-central" 11:14:28 INFO - } 11:14:28 INFO - Finding installer, test and symbols from parent task. 11:14:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3pV9YGNRFasvI5Brp1WVQ'}, attempt #1 11:14:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3pV9YGNRFasvI5Brp1WVQ'}, attempt #1 11:14:29 INFO - Task dependencies: GZYAQSRRSEqTyuzMZ7Fwsg as-j4BcPQfu6JGYK2E6l5A 11:14:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A'}, attempt #1 11:14:29 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:14:29 INFO - Set test_url: https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/test_packages.json 11:14:29 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/target.crashreporter-symbols.zip 11:14:29 INFO - Running post-action listener: _resource_record_post_action 11:14:29 INFO - [mozharness: 2017-05-20 18:14:29.829067Z] Finished read-buildbot-config step (success) 11:14:29 INFO - [mozharness: 2017-05-20 18:14:29.829200Z] Running download-and-extract step. 11:14:29 INFO - Running pre-action listener: _resource_record_pre_action 11:14:29 INFO - Running main action method: download_and_extract 11:14:29 INFO - mkdir: /builds/slave/test/build/tests 11:14:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:14:29 INFO - trying https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:14:29 INFO - Downloading https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 11:14:30 INFO - Downloaded 1325 bytes. 11:14:30 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:14:30 INFO - Using the following test package requirements: 11:14:30 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:14:30 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:14:30 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:14:30 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:14:30 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'jsshell-mac.zip'], 11:14:30 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:14:30 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:14:30 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:14:30 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:14:30 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:14:30 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:14:30 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:14:30 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:14:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:14:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:14:30 INFO - Fetch https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:14:32 INFO - Content-Length response header: 57622779 11:14:32 INFO - Bytes received: 57622779 11:14:50 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:14:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:14:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:14:50 INFO - Fetch https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:14:51 INFO - Content-Length response header: 57622779 11:14:51 INFO - Bytes received: 57622779 11:15:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:15:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:15:09 INFO - Fetch https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:15:10 INFO - Content-Length response header: 14410000 11:15:10 INFO - Bytes received: 14410000 11:15:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:11 INFO - trying https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:15:11 INFO - Downloading https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:15:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 11:15:13 INFO - Downloaded 68068479 bytes. 11:15:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:15:13 INFO - mkdir: /builds/slave/test/properties 11:15:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:15:13 INFO - Writing to file /builds/slave/test/properties/build_url 11:15:13 INFO - Contents: 11:15:13 INFO - build_url:https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:15:13 INFO - Running post-action listener: _resource_record_post_action 11:15:13 INFO - Running post-action listener: set_extra_try_arguments 11:15:13 INFO - [mozharness: 2017-05-20 18:15:13.205409Z] Finished download-and-extract step (success) 11:15:13 INFO - [mozharness: 2017-05-20 18:15:13.205525Z] Running populate-webroot step. 11:15:13 INFO - Running pre-action listener: _resource_record_pre_action 11:15:13 INFO - Running main action method: populate_webroot 11:15:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:15:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:15:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:15:13 INFO - '--tppagecycles', 11:15:13 INFO - '7'], 11:15:13 INFO - 'tests': ['tsvgm']}}, 11:15:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:15:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:15:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:15:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:15:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:15:13 INFO - 'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:15:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:15:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:15:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:15:13 INFO - 'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['damp', 'tps']}, 11:15:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:15:13 INFO - 'tests': ['damp', 'tps']}, 11:15:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['dromaeo_dom']}, 11:15:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:15:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:15:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:15:13 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:15:13 INFO - 'tests': ['ts_paint', 'tp5o']}, 11:15:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['a11yr', 11:15:13 INFO - 'ts_paint', 11:15:13 INFO - 'tpaint', 11:15:13 INFO - 'sessionrestore', 11:15:13 INFO - 'sessionrestore_no_auto_restore', 11:15:13 INFO - 'tabpaint']}, 11:15:13 INFO - 'other-e10s': {'tests': ['a11yr', 11:15:13 INFO - 'ts_paint', 11:15:13 INFO - 'tpaint', 11:15:13 INFO - 'sessionrestore', 11:15:13 INFO - 'sessionrestore_no_auto_restore', 11:15:13 INFO - 'tabpaint']}, 11:15:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:15:13 INFO - 'ts_paint', 11:15:13 INFO - 'tpaint', 11:15:13 INFO - 'sessionrestore', 11:15:13 INFO - 'sessionrestore_no_auto_restore', 11:15:13 INFO - 'tabpaint']}, 11:15:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:15:13 INFO - 'ts_paint', 11:15:13 INFO - 'tpaint', 11:15:13 INFO - 'sessionrestore', 11:15:13 INFO - 'sessionrestore_no_auto_restore', 11:15:13 INFO - 'tabpaint']}, 11:15:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['a11yr', 11:15:13 INFO - 'ts_paint', 11:15:13 INFO - 'tpaint', 11:15:13 INFO - 'sessionrestore', 11:15:13 INFO - 'sessionrestore_no_auto_restore', 11:15:13 INFO - 'tabpaint']}, 11:15:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['a11yr', 11:15:13 INFO - 'ts_paint', 11:15:13 INFO - 'tpaint', 11:15:13 INFO - 'sessionrestore', 11:15:13 INFO - 'sessionrestore_no_auto_restore', 11:15:13 INFO - 'tabpaint']}, 11:15:13 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:15:13 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:15:13 INFO - 'bloom_basic_ref']}, 11:15:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['tsvgx', 11:15:13 INFO - 'tsvgr_opacity', 11:15:13 INFO - 'tart', 11:15:13 INFO - 'tscrollx', 11:15:13 INFO - 'cart', 11:15:13 INFO - 'tsvg_static']}, 11:15:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:15:13 INFO - 'tsvgr_opacity', 11:15:13 INFO - 'tart', 11:15:13 INFO - 'tscrollx', 11:15:13 INFO - 'cart', 11:15:13 INFO - 'tsvg_static']}, 11:15:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:15:13 INFO - 'talos_options': ['--disable-e10s'], 11:15:13 INFO - 'tests': ['tp5o']}, 11:15:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:15:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:15:13 INFO - 'talos_options': ['--disable-e10s', 11:15:13 INFO - '--xperf_path', 11:15:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:15:13 INFO - 'tests': ['tp5n']}, 11:15:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:15:13 INFO - 'talos_options': ['--xperf_path', 11:15:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:15:13 INFO - 'tests': ['tp5n']}}, 11:15:13 INFO - 'talos.zip': {'path': '', 11:15:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:15:13 INFO - Running post-action listener: _resource_record_post_action 11:15:13 INFO - [mozharness: 2017-05-20 18:15:13.226810Z] Finished populate-webroot step (success) 11:15:13 INFO - [mozharness: 2017-05-20 18:15:13.226944Z] Running create-virtualenv step. 11:15:13 INFO - Running pre-action listener: _resource_record_pre_action 11:15:13 INFO - Running main action method: create_virtualenv 11:15:13 INFO - Creating virtualenv /builds/slave/test/build/venv 11:15:13 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 11:15:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:15:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:15:13 INFO - Using real prefix '/tools/python27' 11:15:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:15:14 INFO - Installing distribute.............................................................................................................................................................................................done. 11:15:17 INFO - Installing pip.................done. 11:15:17 INFO - Return code: 0 11:15:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:15:17 INFO - Reading from file tmpfile_stdout 11:15:17 INFO - Output received: 11:15:17 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) 11:15:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:17 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:17 INFO - 'HOME': '/Users/cltbld', 11:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:17 INFO - 'LOGNAME': 'cltbld', 11:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:17 INFO - 'MOZ_NO_REMOTE': '1', 11:15:17 INFO - 'NO_EM_RESTART': '1', 11:15:17 INFO - 'PAGER': '/bin/cat', 11:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:17 INFO - 'PWD': '/builds/slave/test', 11:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:17 INFO - 'SHELL': '/bin/bash', 11:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:17 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:17 INFO - 'USER': 'cltbld', 11:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:17 INFO - 'XPC_FLAGS': '0x0', 11:15:17 INFO - 'XPC_SERVICE_NAME': '0', 11:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:17 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 11:15:17 INFO - Cleaning up... 11:15:17 INFO - Return code: 0 11:15:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:17 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:17 INFO - 'HOME': '/Users/cltbld', 11:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:17 INFO - 'LOGNAME': 'cltbld', 11:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:17 INFO - 'MOZ_NO_REMOTE': '1', 11:15:17 INFO - 'NO_EM_RESTART': '1', 11:15:17 INFO - 'PAGER': '/bin/cat', 11:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:17 INFO - 'PWD': '/builds/slave/test', 11:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:17 INFO - 'SHELL': '/bin/bash', 11:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:17 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:17 INFO - 'USER': 'cltbld', 11:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:17 INFO - 'XPC_FLAGS': '0x0', 11:15:17 INFO - 'XPC_SERVICE_NAME': '0', 11:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:17 INFO - Downloading/unpacking psutil>=3.1.1 11:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:15:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:20 INFO - Installing collected packages: psutil 11:15:20 INFO - Running setup.py install for psutil 11:15:20 INFO - building 'psutil._psutil_osx' extension 11:15:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:15:21 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 11:15:21 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 11:15:21 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 11:15:21 INFO - building 'psutil._psutil_posix' extension 11:15:21 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 11:15:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:15:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:15:21 INFO - ^ 11:15:21 INFO - 1 warning generated. 11:15:21 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 11:15:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:15:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:15:21 INFO - Successfully installed psutil 11:15:21 INFO - Cleaning up... 11:15:21 INFO - Return code: 0 11:15:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:21 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:15:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:15:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:21 INFO - 'HOME': '/Users/cltbld', 11:15:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:21 INFO - 'LOGNAME': 'cltbld', 11:15:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:21 INFO - 'MOZ_NO_REMOTE': '1', 11:15:21 INFO - 'NO_EM_RESTART': '1', 11:15:21 INFO - 'PAGER': '/bin/cat', 11:15:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:21 INFO - 'PWD': '/builds/slave/test', 11:15:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:21 INFO - 'SHELL': '/bin/bash', 11:15:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:21 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:21 INFO - 'USER': 'cltbld', 11:15:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:21 INFO - 'XPC_FLAGS': '0x0', 11:15:21 INFO - 'XPC_SERVICE_NAME': '0', 11:15:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:15:21 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 11:15:21 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 11:15:21 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 11:15:21 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 11:15:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:15:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:15:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:15:23 INFO - Installing collected packages: mozsystemmonitor 11:15:23 INFO - Running setup.py install for mozsystemmonitor 11:15:23 INFO - Successfully installed mozsystemmonitor 11:15:23 INFO - Cleaning up... 11:15:23 INFO - Return code: 0 11:15:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:15:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:15:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:15:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:23 INFO - 'HOME': '/Users/cltbld', 11:15:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:23 INFO - 'LOGNAME': 'cltbld', 11:15:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:23 INFO - 'MOZ_NO_REMOTE': '1', 11:15:23 INFO - 'NO_EM_RESTART': '1', 11:15:23 INFO - 'PAGER': '/bin/cat', 11:15:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:23 INFO - 'PWD': '/builds/slave/test', 11:15:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:23 INFO - 'SHELL': '/bin/bash', 11:15:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:23 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:23 INFO - 'USER': 'cltbld', 11:15:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:23 INFO - 'XPC_FLAGS': '0x0', 11:15:23 INFO - 'XPC_SERVICE_NAME': '0', 11:15:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:23 INFO - Downloading/unpacking jsonschema==2.5.1 11:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:15:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:25 INFO - Downloading functools32-3.2.3-2.tar.gz 11:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:15:25 INFO - warning: no files found matching '*.txt' 11:15:25 INFO - no previously-included directories found matching 'build' 11:15:25 INFO - no previously-included directories found matching 'dist' 11:15:25 INFO - no previously-included directories found matching '.git*' 11:15:25 INFO - Installing collected packages: jsonschema, functools32 11:15:25 INFO - Running setup.py install for functools32 11:15:25 INFO - warning: no files found matching '*.txt' 11:15:25 INFO - no previously-included directories found matching 'build' 11:15:25 INFO - no previously-included directories found matching 'dist' 11:15:25 INFO - no previously-included directories found matching '.git*' 11:15:25 INFO - Successfully installed jsonschema functools32 11:15:25 INFO - Cleaning up... 11:15:25 INFO - Return code: 0 11:15:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:25 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:25 INFO - 'HOME': '/Users/cltbld', 11:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:25 INFO - 'LOGNAME': 'cltbld', 11:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:25 INFO - 'MOZ_NO_REMOTE': '1', 11:15:25 INFO - 'NO_EM_RESTART': '1', 11:15:25 INFO - 'PAGER': '/bin/cat', 11:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:25 INFO - 'PWD': '/builds/slave/test', 11:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:25 INFO - 'SHELL': '/bin/bash', 11:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:25 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:25 INFO - 'USER': 'cltbld', 11:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:25 INFO - 'XPC_FLAGS': '0x0', 11:15:25 INFO - 'XPC_SERVICE_NAME': '0', 11:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:15:26 INFO - Cleaning up... 11:15:26 INFO - Return code: 0 11:15:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:26 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 11:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:26 INFO - 'HOME': '/Users/cltbld', 11:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:26 INFO - 'LOGNAME': 'cltbld', 11:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:26 INFO - 'MOZ_NO_REMOTE': '1', 11:15:26 INFO - 'NO_EM_RESTART': '1', 11:15:26 INFO - 'PAGER': '/bin/cat', 11:15:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:26 INFO - 'PWD': '/builds/slave/test', 11:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:26 INFO - 'SHELL': '/bin/bash', 11:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:26 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:26 INFO - 'USER': 'cltbld', 11:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:26 INFO - 'XPC_FLAGS': '0x0', 11:15:26 INFO - 'XPC_SERVICE_NAME': '0', 11:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:26 INFO - Downloading/unpacking blobuploader==1.2.4 11:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:27 INFO - Downloading blobuploader-1.2.4.tar.gz 11:15:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:15:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:15:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:28 INFO - Downloading docopt-0.6.1.tar.gz 11:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:15:28 INFO - Installing collected packages: blobuploader, requests, docopt 11:15:28 INFO - Running setup.py install for blobuploader 11:15:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:15:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:15:28 INFO - Running setup.py install for requests 11:15:29 INFO - Running setup.py install for docopt 11:15:29 INFO - Successfully installed blobuploader requests docopt 11:15:29 INFO - Cleaning up... 11:15:29 INFO - Return code: 0 11:15:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:29 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:29 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:15:29 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:15:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:29 INFO - 'HOME': '/Users/cltbld', 11:15:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:29 INFO - 'LOGNAME': 'cltbld', 11:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:29 INFO - 'MOZ_NO_REMOTE': '1', 11:15:29 INFO - 'NO_EM_RESTART': '1', 11:15:29 INFO - 'PAGER': '/bin/cat', 11:15:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:29 INFO - 'PWD': '/builds/slave/test', 11:15:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:29 INFO - 'SHELL': '/bin/bash', 11:15:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:29 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:29 INFO - 'USER': 'cltbld', 11:15:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:29 INFO - 'XPC_FLAGS': '0x0', 11:15:29 INFO - 'XPC_SERVICE_NAME': '0', 11:15:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:29 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-RfQ9DR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:29 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-IKMLb7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:29 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-MgImFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-8N36Hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-z2tZ9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-KS_Jzy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-RwxP78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-H2k4YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-FrgM8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-vqNQxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-v6DlLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-CDRdbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:30 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-cYakUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:31 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-aqdFbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:31 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-GyQrCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:31 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Q58G84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:31 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-w0VmTF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:15:31 INFO - Running setup.py install for manifestparser 11:15:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:15:31 INFO - Running setup.py install for mozcrash 11:15:31 INFO - Running setup.py install for mozdebug 11:15:31 INFO - Running setup.py install for mozdevice 11:15:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:15:32 INFO - Running setup.py install for mozfile 11:15:32 INFO - Running setup.py install for mozhttpd 11:15:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:15:32 INFO - Running setup.py install for mozinfo 11:15:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:15:32 INFO - Running setup.py install for mozInstall 11:15:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:15:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:15:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:15:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:15:32 INFO - Running setup.py install for mozleak 11:15:32 INFO - Running setup.py install for mozlog 11:15:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:15:32 INFO - Running setup.py install for moznetwork 11:15:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:15:33 INFO - Running setup.py install for mozprocess 11:15:33 INFO - Running setup.py install for mozprofile 11:15:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:15:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:15:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:15:33 INFO - Running setup.py install for mozrunner 11:15:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:15:33 INFO - Running setup.py install for mozscreenshot 11:15:33 INFO - Running setup.py install for moztest 11:15:33 INFO - Running setup.py install for mozversion 11:15:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:15:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:15:34 INFO - Cleaning up... 11:15:34 INFO - Return code: 0 11:15:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:34 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:34 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:15:34 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:15:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:34 INFO - 'HOME': '/Users/cltbld', 11:15:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:34 INFO - 'LOGNAME': 'cltbld', 11:15:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:34 INFO - 'MOZ_NO_REMOTE': '1', 11:15:34 INFO - 'NO_EM_RESTART': '1', 11:15:34 INFO - 'PAGER': '/bin/cat', 11:15:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:34 INFO - 'PWD': '/builds/slave/test', 11:15:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:34 INFO - 'SHELL': '/bin/bash', 11:15:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:34 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:34 INFO - 'USER': 'cltbld', 11:15:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:34 INFO - 'XPC_FLAGS': '0x0', 11:15:34 INFO - 'XPC_SERVICE_NAME': '0', 11:15:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-XZ9iWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:34 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)) 11:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-ewWb6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:34 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)) 11:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-vi7KKF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:34 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)) 11:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-cGZ8Vj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 11:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-Hvteyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:34 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)) 11:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-UyjYIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:34 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)) 11:15:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:34 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-b6S1o_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:35 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-qSw26h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:35 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-wstMSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:35 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-HD5xSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:35 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-NzdEHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:35 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-QuVVXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-pbtTRT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:35 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-xKvIeU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:35 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-e1Dg82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:35 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)) 11:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:35 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-OWKaCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:36 INFO - Running setup.py (path:/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/pip-HLHQYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:36 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)) 11:15:36 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)) 11:15:36 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)) 11:15:36 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)) 11:15:36 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:15:36 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:15:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:15:36 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 11:15:36 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 11:15:36 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 11:15:36 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 11:15:37 INFO - Downloading blessings-1.6.tar.gz 11:15:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:15:37 INFO - Installing collected packages: blessings 11:15:37 INFO - Running setup.py install for blessings 11:15:37 INFO - Successfully installed blessings 11:15:37 INFO - Cleaning up... 11:15:37 INFO - Return code: 0 11:15:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:15:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:38 INFO - Reading from file tmpfile_stdout 11:15:38 INFO - Current package versions: 11:15:38 INFO - blessings == 1.6 11:15:38 INFO - blobuploader == 1.2.4 11:15:38 INFO - docopt == 0.6.1 11:15:38 INFO - functools32 == 3.2.3-2 11:15:38 INFO - jsonschema == 2.5.1 11:15:38 INFO - manifestparser == 1.1 11:15:38 INFO - mozInstall == 1.12 11:15:38 INFO - mozcrash == 1.0 11:15:38 INFO - mozdebug == 0.1 11:15:38 INFO - mozdevice == 0.50 11:15:38 INFO - mozfile == 1.2 11:15:38 INFO - mozhttpd == 0.7 11:15:38 INFO - mozinfo == 0.9 11:15:38 INFO - mozleak == 0.1 11:15:38 INFO - mozlog == 3.4 11:15:38 INFO - moznetwork == 0.27 11:15:38 INFO - mozprocess == 0.25 11:15:38 INFO - mozprofile == 0.28 11:15:38 INFO - mozrunner == 6.13 11:15:38 INFO - mozscreenshot == 0.1 11:15:38 INFO - mozsystemmonitor == 0.3 11:15:38 INFO - moztest == 0.8 11:15:38 INFO - mozversion == 1.4 11:15:38 INFO - psutil == 3.1.1 11:15:38 INFO - requests == 1.2.3 11:15:38 INFO - wsgiref == 0.1.2 11:15:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:38 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:38 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:15:38 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:15:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:38 INFO - 'HOME': '/Users/cltbld', 11:15:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:38 INFO - 'LOGNAME': 'cltbld', 11:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:38 INFO - 'MOZ_NO_REMOTE': '1', 11:15:38 INFO - 'NO_EM_RESTART': '1', 11:15:38 INFO - 'PAGER': '/bin/cat', 11:15:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:38 INFO - 'PWD': '/builds/slave/test', 11:15:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:38 INFO - 'SHELL': '/bin/bash', 11:15:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:38 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:38 INFO - 'USER': 'cltbld', 11:15:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:38 INFO - 'XPC_FLAGS': '0x0', 11:15:38 INFO - 'XPC_SERVICE_NAME': '0', 11:15:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 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)) 11:15:38 INFO - Cleaning up... 11:15:38 INFO - Return code: 0 11:15:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:15:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:38 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/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 0x1025e4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1025e8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd3da473ee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, '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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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 11:15:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:15:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:15:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:38 INFO - 'HOME': '/Users/cltbld', 11:15:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:38 INFO - 'LOGNAME': 'cltbld', 11:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:38 INFO - 'MOZ_NO_REMOTE': '1', 11:15:38 INFO - 'NO_EM_RESTART': '1', 11:15:38 INFO - 'PAGER': '/bin/cat', 11:15:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:38 INFO - 'PWD': '/builds/slave/test', 11:15:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:38 INFO - 'SHELL': '/bin/bash', 11:15:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:15:38 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:15:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:38 INFO - 'USER': 'cltbld', 11:15:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:38 INFO - 'XPC_FLAGS': '0x0', 11:15:38 INFO - 'XPC_SERVICE_NAME': '0', 11:15:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:15:38 INFO - Cleaning up... 11:15:38 INFO - Return code: 0 11:15:38 INFO - Running post-action listener: _resource_record_post_action 11:15:38 INFO - Running post-action listener: _start_resource_monitoring 11:15:38 INFO - Starting resource monitoring. 11:15:38 INFO - [mozharness: 2017-05-20 18:15:38.591872Z] Finished create-virtualenv step (success) 11:15:38 INFO - [mozharness: 2017-05-20 18:15:38.592916Z] Running install step. 11:15:38 INFO - Running pre-action listener: _resource_record_pre_action 11:15:38 INFO - Running main action method: install 11:15:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:38 INFO - Reading from file tmpfile_stdout 11:15:38 INFO - Detecting whether we're running mozinstall >=1.0... 11:15:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:15:38 INFO - Reading from file tmpfile_stdout 11:15:38 INFO - Output received: 11:15:38 INFO - Usage: mozinstall [options] installer 11:15:38 INFO - Options: 11:15:38 INFO - -h, --help show this help message and exit 11:15:38 INFO - -d DEST, --destination=DEST 11:15:38 INFO - Directory to install application into. [default: 11:15:38 INFO - "/builds/slave/test"] 11:15:38 INFO - --app=APP Application being installed. [default: firefox] 11:15:38 INFO - mkdir: /builds/slave/test/build/application 11:15:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:16:00 INFO - Reading from file tmpfile_stdout 11:16:00 INFO - Output received: 11:16:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:16:00 INFO - Running post-action listener: _resource_record_post_action 11:16:00 INFO - [mozharness: 2017-05-20 18:16:00.289074Z] Finished install step (success) 11:16:00 INFO - [mozharness: 2017-05-20 18:16:00.289210Z] Running run-tests step. 11:16:00 INFO - Running pre-action listener: _resource_record_pre_action 11:16:00 INFO - Running main action method: run_tests 11:16:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:16:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:16:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:16:00 INFO - 2017-05-20 11:16:00.304 screenresolution[1635:8014] starting screenresolution argv=screenresolution get 11:16:00 INFO - 2017-05-20 11:16:00.306 screenresolution[1635:8014] Display 0: 1600x1200x32@60 11:16:00 INFO - 2017-05-20 11:16:00.312 screenresolution[1636:8016] starting screenresolution argv=screenresolution list 11:16:00 INFO - Available Modes on Display 0 11:16:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:16:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:16:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:16:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:16:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:16:00 INFO - Intel Iris: 11:16:00 INFO - Chipset Model: Intel Iris 11:16:00 INFO - Type: GPU 11:16:00 INFO - Bus: Built-In 11:16:00 INFO - VRAM (Dynamic, Max): 1536 MB 11:16:00 INFO - Vendor: Intel (0x8086) 11:16:00 INFO - Device ID: 0x0a2e 11:16:00 INFO - Revision ID: 0x0009 11:16:00 INFO - Displays: 11:16:00 INFO - Display: 11:16:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:16:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:16:00 INFO - Display Serial Number: 1600x1200 60 11:16:00 INFO - Main Display: Yes 11:16:00 INFO - Mirror: Off 11:16:00 INFO - Online: Yes 11:16:00 INFO - Rotation: Supported 11:16:00 INFO - Return code: 0 11:16:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:16:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:16:00 INFO - Python 2.7.3 11:16:00 INFO - Return code: 0 11:16:00 INFO - grabbing minidump binary from tooltool 11:16:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 11:16:00 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 0x1022f4d30>, '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 0x1022a8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:16:00 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 11:16:00 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 11:16:00 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 11:16:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:16:00 INFO - Return code: 0 11:16:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:16:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:16:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:16:00 INFO - ENV: RUST_BACKTRACE is now 1 11:16:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:16:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:16:00 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/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 11:16:00 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/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0298 --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 11:16:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OIz6uvDLmr/Render', 11:16:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:16:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:16:00 INFO - 'HOME': '/Users/cltbld', 11:16:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:16:00 INFO - 'LOGNAME': 'cltbld', 11:16:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:16:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:16:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:16:00 INFO - 'MOZ_NO_REMOTE': '1', 11:16:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:16:00 INFO - 'NO_EM_RESTART': '1', 11:16:00 INFO - 'PAGER': '/bin/cat', 11:16:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:16:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:16:00 INFO - 'PWD': '/builds/slave/test', 11:16:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:16:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:16:00 INFO - 'RUST_BACKTRACE': '1', 11:16:00 INFO - 'SHELL': '/bin/bash', 11:16:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pyL3d8fnTC/Listeners', 11:16:00 INFO - 'TMPDIR': '/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/', 11:16:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:16:00 INFO - 'USER': 'cltbld', 11:16:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:16:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:16:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:16:00 INFO - 'XPC_FLAGS': '0x0', 11:16:00 INFO - 'XPC_SERVICE_NAME': '0', 11:16:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:16:00 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/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0298', '--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 11:16:00 INFO - mozversion application_buildid: 20170520095248 11:16:00 INFO - mozversion application_changeset: 5b74bbf20e803e299790d266fc6ebf5d53b7a1b7 11:16:00 INFO - mozversion application_display_name: Nightly 11:16:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:16:00 INFO - mozversion application_name: Firefox 11:16:00 INFO - mozversion application_remotingname: firefox 11:16:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:16:00 INFO - mozversion application_vendor: Mozilla 11:16:00 INFO - mozversion application_version: 55.0a1 11:16:00 INFO - mozversion platform_buildid: 20170520095248 11:16:00 INFO - mozversion platform_changeset: 5b74bbf20e803e299790d266fc6ebf5d53b7a1b7 11:16:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:16:00 INFO - mozversion platform_version: 55.0a1 11:16:00 INFO - using testdate: 1495304160 11:16:00 INFO - actual date: 1495304160 11:16:00 INFO - starting webserver on 'localhost:49226' 11:16:00 INFO - SUITE-START | Running 2 tests 11:16:00 INFO - TEST-START | dromaeo_css 11:16:00 INFO - operating with platform_type : mac_ 11:16:00 INFO - Initialising browser for dromaeo_css test... 11:16:00 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpeIAPhA/profile http://localhost:49226/getInfo.html) 11:16:01 INFO - PID 1646 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 11:16:03 INFO - PID 1646 | 2017-05-20 11:16:03.667 plugin-container[1648:8195] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d43, name = 'com.apple.tsm.portname' 11:16:03 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:16:03 INFO - PID 1646 | 2017-05-20 11:16:03.670 plugin-container[1648:8195] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 11:16:03 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:16:03 INFO - PID 1646 | 2017-05-20 11:16:03.670 plugin-container[1648:8195] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:16:03 INFO - PID 1646 | __metrics Screen width/height:1600/1200 11:16:03 INFO - PID 1646 | colorDepth:24 11:16:03 INFO - PID 1646 | Browser inner width/height: 1024/691 11:16:03 INFO - PID 1646 | __metrics 11:16:04 INFO - TEST-INFO | 1646: exit 0 11:16:04 INFO - Browser initialized. 11:16:04 INFO - Running cycle 1/1 for dromaeo_css test... 11:16:04 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmpeIAPhA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:16:06 INFO - PID 1651 | 2017-05-20 11:16:06.628 plugin-container[1652:8354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.tsm.portname' 11:16:06 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:16:06 INFO - PID 1651 | 2017-05-20 11:16:06.629 plugin-container[1652:8354] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b1b, name = 'com.apple.CFPasteboardClient' 11:16:06 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:16:06 INFO - PID 1651 | 2017-05-20 11:16:06.629 plugin-container[1652:8354] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:16:07 INFO - PID 1651 | 2017-05-20 11:16:07.341 plugin-container[1653:8399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 11:16:07 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:16:07 INFO - PID 1651 | 2017-05-20 11:16:07.342 plugin-container[1653:8399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 11:16:07 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:16:07 INFO - PID 1651 | 2017-05-20 11:16:07.342 plugin-container[1653:8399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:16:16 INFO - PID 1651 | RSS: Main: 241410048 11:16:16 INFO - PID 1651 | 11:19:20 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 11:19:21 INFO - PID 1651 | RSS: Main: 260947968 11:19:21 INFO - PID 1651 | 11:22:23 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 11:22:23 INFO - PID 1651 | RSS: Main: 268517376 11:22:23 INFO - PID 1651 | 11:25:25 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 11:25:26 INFO - PID 1651 | RSS: Main: 273870848 11:25:26 INFO - PID 1651 | 11:28:28 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 11:28:28 INFO - PID 1651 | RSS: Main: 271540224 11:28:28 INFO - PID 1651 | 11:31:31 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 11:31:31 INFO - PID 1651 | RSS: Main: 273076224 11:31:31 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 11:34:33 INFO - PID 1651 | RSS: Main: 274272256 11:34:33 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | __start_tp_report 11:34:33 INFO - PID 1651 | _x_x_mozilla_page_load 11:34:33 INFO - PID 1651 | _x_x_mozilla_page_load_details 11:34:33 INFO - PID 1651 | |i|pagename|runs| 11:34:33 INFO - PID 1651 | |0;dojo.html;3084.7532197424207;3154.4795108807034;3595.7303202259836;3625.2205775758207;3663.9267214655706;6123.526657168192;6214.9378506214925;6245.250569931609;6270.184875966123;6280.026595877646;6216.067589861512;6218.347073557279;6229.563930524865;6241.687915604224;6258.123862659231;5464.251439539349;5466.1527463243165;5515.751791169407;5547.97362487939;5593.077142271521;5030.087101471833;5337.11937530307;5538.141588053858;5572.136318870582;5590.636608620447;5460.863478413032;5496.615236933416;5519.39286678465;5560.582956278275;5577.191307260445;4512.706674066208;5493.725313734298;5538.695371754552;5569.637973531708;5600.52395546379;5469.398366179717;5528.612997090206;5529.838733865853;5535.972320138414;5594.272744543184;6199.907001394983;6205.565610407273;6296.307406185316;6296.87406251877;6299.779507717208;6575.375339342755;6653.700583473756;6658.533902626818;6705.832354191184;6736.157980252468;6815.727370905158;6847.863042739117;6858.862822743517;6879.3120687931305;6886.827829304308;6332.936670633235;6346.365363463617;6355.713992870332;6373.362663733589;6379.71291291893;6771.762988295484;6865.27914568973;6949.200841903245;7003.2296447390745;7031.226565077837;6643.368879956396;6678.031685405589;6691.5650482718465;6700.061991321219;6717.059611654372;6749.763758268536;6848.3494068063455;6871.690773915186;6876.277990810893;6879.690413931385;6533.705983230766;6596.406323430914;6607.867842643121;6657.966710166418;6753.561018533779;6654.567453115532;6678.33216678338;6718.428933540704;6719.0593316935665;6728.865422691617;5373.865653358697;5375.9462405376225;5392.892142157135;5408.783648654088;5420.376656684453;4755.548222918817;4758.904821903542;4767.070421267819;4778.092162489116;4808.134535783593;6346.619202847844;6355.904661430082;6408.231012278557;6436.098946147543;6464.547481676332;6852.931470685329;6875.243723190445;6875.346842049998;6876.277990810893;6884.208316043619;6311.368863113652;6360.332165122688;6403.839904002344;6412.935870641327;6421.068945002949;5805.709714514257;5903.291605007426;5940.851478713067;6001.579889407787;6023.548233882477;7009.54437961541;7015.438764898796;7032.648367581703;7053.470989675692;7065.6820443081215;5359.946400535945;6796.524243302922;6876.931230687629;6888.138982627172;6910.239873613999;6843.349881761224;6979.511434199558;6983.1620205575655;7065.57606543609;7090.929090709234;6592.17597800275;6652.135222421054;6670.266270710315;6719.328067193242;6755.493337999768;5748.827535173951;6688.665566721742;6851.520393572601;6895.034695142785;6901.275366086689;6662.83342916431;6675.599464032173;6687.598744075371;6690.899636505554;6712.2952090031795;5739.765950320701;6445.162128923397;6506.739730410731;6509.967450162719;6631.933680663132;5455.9633669602645;6080.513558915188;6425.935740642533;6432.453241474527;6460.903086453801;6424.132742079946;6487.124238227779;6548.8035358939305;6569;6641.966790166019;5500.972495137498;6478.838029049123;6495.935040649533;6500.934990650033;6535.281119076993 11:34:33 INFO - PID 1651 | |1;ext.html;793.1275596843473;804.8189157439576;824.3487644760639;1049.449039254391;1220.5178954313042;38830;39089.41365879507;39170.216595668106;39182.41226381606;39188.80405597968;39401.40897886534;39743.801280993626;39787.20425591486;39940.40089398654;39954.60045399545;7636.694532218705;7770.533767973911;8317.293030092449;8440.873386899202;8451.492910425364;8381.07808141104;8416.789580260513;8474.661013559451;8489.108643592395;8491.193336633041;7088.751893683722;7620.847583048336;7976.840463190732;8444.324454043663;8457.365697572706;8390.160983901591;8423.241908228227;8443.493390396565;8477.957610211972;8484.618192181366;8238.464499807493;8288.5855707215;8301.46040507365;8329.83340333196;8333;37044.814775926214;37070.62929370726;37144.814275928715;37219.62780372189;37235.06912327187;3543.5924868639922;3593.0658028912408;3596.5863925648364;3600.9099772505633;3601.7658852174527;30276.243093922607;30449.847750761324;30587.694123058707;30616.38767224643;30699.539506907415;3087.4133914556164;3098.7211150996295;3118.064580625826;3136.168915237464;3160.288934989632;213522;252258;280192;287410;290923;48862.75568622205;49226;49239.01521969541;49291.753541232785;49305.75347123241;50464;50922.23616645753;51115.488845111075;51184.97630047378;51300.23049654258;14228.502002429865;14399.42402303896;14403.78394324086;14417.351219195161;14452.132872027712;11684.357360345093;11692.532298707956;11695.649130526097;11699.88300117005;11712.531498740125;11022;13798.793018104736;13805.723885522435;13841.723165536632;13912.37394317258;5454.61817672767;5461.15352120422;6012.8575570642315;6043.48630366415;6248.687565621701;906.8503696890072;1016.8932262112372;1028.891966343523;1051.444988677521;1067.1622776120707;19829.60340793176;23246.883765581064;24380.14669486595;24574.754252457606;24665.136720215065;1113.4432783608195;1114.7157474844025;1147.8565179352581;1150.4822829726656;1154.1055681846635;9521.666741664147;12077.396130193456;12181.451834667285;12394.884460398607;12625.179363341353;312870;314621;316448;316995;317489;54469;54647.45352546424;54794.9999999992;54841.177382339294;55036.44963550313;1528.9923550382177;1534.424590778458;1564.4993602047236;1566.8041494813149;1572.0253442865496;22369;22462.32613021612;24124.51750964936;24245.272641820775;24340.391490212885;38624.613753862104;38697.80651096727;38726.41910371403;38734;38771.41842872303;274700;276341;276859;277272;277558;33843.492347614316;34580;35201.82399087988;35235.82382088073;35311.76408825679;43271.134577308905;43596.56403435925;44865;44944;45104.323435149156;29880.48204252688;30368.544471832498;30562.541561877035;31215.68784312128;31377;31045.844770776002;31100.68899310978;31147;31183.53224701586;31245.687543125186;37183.442248365754;37199.884003479936;37237.88286351414;37252;37278.627213727515;24202.636960445256;28876.133715988555;31408.685913140576;31605.20986975344;32054.519182211818;23850.16524421637;24465.633015505136;24520.754792451848;24581.631275530523;24740.433402484297 11:34:33 INFO - PID 1651 | |2;jquery.html;408.7670028083991;429.3216717586214;431.49946062567426;437.85550768246475;456.1993701054649;18864.434066977996;20408.979551022447;21102.788972110273;21296;21331.68002479964;21282.14871405146;22002;22217.888910555466;22340.329790106283;22600.886995565;5853.385394533577;6086.087086936951;6130.601510901799;6134.693265336738;6140.692965351737;5903.704814759266;5945.108233764949;6142.447179753821;6169.537284703642;6201.0388389799655;5991.281046274453;6087.269527656687;6140.447359737623;6142.815715528542;6153.200083989075;6021.36775638556;6102;6113.205283313163;6118.8470288242925;6137.969310153465;5224.320838291036;5384.0847056000575;5400.91898621521;5437.238786569884;5451;26369.472610547873;26456;26801.86599066992;27526.587101193498;27886.860565697043;2166.469215042325;2238.955220895573;2245.0795173978754;2247.78646028627;2250.2574150530286;14789.968056548381;17253.568660783454;17430.825691743175;17484.688648351424;17693;1838.5311745504944;2142.4001279641725;2163.405063607505;2170.0994087453687;2175.8585691929975;145191;155100.2244988779;155920;160261.19869400578;163642;10232.283740138268;12311.384430778604;12521.56174533887;12739.872601273868;12776.488940442465;13199.40602672882;15922.442714504938;16087.678246435005;16137.193140342935;16191.109488977983;8736.519491427905;9046.150462604259;9060.229880460234;9190.310726695525;9208.90791092094;8626.309895208367;8628.22345988864;8645.95677021611;8791.252743516745;8859.601317940707;7908.683652653829;8045.115037345888;8056.154103819132;8067.798305042421;8072.636731347104;2197.8571392859412;2197.967030494544;2203.3389983005036;2203.6253836847773;2314.1437668062927;907.4464576608264;915.6174176992789;985.1281615770096;987.9209663226925;994.5275993902983;19200.327988520334;19814.126951591938;19870.70193947092;19895.701564476545;20288.289909853083;866.9479831210148;885.420049867338;886.1979908182976;979.3389462113188;994.8060128274917;8176.354302047033;9872.308938374244;10518.105960993402;10542.894571054192;10550;413142;537358;545410;552240;553113;128191;130442;131268;132080;133677;1202.2185579373477;1222.2177806204036;1248.4070066718236;1252.5929073051295;1253.2856271925098;20569.07439165271;21751.9124043794;21778.89110554437;21801.127954881627;21835.01742421563;52299.47700522946;52395.47604524058;52633.7368313156;53587;53813.73093134509;60891.69554152201;61558.15325540241;68418.65790671014;71580;72195.27804721886;8499.107593702573;8703.825923481369;8745.256653184551;8905.421147625384;9032.32257580671;13539.323033848465;13688;13750.106243094167;13959.651008724863;13962.441502339792;11227.943860280646;11868.287902725864;11969.880301197225;12010.699732506408;12100.273983560663;9602.95198524003;10421.895781042396;11025.173112016791;11521.135914806591;11577.826332605186;11733.88266117362;11878.287302761863;12049.578264761041;12057.457414416196;12175.14773965814;8610.698625548075;8642.697505587274;8874.778130546789;8985.820283594161;9010.91868975727;11521.654350369503;11775.823362649739;11865.991390731884;11942.402879856147;12093.57672481459 11:34:33 INFO - PID 1651 | |3;mootools.html;1220.8473940757406;1358.5041459867152;1374.6082366525538;1375.9656008599777;1378.2626294932213;10253.487325633716;10461.320014199071;10749.408782516959;10755.193360497964;11050.447477626127;10533.525991330409;10716.892831071687;10727.08819750322;10735.248532602722;10829.241953063289;2469.3826543364157;2502.5620516409645;2509.761572650604;2511.8869650865754;2514.0446630280467;2437.622168563875;2488.4401009772837;2490.9626355604682;2491.9750802492017;2499.2252401755422;2444.3766839455916;2464.199135281032;2502.987485062581;2503.436726736488;2505.34860936157;2433.5862903306393;2448.7796098351146;2486.353548077504;2491.813114016456;2498.7626175513296;2404.1705611563984;2473.715522714893;2493.364192131013;2509.560826855293;2510.4226028013477;10551.050405463468;10598.046175844134;10644.148468122532;10649;10653.147748180127;6163.8150855474405;6366.331535188831;6390.6804659767295;6398.808035758934;6416.486681065504;3142.685731426862;4036.616521430459;4108.50697916249;4119.382092686121;4187.8324867005285;1187.7208855918852;1193.1349771415655;1210.4250481021531;1229.5327775445362;1236.5548402575066;14029.508967186202;14405;14566.543345665348;14692;14819;5117.437081920985;5200.531952124327;5318.574514038867;5347;5364.1417373220875;10787.352758834495;10897.891021089688;10902.727431814268;10915.290506117077;10937.617183398543;6235.035963671519;6235.844103897348;6238.313785483593;6240.687965601793;6245.188125543741;5664.858378540487;5786.855328616734;5827.825165245049;5838.649681019152;5848.502877255396;4107.732997355162;4199.874003779892;4221.746695198237;4235.195312890541;4265.914681706348;1230.477047254913;1243.6828608704723;1249.8000319948947;1257.1639859493516;1258.157034786695;672.6166085331314;772.4554189296532;791.7545560876147;798.4171554765054;805.621357961757;6302.338254483305;6310.6213627182515;6406.775762848278;6481.351864813481;6570.441512471493;909.054583233443;914.7118657622827;921.6129225725211;940.9435433873989;944.7071407863584;3115.5949726535405;3928.3910993796007;4021.2962731521866;4030.214108248922;4154.958450415457;12607.558735444216;12973.481060757464;12975.221486710827;12985.740285194242;12994.285314307805;13105.934470327778;13284.468621255235;13292.601221963356;13306;13311.800322995163;6306.9684651576445;7738.574378409129;8114.715984940499;8134.75595732129;8147.470414423044;5927.673977931083;6358.809235722936;6586.538942274186;6601.9009714853355;6651.73393064272;9727.854082188915;11770.234934729559;12275.447604857623;12349.259044457362;12384.56654017105;12137.635870923887;12211.145219834527;12249.571265005681;12402.5659101931;12570.937145314216;8457.365697572584;8559.700410485602;8571.742847714579;8692.956535217283;8709.956450217709;9700;9800.264980126376;9816.950915245377;9819.06718861707;9826.361286516354;7323.890141647986;8150.551719655476;8403.075661677332;8561.914380856111;8562.015368232733;6431.453326467115;8309.418340716333;8358.749237522885;8410.663573456994;8508.27679647237;7660.885086723815;7819.452638315263;7823.374130069467;7828.373730101464;7838.647260873388;5677.858053548694;5687.459691329317;5744.885102298014;5856.619319744203;5863.237779088691;4638.744869032235;5776.393478684678;5785.334686511105;5828.76684932598;5918.970405147947 11:34:33 INFO - PID 1651 | |4;prototype.html;316.8067478837908;372.515729551583;383.8925100971729;393.9507561554806;396.9126792105736;5774.566907481934;6041.758329666819;6448.355164483555;6507.121538592289;6528.804135875926;6198.72105755241;6278.711337892236;6282.151909492217;6394.200724909387;6422.421982021617;4592.242280023792;4600.826789168757;4805.693267231731;4811.9037619247765;4815.566599006089;4159.188958153161;4830.2030165022725;4834.97582512087;4840.177169881111;4851.63612729045;4398.296272596385;4566.49768525462;4594.540545945395;4678.883027924312;4803.903921921559;4180.958190418088;4580.381648477446;4581.404417425747;4585.747783871902;4600.148972440109;3895.2014836958638;3904.297226499229;3906.1797022625283;3910.061585219527;3920.5491368492703;6109.419605137505;6223.43989040984;6231.501479881644;6313.71588278524;6339.8415039623915;5456.536194423479;5465.262189604392;5479.808206712746;5480.424555421663;5484.808031718911;6406.679666016727;6570.667413068388;6829.93170068298;6830.214525329601;6836.28219036999;3543.8836766418754;3820.0067982324517;4024.376221685614;4356.499002614708;4386.956130438687;6317.273513545909;6621.934220321458;7049.365557099834;7189.388901943341;7267.745628902963;3934.213157368538;4240.893977650521;4421.734695918255;4434.312681534368;4473.888152796206;4538.183127037165;4546.318052292183;4548.385967894292;4549.658775591844;4551.8179272829375;4403.911921761547;5284.4187139414635;5414.267434420947;5445.646033007921;5478.753456094485;6439.61362318253;6456.774012909619;6828.487863410264;6882.827929301708;6889.827754306082;1301.1347453943022;1497.7903093566908;1531.9310631021408;1540.530138307827;1540.8135735483615;970.8253013853316;987.9802403952025;989.1592146675268;1000.8198524265703;1001.8747656542932;6454.644994525346;6458.257300410513;6472.8672482315405;6482.022982990234;6521.576097553644;6886.896696549556;7026.154407464985;7111.53775004623;7123.465740069516;7126.322999315057;5390.97304513475;5413.323334583177;5969.1495636352865;6373.3945275198785;6477.578957367756;3171.6035495563056;3274.6397896231397;3281.9671803281663;3388.678075582816;3630.41913293877;6331.620102793847;7276.563406195645;7278.381337586365;7308.159561650478;7331.193568707438;6467.611943283418;7265.963670181615;7324.656110044841;7333;7340.743073992385;6568.916128838688;6786.660666966731;7218.8917166242545;7247.528910620793;7284.6721897514735;6482;6960.965195173992;7117.8932316016335;7128.857422851617;7141.571505709674;6947.826304342432;6969.407600354027;6976.232614412512;6986.755463558751;6996.370326670624;5778.624389414675;7278.199398066103;7337.192908780136;7348.672212645789;7354.852902941804;4482.686211965131;4487.551244875486;4489.393931819293;4494.5730155635165;4511.616512596334;6028.07101727443;7099.8995155750945;7102.893456598258;7134;7194.856102878018;5743.597948143757;6858.639266759153;6881.93118068833;6893.862122757617;6945.722171113099;6721.764738234138;6845.178578570603;6905.964105384232;6913.51605387618;6937.167539895245;5624.859378515406;6560.639164845979;6725.654869026313;6738.225104113089;6799.728010879509;6206.565540412308;6701.698423570853;6764.3573860483175;6808.897866532105;6896.586204827727;5807.419258074159;6633.6019838809825;6675.7997260082275;6722.2605513394465;6724.125863637696 11:34:33 INFO - PID 1651 | |5;yui.html;817.3665409307782;831.912649171837;848.2874385516169;854.978625534362;865.584519430673;14480.2759862007;16312.510624681263;16702.916485417587;16786.49640510785;19134.61730765384;14727.48453804117;16825.32698692054;17034.574135646613;18289.085545722726;18332.816671833276;979.7207795778187;1000.7498125468633;1004.36724863336;1006.9980369532326;1011.2971484818055;1016.7305663999047;1017.0795430135739;1018.8267994440963;1020.1991436722175;1022.8005538919914;1058.9099926506256;1067.2849191042021;1071.1109778883547;1074.0977578833779;1075.2688172043008;1065.0946695309003;1066.6000249906285;1067.877194122678;1070.4273213830563;1073.5437439088396;634.3181080338654;779.1701837542998;787.3779714025912;788.3968763895624;790.1071788878588;14620.634484137981;15881.205939702968;17291.394801181897;17328.480145595528;17575.29698812046;16610.252538635792;16861.81967262292;16993.15034248295;17179.914100429418;17233.741493877602;13958.790618140736;14505.41978320866;14510.854891451056;14644.267786610626;14682.926585367004;3538.663826936437;3553.5025096486256;3560.12776869666;3573.6069032406413;3602;126670.69957654498;129859.05211421836;134023;134226;135389;12876.549320773727;13147.60557183286;13149.60551183466;13274.93362533181;13391;21452;25367.492650146894;25448.872755636105;25613.743862561136;25986.09048683287;6641.900371494431;7327.7909144991545;7514;7939.960300198462;7945.6027198640995;3827.081500439874;3942.5466071402157;3950.4469374287623;3955.64399204073;3963.7819919904105;4182.979085104555;4258.446401967786;4261.701680882308;4264.466941632296;4277.144571085795;901.1574178143456;901.9288775996091;921.9631214751469;923.0400383601113;923.1875949164692;614.9944840186328;620.4488778054863;623.8003838771549;633.7179954920016;666.3270097201861;11083.944580277208;11864.110191735654;11889;11991.340476273888;12171.93914030442;585.5900869391443;630.4326106504092;646.2309851276965;651.3779340729592;654.8886689262837;5060.898782024413;5353.7590808413715;5435.1847222916085;5492.780288788404;5562.694051827189;63845.68077159584;95678.04321956691;97966;98783.50608247056;99057.00942990622;62744;78067;79106.20893791104;79426;80784;76057.4788504238;76111.9999999989;76853.6157319221;77013;78600.82098768523;10713.196510261605;12168.965637920875;12225.877741222474;12536.185147965352;12548;40222.59220927254;40232.59767402289;40601.59398405978;40718.79640601896;40905.59094409137;49014;50125.24812127748;50376.74811625919;50564.49435505598;64349.06952791424;13124.671883202996;16807;16962.406315779383;17071.146442678066;17109;17856.821431786037;18534.536636584195;18558.164882580586;18635.72046419332;18740.531486712942;16150.838491614933;16730.330786768878;16853.915730421268;17342;17412.91293543524;15960.920195398949;16051.277692503632;16955.23701433414;17246.82753172452;17312.653746924738;15629.984051036647;16241.67516649684;16274.918625406797;16319.184040797674;16382.361763823523;11474;11599.246049006788;11880;12038.916497515265;12311.44598493052;12500.437480313225;12736.745265094462;12867.292298923649;12992.155509891827;13053.34733263352 11:34:33 INFO - PID 1651 | __end_tp_report 11:34:33 INFO - PID 1651 | __start_cc_report 11:34:33 INFO - PID 1651 | _x_x_mozilla_cycle_collect,348 11:34:33 INFO - PID 1651 | __end_cc_report 11:34:33 INFO - PID 1651 | __startTimestamp1495305273932__endTimestamp 11:34:33 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | ------- Summary: start ------- 11:34:33 INFO - PID 1651 | Number of tests: 6 11:34:33 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | [#0] dojo.html Cycles:165 Average:6207.53 Median:6436.10 stddev:772.18 (12.0%) stddev-sans-first:734.66 11:34:33 INFO - PID 1651 | Values: 3084.8 3154.5 3595.7 3625.2 3663.9 6123.5 6214.9 6245.3 6270.2 6280.0 6216.1 6218.3 6229.6 6241.7 6258.1 5464.3 5466.2 5515.8 5548.0 5593.1 5030.1 5337.1 5538.1 5572.1 5590.6 5460.9 5496.6 5519.4 5560.6 5577.2 4512.7 5493.7 5538.7 5569.6 5600.5 5469.4 5528.6 5529.8 5536.0 5594.3 6199.9 6205.6 6296.3 6296.9 6299.8 6575.4 6653.7 6658.5 6705.8 6736.2 6815.7 6847.9 6858.9 6879.3 6886.8 6332.9 6346.4 6355.7 6373.4 6379.7 6771.8 6865.3 6949.2 7003.2 7031.2 6643.4 6678.0 6691.6 6700.1 6717.1 6749.8 6848.3 6871.7 6876.3 6879.7 6533.7 6596.4 6607.9 6658.0 6753.6 6654.6 6678.3 6718.4 6719.1 6728.9 5373.9 5375.9 5392.9 5408.8 5420.4 4755.5 4758.9 4767.1 4778.1 4808.1 6346.6 6355.9 6408.2 6436.1 6464.5 6852.9 6875.2 6875.3 6876.3 6884.2 6311.4 6360.3 6403.8 6412.9 6421.1 5805.7 5903.3 5940.9 6001.6 6023.5 7009.5 7015.4 7032.6 7053.5 7065.7 5359.9 6796.5 6876.9 6888.1 6910.2 6843.3 6979.5 6983.2 7065.6 7090.9 6592.2 6652.1 6670.3 6719.3 6755.5 5748.8 6688.7 6851.5 6895.0 6901.3 6662.8 6675.6 6687.6 6690.9 6712.3 5739.8 6445.2 6506.7 6510.0 6631.9 5456.0 6080.5 6425.9 6432.5 6460.9 6424.1 6487.1 6548.8 6569.0 6642.0 5501.0 6478.8 6495.9 6500.9 6535.3 11:34:33 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | [#1] ext.html Cycles:180 Average:44054.60 Median:34211.75 stddev:75177.73 (219.7%) stddev-sans-first:75318.46 11:34:33 INFO - PID 1651 | Values: 793.1 804.8 824.3 1049.4 1220.5 38830.0 39089.4 39170.2 39182.4 39188.8 39401.4 39743.8 39787.2 39940.4 39954.6 7636.7 7770.5 8317.3 8440.9 8451.5 8381.1 8416.8 8474.7 8489.1 8491.2 7088.8 7620.8 7976.8 8444.3 8457.4 8390.2 8423.2 8443.5 8478.0 8484.6 8238.5 8288.6 8301.5 8329.8 8333.0 37044.8 37070.6 37144.8 37219.6 37235.1 3543.6 3593.1 3596.6 3600.9 3601.8 30276.2 30449.8 30587.7 30616.4 30699.5 3087.4 3098.7 3118.1 3136.2 3160.3 213522.0 252258.0 280192.0 287410.0 290923.0 48862.8 49226.0 49239.0 49291.8 49305.8 50464.0 50922.2 51115.5 51185.0 51300.2 14228.5 14399.4 14403.8 14417.4 14452.1 11684.4 11692.5 11695.6 11699.9 11712.5 11022.0 13798.8 13805.7 13841.7 13912.4 5454.6 5461.2 6012.9 6043.5 6248.7 906.9 1016.9 1028.9 1051.4 1067.2 19829.6 23246.9 24380.1 24574.8 24665.1 1113.4 1114.7 1147.9 1150.5 1154.1 9521.7 12077.4 12181.5 12394.9 12625.2 312870.0 314621.0 316448.0 316995.0 317489.0 54469.0 54647.5 54795.0 54841.2 55036.4 1529.0 1534.4 1564.5 1566.8 1572.0 22369.0 22462.3 24124.5 24245.3 24340.4 38624.6 38697.8 38726.4 38734.0 38771.4 274700.0 276341.0 276859.0 277272.0 277558.0 33843.5 34580.0 35201.8 35235.8 35311.8 43271.1 43596.6 44865.0 44944.0 45104.3 29880.5 30368.5 30562.5 31215.7 31377.0 31045.8 31100.7 31147.0 31183.5 31245.7 37183.4 37199.9 37237.9 37252.0 37278.6 24202.6 28876.1 31408.7 31605.2 32054.5 23850.2 24465.6 24520.8 24581.6 24740.4 11:34:33 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | [#2] jquery.html Cycles:180 Average:34341.79 Median:12457.52 stddev:89157.21 (715.7%) stddev-sans-first:89370.91 11:34:33 INFO - PID 1651 | Values: 408.8 429.3 431.5 437.9 456.2 18864.4 20409.0 21102.8 21296.0 21331.7 21282.1 22002.0 22217.9 22340.3 22600.9 5853.4 6086.1 6130.6 6134.7 6140.7 5903.7 5945.1 6142.4 6169.5 6201.0 5991.3 6087.3 6140.4 6142.8 6153.2 6021.4 6102.0 6113.2 6118.8 6138.0 5224.3 5384.1 5400.9 5437.2 5451.0 26369.5 26456.0 26801.9 27526.6 27886.9 2166.5 2239.0 2245.1 2247.8 2250.3 14790.0 17253.6 17430.8 17484.7 17693.0 1838.5 2142.4 2163.4 2170.1 2175.9 145191.0 155100.2 155920.0 160261.2 163642.0 10232.3 12311.4 12521.6 12739.9 12776.5 13199.4 15922.4 16087.7 16137.2 16191.1 8736.5 9046.2 9060.2 9190.3 9208.9 8626.3 8628.2 8646.0 8791.3 8859.6 7908.7 8045.1 8056.2 8067.8 8072.6 2197.9 2198.0 2203.3 2203.6 2314.1 907.4 915.6 985.1 987.9 994.5 19200.3 19814.1 19870.7 19895.7 20288.3 866.9 885.4 886.2 979.3 994.8 8176.4 9872.3 10518.1 10542.9 10550.0 413142.0 537358.0 545410.0 552240.0 553113.0 128191.0 130442.0 131268.0 132080.0 133677.0 1202.2 1222.2 1248.4 1252.6 1253.3 20569.1 21751.9 21778.9 21801.1 21835.0 52299.5 52395.5 52633.7 53587.0 53813.7 60891.7 61558.2 68418.7 71580.0 72195.3 8499.1 8703.8 8745.3 8905.4 9032.3 13539.3 13688.0 13750.1 13959.7 13962.4 11227.9 11868.3 11969.9 12010.7 12100.3 9603.0 10421.9 11025.2 11521.1 11577.8 11733.9 11878.3 12049.6 12057.5 12175.1 8610.7 8642.7 8874.8 8985.8 9010.9 11521.7 11775.8 11866.0 11942.4 12093.6 11:34:33 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | [#3] mootools.html Cycles:180 Average:6537.92 Median:4108.12 stddev:3950.42 (96.2%) stddev-sans-first:3941.29 11:34:33 INFO - PID 1651 | Values: 1220.8 1358.5 1374.6 1376.0 1378.3 10253.5 10461.3 10749.4 10755.2 11050.4 10533.5 10716.9 10727.1 10735.2 10829.2 2469.4 2502.6 2509.8 2511.9 2514.0 2437.6 2488.4 2491.0 2492.0 2499.2 2444.4 2464.2 2503.0 2503.4 2505.3 2433.6 2448.8 2486.4 2491.8 2498.8 2404.2 2473.7 2493.4 2509.6 2510.4 10551.1 10598.0 10644.1 10649.0 10653.1 6163.8 6366.3 6390.7 6398.8 6416.5 3142.7 4036.6 4108.5 4119.4 4187.8 1187.7 1193.1 1210.4 1229.5 1236.6 14029.5 14405.0 14566.5 14692.0 14819.0 5117.4 5200.5 5318.6 5347.0 5364.1 10787.4 10897.9 10902.7 10915.3 10937.6 6235.0 6235.8 6238.3 6240.7 6245.2 5664.9 5786.9 5827.8 5838.6 5848.5 4107.7 4199.9 4221.7 4235.2 4265.9 1230.5 1243.7 1249.8 1257.2 1258.2 672.6 772.5 791.8 798.4 805.6 6302.3 6310.6 6406.8 6481.4 6570.4 909.1 914.7 921.6 940.9 944.7 3115.6 3928.4 4021.3 4030.2 4155.0 12607.6 12973.5 12975.2 12985.7 12994.3 13105.9 13284.5 13292.6 13306.0 13311.8 6307.0 7738.6 8114.7 8134.8 8147.5 5927.7 6358.8 6586.5 6601.9 6651.7 9727.9 11770.2 12275.4 12349.3 12384.6 12137.6 12211.1 12249.6 12402.6 12570.9 8457.4 8559.7 8571.7 8693.0 8710.0 9700.0 9800.3 9817.0 9819.1 9826.4 7323.9 8150.6 8403.1 8561.9 8562.0 6431.5 8309.4 8358.7 8410.7 8508.3 7660.9 7819.5 7823.4 7828.4 7838.6 5677.9 5687.5 5744.9 5856.6 5863.2 4638.7 5776.4 5785.3 5828.8 5919.0 11:34:33 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | [#4] prototype.html Cycles:180 Average:5489.01 Median:6324.45 stddev:1785.55 (28.2%) stddev-sans-first:1747.85 11:34:33 INFO - PID 1651 | Values: 316.8 372.5 383.9 394.0 396.9 5774.6 6041.8 6448.4 6507.1 6528.8 6198.7 6278.7 6282.2 6394.2 6422.4 4592.2 4600.8 4805.7 4811.9 4815.6 4159.2 4830.2 4835.0 4840.2 4851.6 4398.3 4566.5 4594.5 4678.9 4803.9 4181.0 4580.4 4581.4 4585.7 4600.1 3895.2 3904.3 3906.2 3910.1 3920.5 6109.4 6223.4 6231.5 6313.7 6339.8 5456.5 5465.3 5479.8 5480.4 5484.8 6406.7 6570.7 6829.9 6830.2 6836.3 3543.9 3820.0 4024.4 4356.5 4387.0 6317.3 6621.9 7049.4 7189.4 7267.7 3934.2 4240.9 4421.7 4434.3 4473.9 4538.2 4546.3 4548.4 4549.7 4551.8 4403.9 5284.4 5414.3 5445.6 5478.8 6439.6 6456.8 6828.5 6882.8 6889.8 1301.1 1497.8 1531.9 1540.5 1540.8 970.8 988.0 989.2 1000.8 1001.9 6454.6 6458.3 6472.9 6482.0 6521.6 6886.9 7026.2 7111.5 7123.5 7126.3 5391.0 5413.3 5969.1 6373.4 6477.6 3171.6 3274.6 3282.0 3388.7 3630.4 6331.6 7276.6 7278.4 7308.2 7331.2 6467.6 7266.0 7324.7 7333.0 7340.7 6568.9 6786.7 7218.9 7247.5 7284.7 6482.0 6961.0 7117.9 7128.9 7141.6 6947.8 6969.4 6976.2 6986.8 6996.4 5778.6 7278.2 7337.2 7348.7 7354.9 4482.7 4487.6 4489.4 4494.6 4511.6 6028.1 7099.9 7102.9 7134.0 7194.9 5743.6 6858.6 6881.9 6893.9 6945.7 6721.8 6845.2 6906.0 6913.5 6937.2 5624.9 6560.6 6725.7 6738.2 6799.7 6206.6 6701.7 6764.4 6808.9 6896.6 5807.4 6633.6 6675.8 6722.3 6724.1 11:34:33 INFO - PID 1651 | 11:34:33 INFO - PID 1651 | [#5] yui.html Cycles:180 Average:20962.05 Median:17716.06 stddev:29244.65 (165.1%) stddev-sans-first:29287.57 11:34:33 INFO - PID 1651 | Values: 817.4 831.9 848.3 855.0 865.6 14480.3 16312.5 16702.9 16786.5 19134.6 14727.5 16825.3 17034.6 18289.1 18332.8 979.7 1000.7 1004.4 1007.0 1011.3 1016.7 1017.1 1018.8 1020.2 1022.8 1058.9 1067.3 1071.1 1074.1 1075.3 1065.1 1066.6 1067.9 1070.4 1073.5 634.3 779.2 787.4 788.4 790.1 14620.6 15881.2 17291.4 17328.5 17575.3 16610.3 16861.8 16993.2 17179.9 17233.7 13958.8 14505.4 14510.9 14644.3 14682.9 3538.7 3553.5 3560.1 3573.6 3602.0 126670.7 129859.1 134023.0 134226.0 135389.0 12876.5 13147.6 13149.6 13274.9 13391.0 21452.0 25367.5 25448.9 25613.7 25986.1 6641.9 7327.8 7514.0 7940.0 7945.6 3827.1 3942.5 3950.4 3955.6 3963.8 4183.0 4258.4 4261.7 4264.5 4277.1 901.2 901.9 922.0 923.0 923.2 615.0 620.4 623.8 633.7 666.3 11083.9 11864.1 11889.0 11991.3 12171.9 585.6 630.4 646.2 651.4 654.9 5060.9 5353.8 5435.2 5492.8 5562.7 63845.7 95678.0 97966.0 98783.5 99057.0 62744.0 78067.0 79106.2 79426.0 80784.0 76057.5 76112.0 76853.6 77013.0 78600.8 10713.2 12169.0 12225.9 12536.2 12548.0 40222.6 40232.6 40601.6 40718.8 40905.6 49014.0 50125.2 50376.7 50564.5 64349.1 13124.7 16807.0 16962.4 17071.1 17109.0 17856.8 18534.5 18558.2 18635.7 18740.5 16150.8 16730.3 16853.9 17342.0 17412.9 15960.9 16051.3 16955.2 17246.8 17312.7 15630.0 16241.7 16274.9 16319.2 16382.4 11474.0 11599.2 11880.0 12038.9 12311.4 12500.4 12736.7 12867.3 12992.2 13053.3 11:34:33 INFO - PID 1651 | -------- Summary: end -------- 11:34:33 INFO - PID 1651 | 11:34:34 INFO - TEST-INFO | 1651: exit 0 11:34:34 INFO - TEST-OK | dromaeo_css | took 1113760ms 11:34:34 INFO - TEST-START | kraken 11:34:34 INFO - operating with platform_type : mac_ 11:34:34 INFO - Initialising browser for kraken test... 11:34:34 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp_WyYg1/profile http://localhost:49226/getInfo.html) 11:34:35 INFO - PID 1661 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 11:34:37 INFO - PID 1661 | 2017-05-20 11:34:37.619 plugin-container[1662:10497] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 11:34:37 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:34:37 INFO - PID 1661 | 2017-05-20 11:34:37.624 plugin-container[1662:10497] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 11:34:37 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:34:37 INFO - PID 1661 | 2017-05-20 11:34:37.624 plugin-container[1662:10497] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:34:37 INFO - PID 1661 | __metrics Screen width/height:1600/1200 11:34:37 INFO - PID 1661 | colorDepth:24 11:34:37 INFO - PID 1661 | Browser inner width/height: 1024/691 11:34:37 INFO - PID 1661 | __metrics 11:34:38 INFO - PID 1661 | 2017-05-20 11:34:38.094 plugin-container[1663:10551] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 11:34:38 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:34:38 INFO - PID 1661 | 2017-05-20 11:34:38.095 plugin-container[1663:10551] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 11:34:38 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:34:38 INFO - PID 1661 | 2017-05-20 11:34:38.095 plugin-container[1663:10551] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:34:39 INFO - TEST-INFO | 1661: exit 0 11:34:39 INFO - Browser initialized. 11:34:39 INFO - Running cycle 1/1 for kraken test... 11:34:39 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/T/tmp_WyYg1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:34:41 INFO - PID 1664 | 2017-05-20 11:34:41.115 plugin-container[1665:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 11:34:41 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:34:41 INFO - PID 1664 | 2017-05-20 11:34:41.116 plugin-container[1665:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 11:34:41 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:34:41 INFO - PID 1664 | 2017-05-20 11:34:41.116 plugin-container[1665:10645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:34:41 INFO - PID 1664 | 2017-05-20 11:34:41.739 plugin-container[1666:10689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 11:34:41 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:34:41 INFO - PID 1664 | 2017-05-20 11:34:41.739 plugin-container[1666:10689] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d03, name = 'com.apple.CFPasteboardClient' 11:34:41 INFO - PID 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:34:41 INFO - PID 1664 | 2017-05-20 11:34:41.739 plugin-container[1666:10689] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:34:51 INFO - PID 1664 | RSS: Main: 245751808 11:34:51 INFO - PID 1664 | 11:34:53 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 11:34:54 INFO - PID 1664 | RSS: Main: 256655360 11:34:54 INFO - PID 1664 | 11:34:57 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 11:34:57 INFO - PID 1664 | RSS: Main: 265568256 11:34:57 INFO - PID 1664 | 11:35:01 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 11:35:01 INFO - PID 1664 | RSS: Main: 268566528 11:35:01 INFO - PID 1664 | 11:35:03 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 11:35:04 INFO - PID 1664 | RSS: Main: 270602240 11:35:04 INFO - PID 1664 | 11:35:06 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:35:06 INFO - PID 1664 | RSS: Main: 271020032 11:35:06 INFO - PID 1664 | 11:35:09 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 11:35:10 INFO - PID 1664 | RSS: Main: 281096192 11:35:10 INFO - PID 1664 | 11:35:13 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 11:35:14 INFO - PID 1664 | RSS: Main: 281034752 11:35:14 INFO - PID 1664 | 11:35:17 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 11:35:17 INFO - PID 1664 | RSS: Main: 280154112 11:35:17 INFO - PID 1664 | 11:35:19 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:35:20 INFO - PID 1664 | RSS: Main: 280432640 11:35:20 INFO - PID 1664 | 11:35:21 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:35:22 INFO - PID 1664 | RSS: Main: 280113152 11:35:22 INFO - PID 1664 | 11:35:24 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:35:25 INFO - PID 1664 | RSS: Main: 279662592 11:35:25 INFO - PID 1664 | 11:35:28 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:35:29 INFO - PID 1664 | RSS: Main: 280416256 11:35:29 INFO - PID 1664 | 11:35:32 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:35:32 INFO - PID 1664 | RSS: Main: 280268800 11:35:32 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 11:35:34 INFO - PID 1664 | RSS: Main: 282165248 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | __start_tp_report 11:35:34 INFO - PID 1664 | _x_x_mozilla_page_load 11:35:34 INFO - PID 1664 | _x_x_mozilla_page_load_details 11:35:34 INFO - PID 1664 | |i|pagename|runs| 11:35:34 INFO - PID 1664 | |0;ai-astar;103;116;114;114;116;116;115;114;116;114 11:35:34 INFO - PID 1664 | |1;audio-beat-detection;155;156;122;157;112;115;115;110;147;152 11:35:34 INFO - PID 1664 | |2;audio-dft;198;198;193;215;216;210;199;200;201;199 11:35:34 INFO - PID 1664 | |3;audio-fft;80;60;59;86;65;64;59;64;85;62 11:35:34 INFO - PID 1664 | |4;audio-oscillator;93;73;85;73;67;76;89;72;72;93 11:35:34 INFO - PID 1664 | |5;imaging-gaussian-blur;90;95;104;90;91;110;91;90;99;91 11:35:34 INFO - PID 1664 | |6;imaging-darkroom;96;98;97;97;104;156;144;97;98;96 11:35:34 INFO - PID 1664 | |7;imaging-desaturate;90;91;91;91;95;90;106;91;90;94 11:35:34 INFO - PID 1664 | |8;json-parse-financial;50;50;49;54;66;83;79;76;45;50 11:35:34 INFO - PID 1664 | |9;json-stringify-tinderbox;56;58;59;59;62;62;56;59;57;59 11:35:34 INFO - PID 1664 | |10;stanford-crypto-aes;80;88;74;80;74;71;110;112;113;104 11:35:34 INFO - PID 1664 | |11;stanford-crypto-ccm;135;165;154;134;147;161;138;179;209;145 11:35:34 INFO - PID 1664 | |12;stanford-crypto-pbkdf2;186;191;190;187;183;187;188;188;187;188 11:35:34 INFO - PID 1664 | |13;stanford-crypto-sha256-iterative;70;69;77;64;87;102;100;102;91;68 11:35:34 INFO - PID 1664 | __end_tp_report 11:35:34 INFO - PID 1664 | __start_cc_report 11:35:34 INFO - PID 1664 | _x_x_mozilla_cycle_collect,903 11:35:34 INFO - PID 1664 | __end_cc_report 11:35:34 INFO - PID 1664 | __startTimestamp1495305334966__endTimestamp 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | ------- Summary: start ------- 11:35:34 INFO - PID 1664 | Number of tests: 14 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.50 stddev:3.91 (3.4%) stddev-sans-first:1.00 11:35:34 INFO - PID 1664 | Values: 103.0 116.0 114.0 114.0 116.0 116.0 115.0 114.0 116.0 114.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:149.50 stddev:20.74 (13.9%) stddev-sans-first:20.58 11:35:34 INFO - PID 1664 | Values: 155.0 156.0 122.0 157.0 112.0 115.0 115.0 110.0 147.0 152.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#2] audio-dft Cycles:10 Average:202.90 Median:200.50 stddev:7.87 (3.9%) stddev-sans-first:8.14 11:35:34 INFO - PID 1664 | Values: 198.0 198.0 193.0 215.0 216.0 210.0 199.0 200.0 201.0 199.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#3] audio-fft Cycles:10 Average:68.40 Median:64.50 stddev:10.84 (16.8%) stddev-sans-first:10.66 11:35:34 INFO - PID 1664 | Values: 80.0 60.0 59.0 86.0 65.0 64.0 59.0 64.0 85.0 62.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:80.50 stddev:9.72 (12.1%) stddev-sans-first:8.96 11:35:34 INFO - PID 1664 | Values: 93.0 73.0 85.0 73.0 67.0 76.0 89.0 72.0 72.0 93.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:95.10 Median:91.00 stddev:7.03 (7.7%) stddev-sans-first:7.21 11:35:34 INFO - PID 1664 | Values: 90.0 95.0 104.0 90.0 91.0 110.0 91.0 90.0 99.0 91.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:97.00 stddev:22.28 (23.0%) stddev-sans-first:23.18 11:35:34 INFO - PID 1664 | Values: 96.0 98.0 97.0 97.0 104.0 156.0 144.0 97.0 98.0 96.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#7] imaging-desaturate Cycles:10 Average:92.90 Median:91.00 stddev:4.91 (5.4%) stddev-sans-first:5.09 11:35:34 INFO - PID 1664 | Values: 90.0 91.0 91.0 91.0 95.0 90.0 106.0 91.0 90.0 94.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:60.00 stddev:14.39 (24.0%) stddev-sans-first:14.78 11:35:34 INFO - PID 1664 | Values: 50.0 50.0 49.0 54.0 66.0 83.0 79.0 76.0 45.0 50.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:2.11 (3.6%) stddev-sans-first:2.00 11:35:34 INFO - PID 1664 | Values: 56.0 58.0 59.0 59.0 62.0 62.0 56.0 59.0 57.0 59.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:74.00 stddev:17.26 (23.3%) stddev-sans-first:17.88 11:35:34 INFO - PID 1664 | Values: 80.0 88.0 74.0 80.0 74.0 71.0 110.0 112.0 113.0 104.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:156.70 Median:157.50 stddev:23.32 (14.8%) stddev-sans-first:23.37 11:35:34 INFO - PID 1664 | Values: 135.0 165.0 154.0 134.0 147.0 161.0 138.0 179.0 209.0 145.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.50 Median:188.00 stddev:2.17 (1.2%) stddev-sans-first:2.24 11:35:34 INFO - PID 1664 | Values: 186.0 191.0 190.0 187.0 183.0 187.0 188.0 188.0 187.0 188.0 11:35:34 INFO - PID 1664 | 11:35:34 INFO - PID 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.00 Median:69.50 stddev:15.20 (21.9%) stddev-sans-first:15.37 11:35:34 INFO - PID 1664 | Values: 70.0 69.0 77.0 64.0 87.0 102.0 100.0 102.0 91.0 68.0 11:35:34 INFO - PID 1664 | -------- Summary: end -------- 11:35:34 INFO - PID 1664 | 11:35:35 INFO - TEST-INFO | 1664: exit 0 11:35:35 INFO - TEST-OK | kraken | took 61230ms 11:35:35 INFO - SUITE-END | took 1174s 11:35:36 INFO - Completed test suite (00:19:35) 11:35:36 INFO - javascript benchmark 11:35:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7811.345740367345, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3084.7532197424207, 3154.4795108807034, 3595.7303202259836, 3625.2205775758207, 3663.9267214655706, 6123.526657168192, 6214.9378506214925, 6245.250569931609, 6270.184875966123, 6280.026595877646, 6216.067589861512, 6218.347073557279, 6229.563930524865, 6241.687915604224, 6258.123862659231, 5464.251439539349, 5466.1527463243165, 5515.751791169407, 5547.97362487939, 5593.077142271521, 5030.087101471833, 5337.11937530307, 5538.141588053858, 5572.136318870582, 5590.636608620447, 5460.863478413032, 5496.615236933416, 5519.39286678465, 5560.582956278275, 5577.191307260445, 4512.706674066208, 5493.725313734298, 5538.695371754552, 5569.637973531708, 5600.52395546379, 5469.398366179717, 5528.612997090206, 5529.838733865853, 5535.972320138414, 5594.272744543184, 6199.907001394983, 6205.565610407273, 6296.307406185316, 6296.87406251877, 6299.779507717208, 6575.375339342755, 6653.700583473756, 6658.533902626818, 6705.832354191184, 6736.157980252468, 6815.727370905158, 6847.863042739117, 6858.862822743517, 6879.3120687931305, 6886.827829304308, 6332.936670633235, 6346.365363463617, 6355.713992870332, 6373.362663733589, 6379.71291291893, 6771.762988295484, 6865.27914568973, 6949.200841903245, 7003.2296447390745, 7031.226565077837, 6643.368879956396, 6678.031685405589, 6691.5650482718465, 6700.061991321219, 6717.059611654372, 6749.763758268536, 6848.3494068063455, 6871.690773915186, 6876.277990810893, 6879.690413931385, 6533.705983230766, 6596.406323430914, 6607.867842643121, 6657.966710166418, 6753.561018533779, 6654.567453115532, 6678.33216678338, 6718.428933540704, 6719.0593316935665, 6728.865422691617, 5373.865653358697, 5375.9462405376225, 5392.892142157135, 5408.783648654088, 5420.376656684453, 4755.548222918817, 4758.904821903542, 4767.070421267819, 4778.092162489116, 4808.134535783593, 6346.619202847844, 6355.904661430082, 6408.231012278557, 6436.098946147543, 6464.547481676332, 6852.931470685329, 6875.243723190445, 6875.346842049998, 6876.277990810893, 6884.208316043619, 6311.368863113652, 6360.332165122688, 6403.839904002344, 6412.935870641327, 6421.068945002949, 5805.709714514257, 5903.291605007426, 5940.851478713067, 6001.579889407787, 6023.548233882477, 7009.54437961541, 7015.438764898796, 7032.648367581703, 7053.470989675692, 7065.6820443081215, 5359.946400535945, 6796.524243302922, 6876.931230687629, 6888.138982627172, 6910.239873613999, 6843.349881761224, 6979.511434199558, 6983.1620205575655, 7065.57606543609, 7090.929090709234, 6592.17597800275, 6652.135222421054, 6670.266270710315, 6719.328067193242, 6755.493337999768, 5748.827535173951, 6688.665566721742, 6851.520393572601, 6895.034695142785, 6901.275366086689, 6662.83342916431, 6675.599464032173, 6687.598744075371, 6690.899636505554, 6712.2952090031795, 5739.765950320701, 6445.162128923397, 6506.739730410731, 6509.967450162719, 6631.933680663132, 5455.9633669602645, 6080.513558915188, 6425.935740642533, 6432.453241474527, 6460.903086453801, 6424.132742079946, 6487.124238227779, 6548.8035358939305, 6569.0, 6641.966790166019, 5500.972495137498, 6478.838029049123, 6495.935040649533, 6500.934990650033, 6535.281119076993], "value": 6153.626060760753, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.1275596843473, 804.8189157439576, 824.3487644760639, 1049.449039254391, 1220.5178954313042, 38830.0, 39089.41365879507, 39170.216595668106, 39182.41226381606, 39188.80405597968, 39401.40897886534, 39743.801280993626, 39787.20425591486, 39940.40089398654, 39954.60045399545, 7636.694532218705, 7770.533767973911, 8317.293030092449, 8440.873386899202, 8451.492910425364, 8381.07808141104, 8416.789580260513, 8474.661013559451, 8489.108643592395, 8491.193336633041, 7088.751893683722, 7620.847583048336, 7976.840463190732, 8444.324454043663, 8457.365697572706, 8390.160983901591, 8423.241908228227, 8443.493390396565, 8477.957610211972, 8484.618192181366, 8238.464499807493, 8288.5855707215, 8301.46040507365, 8329.83340333196, 8333.0, 37044.814775926214, 37070.62929370726, 37144.814275928715, 37219.62780372189, 37235.06912327187, 3543.5924868639922, 3593.0658028912408, 3596.5863925648364, 3600.9099772505633, 3601.7658852174527, 30276.243093922607, 30449.847750761324, 30587.694123058707, 30616.38767224643, 30699.539506907415, 3087.4133914556164, 3098.7211150996295, 3118.064580625826, 3136.168915237464, 3160.288934989632, 213522.0, 252258.0, 280192.0, 287410.0, 290923.0, 48862.75568622205, 49226.0, 49239.01521969541, 49291.753541232785, 49305.75347123241, 50464.0, 50922.23616645753, 51115.488845111075, 51184.97630047378, 51300.23049654258, 14228.502002429865, 14399.42402303896, 14403.78394324086, 14417.351219195161, 14452.132872027712, 11684.357360345093, 11692.532298707956, 11695.649130526097, 11699.88300117005, 11712.531498740125, 11022.0, 13798.793018104736, 13805.723885522435, 13841.723165536632, 13912.37394317258, 5454.61817672767, 5461.15352120422, 6012.8575570642315, 6043.48630366415, 6248.687565621701, 906.8503696890072, 1016.8932262112372, 1028.891966343523, 1051.444988677521, 1067.1622776120707, 19829.60340793176, 23246.883765581064, 24380.14669486595, 24574.754252457606, 24665.136720215065, 1113.4432783608195, 1114.7157474844025, 1147.8565179352581, 1150.4822829726656, 1154.1055681846635, 9521.666741664147, 12077.396130193456, 12181.451834667285, 12394.884460398607, 12625.179363341353, 312870.0, 314621.0, 316448.0, 316995.0, 317489.0, 54469.0, 54647.45352546424, 54794.9999999992, 54841.177382339294, 55036.44963550313, 1528.9923550382177, 1534.424590778458, 1564.4993602047236, 1566.8041494813149, 1572.0253442865496, 22369.0, 22462.32613021612, 24124.51750964936, 24245.272641820775, 24340.391490212885, 38624.613753862104, 38697.80651096727, 38726.41910371403, 38734.0, 38771.41842872303, 274700.0, 276341.0, 276859.0, 277272.0, 277558.0, 33843.492347614316, 34580.0, 35201.82399087988, 35235.82382088073, 35311.76408825679, 43271.134577308905, 43596.56403435925, 44865.0, 44944.0, 45104.323435149156, 29880.48204252688, 30368.544471832498, 30562.541561877035, 31215.68784312128, 31377.0, 31045.844770776002, 31100.68899310978, 31147.0, 31183.53224701586, 31245.687543125186, 37183.442248365754, 37199.884003479936, 37237.88286351414, 37252.0, 37278.627213727515, 24202.636960445256, 28876.133715988555, 31408.685913140576, 31605.20986975344, 32054.519182211818, 23850.16524421637, 24465.633015505136, 24520.754792451848, 24581.631275530523, 24740.433402484297], "value": 17464.387917302225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.7670028083991, 429.3216717586214, 431.49946062567426, 437.85550768246475, 456.1993701054649, 18864.434066977996, 20408.979551022447, 21102.788972110273, 21296.0, 21331.68002479964, 21282.14871405146, 22002.0, 22217.888910555466, 22340.329790106283, 22600.886995565, 5853.385394533577, 6086.087086936951, 6130.601510901799, 6134.693265336738, 6140.692965351737, 5903.704814759266, 5945.108233764949, 6142.447179753821, 6169.537284703642, 6201.0388389799655, 5991.281046274453, 6087.269527656687, 6140.447359737623, 6142.815715528542, 6153.200083989075, 6021.36775638556, 6102.0, 6113.205283313163, 6118.8470288242925, 6137.969310153465, 5224.320838291036, 5384.0847056000575, 5400.91898621521, 5437.238786569884, 5451.0, 26369.472610547873, 26456.0, 26801.86599066992, 27526.587101193498, 27886.860565697043, 2166.469215042325, 2238.955220895573, 2245.0795173978754, 2247.78646028627, 2250.2574150530286, 14789.968056548381, 17253.568660783454, 17430.825691743175, 17484.688648351424, 17693.0, 1838.5311745504944, 2142.4001279641725, 2163.405063607505, 2170.0994087453687, 2175.8585691929975, 145191.0, 155100.2244988779, 155920.0, 160261.19869400578, 163642.0, 10232.283740138268, 12311.384430778604, 12521.56174533887, 12739.872601273868, 12776.488940442465, 13199.40602672882, 15922.442714504938, 16087.678246435005, 16137.193140342935, 16191.109488977983, 8736.519491427905, 9046.150462604259, 9060.229880460234, 9190.310726695525, 9208.90791092094, 8626.309895208367, 8628.22345988864, 8645.95677021611, 8791.252743516745, 8859.601317940707, 7908.683652653829, 8045.115037345888, 8056.154103819132, 8067.798305042421, 8072.636731347104, 2197.8571392859412, 2197.967030494544, 2203.3389983005036, 2203.6253836847773, 2314.1437668062927, 907.4464576608264, 915.6174176992789, 985.1281615770096, 987.9209663226925, 994.5275993902983, 19200.327988520334, 19814.126951591938, 19870.70193947092, 19895.701564476545, 20288.289909853083, 866.9479831210148, 885.420049867338, 886.1979908182976, 979.3389462113188, 994.8060128274917, 8176.354302047033, 9872.308938374244, 10518.105960993402, 10542.894571054192, 10550.0, 413142.0, 537358.0, 545410.0, 552240.0, 553113.0, 128191.0, 130442.0, 131268.0, 132080.0, 133677.0, 1202.2185579373477, 1222.2177806204036, 1248.4070066718236, 1252.5929073051295, 1253.2856271925098, 20569.07439165271, 21751.9124043794, 21778.89110554437, 21801.127954881627, 21835.01742421563, 52299.47700522946, 52395.47604524058, 52633.7368313156, 53587.0, 53813.73093134509, 60891.69554152201, 61558.15325540241, 68418.65790671014, 71580.0, 72195.27804721886, 8499.107593702573, 8703.825923481369, 8745.256653184551, 8905.421147625384, 9032.32257580671, 13539.323033848465, 13688.0, 13750.106243094167, 13959.651008724863, 13962.441502339792, 11227.943860280646, 11868.287902725864, 11969.880301197225, 12010.699732506408, 12100.273983560663, 9602.95198524003, 10421.895781042396, 11025.173112016791, 11521.135914806591, 11577.826332605186, 11733.88266117362, 11878.287302761863, 12049.578264761041, 12057.457414416196, 12175.14773965814, 8610.698625548075, 8642.697505587274, 8874.778130546789, 8985.820283594161, 9010.91868975727, 11521.654350369503, 11775.823362649739, 11865.991390731884, 11942.402879856147, 12093.57672481459], "value": 10290.24797469392, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1220.8473940757406, 1358.5041459867152, 1374.6082366525538, 1375.9656008599777, 1378.2626294932213, 10253.487325633716, 10461.320014199071, 10749.408782516959, 10755.193360497964, 11050.447477626127, 10533.525991330409, 10716.892831071687, 10727.08819750322, 10735.248532602722, 10829.241953063289, 2469.3826543364157, 2502.5620516409645, 2509.761572650604, 2511.8869650865754, 2514.0446630280467, 2437.622168563875, 2488.4401009772837, 2490.9626355604682, 2491.9750802492017, 2499.2252401755422, 2444.3766839455916, 2464.199135281032, 2502.987485062581, 2503.436726736488, 2505.34860936157, 2433.5862903306393, 2448.7796098351146, 2486.353548077504, 2491.813114016456, 2498.7626175513296, 2404.1705611563984, 2473.715522714893, 2493.364192131013, 2509.560826855293, 2510.4226028013477, 10551.050405463468, 10598.046175844134, 10644.148468122532, 10649.0, 10653.147748180127, 6163.8150855474405, 6366.331535188831, 6390.6804659767295, 6398.808035758934, 6416.486681065504, 3142.685731426862, 4036.616521430459, 4108.50697916249, 4119.382092686121, 4187.8324867005285, 1187.7208855918852, 1193.1349771415655, 1210.4250481021531, 1229.5327775445362, 1236.5548402575066, 14029.508967186202, 14405.0, 14566.543345665348, 14692.0, 14819.0, 5117.437081920985, 5200.531952124327, 5318.574514038867, 5347.0, 5364.1417373220875, 10787.352758834495, 10897.891021089688, 10902.727431814268, 10915.290506117077, 10937.617183398543, 6235.035963671519, 6235.844103897348, 6238.313785483593, 6240.687965601793, 6245.188125543741, 5664.858378540487, 5786.855328616734, 5827.825165245049, 5838.649681019152, 5848.502877255396, 4107.732997355162, 4199.874003779892, 4221.746695198237, 4235.195312890541, 4265.914681706348, 1230.477047254913, 1243.6828608704723, 1249.8000319948947, 1257.1639859493516, 1258.157034786695, 672.6166085331314, 772.4554189296532, 791.7545560876147, 798.4171554765054, 805.621357961757, 6302.338254483305, 6310.6213627182515, 6406.775762848278, 6481.351864813481, 6570.441512471493, 909.054583233443, 914.7118657622827, 921.6129225725211, 940.9435433873989, 944.7071407863584, 3115.5949726535405, 3928.3910993796007, 4021.2962731521866, 4030.214108248922, 4154.958450415457, 12607.558735444216, 12973.481060757464, 12975.221486710827, 12985.740285194242, 12994.285314307805, 13105.934470327778, 13284.468621255235, 13292.601221963356, 13306.0, 13311.800322995163, 6306.9684651576445, 7738.574378409129, 8114.715984940499, 8134.75595732129, 8147.470414423044, 5927.673977931083, 6358.809235722936, 6586.538942274186, 6601.9009714853355, 6651.73393064272, 9727.854082188915, 11770.234934729559, 12275.447604857623, 12349.259044457362, 12384.56654017105, 12137.635870923887, 12211.145219834527, 12249.571265005681, 12402.5659101931, 12570.937145314216, 8457.365697572584, 8559.700410485602, 8571.742847714579, 8692.956535217283, 8709.956450217709, 9700.0, 9800.264980126376, 9816.950915245377, 9819.06718861707, 9826.361286516354, 7323.890141647986, 8150.551719655476, 8403.075661677332, 8561.914380856111, 8562.015368232733, 6431.453326467115, 8309.418340716333, 8358.749237522885, 8410.663573456994, 8508.27679647237, 7660.885086723815, 7819.452638315263, 7823.374130069467, 7828.373730101464, 7838.647260873388, 5677.858053548694, 5687.459691329317, 5744.885102298014, 5856.619319744203, 5863.237779088691, 4638.744869032235, 5776.393478684678, 5785.334686511105, 5828.76684932598, 5918.970405147947], "value": 5056.300799485709, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.8067478837908, 372.515729551583, 383.8925100971729, 393.9507561554806, 396.9126792105736, 5774.566907481934, 6041.758329666819, 6448.355164483555, 6507.121538592289, 6528.804135875926, 6198.72105755241, 6278.711337892236, 6282.151909492217, 6394.200724909387, 6422.421982021617, 4592.242280023792, 4600.826789168757, 4805.693267231731, 4811.9037619247765, 4815.566599006089, 4159.188958153161, 4830.2030165022725, 4834.97582512087, 4840.177169881111, 4851.63612729045, 4398.296272596385, 4566.49768525462, 4594.540545945395, 4678.883027924312, 4803.903921921559, 4180.958190418088, 4580.381648477446, 4581.404417425747, 4585.747783871902, 4600.148972440109, 3895.2014836958638, 3904.297226499229, 3906.1797022625283, 3910.061585219527, 3920.5491368492703, 6109.419605137505, 6223.43989040984, 6231.501479881644, 6313.71588278524, 6339.8415039623915, 5456.536194423479, 5465.262189604392, 5479.808206712746, 5480.424555421663, 5484.808031718911, 6406.679666016727, 6570.667413068388, 6829.93170068298, 6830.214525329601, 6836.28219036999, 3543.8836766418754, 3820.0067982324517, 4024.376221685614, 4356.499002614708, 4386.956130438687, 6317.273513545909, 6621.934220321458, 7049.365557099834, 7189.388901943341, 7267.745628902963, 3934.213157368538, 4240.893977650521, 4421.734695918255, 4434.312681534368, 4473.888152796206, 4538.183127037165, 4546.318052292183, 4548.385967894292, 4549.658775591844, 4551.8179272829375, 4403.911921761547, 5284.4187139414635, 5414.267434420947, 5445.646033007921, 5478.753456094485, 6439.61362318253, 6456.774012909619, 6828.487863410264, 6882.827929301708, 6889.827754306082, 1301.1347453943022, 1497.7903093566908, 1531.9310631021408, 1540.530138307827, 1540.8135735483615, 970.8253013853316, 987.9802403952025, 989.1592146675268, 1000.8198524265703, 1001.8747656542932, 6454.644994525346, 6458.257300410513, 6472.8672482315405, 6482.022982990234, 6521.576097553644, 6886.896696549556, 7026.154407464985, 7111.53775004623, 7123.465740069516, 7126.322999315057, 5390.97304513475, 5413.323334583177, 5969.1495636352865, 6373.3945275198785, 6477.578957367756, 3171.6035495563056, 3274.6397896231397, 3281.9671803281663, 3388.678075582816, 3630.41913293877, 6331.620102793847, 7276.563406195645, 7278.381337586365, 7308.159561650478, 7331.193568707438, 6467.611943283418, 7265.963670181615, 7324.656110044841, 7333.0, 7340.743073992385, 6568.916128838688, 6786.660666966731, 7218.8917166242545, 7247.528910620793, 7284.6721897514735, 6482.0, 6960.965195173992, 7117.8932316016335, 7128.857422851617, 7141.571505709674, 6947.826304342432, 6969.407600354027, 6976.232614412512, 6986.755463558751, 6996.370326670624, 5778.624389414675, 7278.199398066103, 7337.192908780136, 7348.672212645789, 7354.852902941804, 4482.686211965131, 4487.551244875486, 4489.393931819293, 4494.5730155635165, 4511.616512596334, 6028.07101727443, 7099.8995155750945, 7102.893456598258, 7134.0, 7194.856102878018, 5743.597948143757, 6858.639266759153, 6881.93118068833, 6893.862122757617, 6945.722171113099, 6721.764738234138, 6845.178578570603, 6905.964105384232, 6913.51605387618, 6937.167539895245, 5624.859378515406, 6560.639164845979, 6725.654869026313, 6738.225104113089, 6799.728010879509, 6206.565540412308, 6701.698423570853, 6764.3573860483175, 6808.897866532105, 6896.586204827727, 5807.419258074159, 6633.6019838809825, 6675.7997260082275, 6722.2605513394465, 6724.125863637696], "value": 4908.528720691918, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [817.3665409307782, 831.912649171837, 848.2874385516169, 854.978625534362, 865.584519430673, 14480.2759862007, 16312.510624681263, 16702.916485417587, 16786.49640510785, 19134.61730765384, 14727.48453804117, 16825.32698692054, 17034.574135646613, 18289.085545722726, 18332.816671833276, 979.7207795778187, 1000.7498125468633, 1004.36724863336, 1006.9980369532326, 1011.2971484818055, 1016.7305663999047, 1017.0795430135739, 1018.8267994440963, 1020.1991436722175, 1022.8005538919914, 1058.9099926506256, 1067.2849191042021, 1071.1109778883547, 1074.0977578833779, 1075.2688172043008, 1065.0946695309003, 1066.6000249906285, 1067.877194122678, 1070.4273213830563, 1073.5437439088396, 634.3181080338654, 779.1701837542998, 787.3779714025912, 788.3968763895624, 790.1071788878588, 14620.634484137981, 15881.205939702968, 17291.394801181897, 17328.480145595528, 17575.29698812046, 16610.252538635792, 16861.81967262292, 16993.15034248295, 17179.914100429418, 17233.741493877602, 13958.790618140736, 14505.41978320866, 14510.854891451056, 14644.267786610626, 14682.926585367004, 3538.663826936437, 3553.5025096486256, 3560.12776869666, 3573.6069032406413, 3602.0, 126670.69957654498, 129859.05211421836, 134023.0, 134226.0, 135389.0, 12876.549320773727, 13147.60557183286, 13149.60551183466, 13274.93362533181, 13391.0, 21452.0, 25367.492650146894, 25448.872755636105, 25613.743862561136, 25986.09048683287, 6641.900371494431, 7327.7909144991545, 7514.0, 7939.960300198462, 7945.6027198640995, 3827.081500439874, 3942.5466071402157, 3950.4469374287623, 3955.64399204073, 3963.7819919904105, 4182.979085104555, 4258.446401967786, 4261.701680882308, 4264.466941632296, 4277.144571085795, 901.1574178143456, 901.9288775996091, 921.9631214751469, 923.0400383601113, 923.1875949164692, 614.9944840186328, 620.4488778054863, 623.8003838771549, 633.7179954920016, 666.3270097201861, 11083.944580277208, 11864.110191735654, 11889.0, 11991.340476273888, 12171.93914030442, 585.5900869391443, 630.4326106504092, 646.2309851276965, 651.3779340729592, 654.8886689262837, 5060.898782024413, 5353.7590808413715, 5435.1847222916085, 5492.780288788404, 5562.694051827189, 63845.68077159584, 95678.04321956691, 97966.0, 98783.50608247056, 99057.00942990622, 62744.0, 78067.0, 79106.20893791104, 79426.0, 80784.0, 76057.4788504238, 76111.9999999989, 76853.6157319221, 77013.0, 78600.82098768523, 10713.196510261605, 12168.965637920875, 12225.877741222474, 12536.185147965352, 12548.0, 40222.59220927254, 40232.59767402289, 40601.59398405978, 40718.79640601896, 40905.59094409137, 49014.0, 50125.24812127748, 50376.74811625919, 50564.49435505598, 64349.06952791424, 13124.671883202996, 16807.0, 16962.406315779383, 17071.146442678066, 17109.0, 17856.821431786037, 18534.536636584195, 18558.164882580586, 18635.72046419332, 18740.531486712942, 16150.838491614933, 16730.330786768878, 16853.915730421268, 17342.0, 17412.91293543524, 15960.920195398949, 16051.277692503632, 16955.23701433414, 17246.82753172452, 17312.653746924738, 15629.984051036647, 16241.67516649684, 16274.918625406797, 16319.184040797674, 16382.361763823523, 11474.0, 11599.246049006788, 11880.0, 12038.916497515265, 12311.44598493052, 12500.437480313225, 12736.745265094462, 12867.292298923649, 12992.155509891827, 13053.34733263352], "value": 8276.175988373592, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1531.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 114.0, 114.0, 116.0, 116.0, 115.0, 114.0, 116.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 156.0, 122.0, 157.0, 112.0, 115.0, 115.0, 110.0, 147.0, 152.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 193.0, 215.0, 216.0, 210.0, 199.0, 200.0, 201.0, 199.0], "value": 202.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 60.0, 59.0, 86.0, 65.0, 64.0, 59.0, 64.0, 85.0, 62.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 73.0, 85.0, 73.0, 67.0, 76.0, 89.0, 72.0, 72.0, 93.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 104.0, 90.0, 91.0, 110.0, 91.0, 90.0, 99.0, 91.0], "value": 95.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 97.0, 97.0, 104.0, 156.0, 144.0, 97.0, 98.0, 96.0], "value": 108.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 91.0, 91.0, 95.0, 90.0, 106.0, 91.0, 90.0, 94.0], "value": 92.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 54.0, 66.0, 83.0, 79.0, 76.0, 45.0, 50.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 59.0, 59.0, 62.0, 62.0, 56.0, 59.0, 57.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 74.0, 80.0, 74.0, 71.0, 110.0, 112.0, 113.0, 104.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 165.0, 154.0, 134.0, 147.0, 161.0, 138.0, 179.0, 209.0, 145.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 191.0, 190.0, 187.0, 183.0, 187.0, 188.0, 188.0, 187.0, 188.0], "value": 187.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 77.0, 64.0, 87.0, 102.0, 100.0, 102.0, 91.0, 68.0], "value": 83.0, "unit": "score"}]}]} 11:35:36 INFO - Return code: 0 11:35:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:35:36 INFO - # TBPL SUCCESS # 11:35:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:35:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:35:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:35:36 INFO - 2017-05-20 11:35:36.299 screenresolution[1672:11132] starting screenresolution argv=screenresolution get 11:35:36 INFO - 2017-05-20 11:35:36.302 screenresolution[1672:11132] Display 0: 1600x1200x32@60 11:35:36 INFO - 2017-05-20 11:35:36.309 screenresolution[1673:11135] starting screenresolution argv=screenresolution list 11:35:36 INFO - Available Modes on Display 0 11:35:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:35:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:35:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:35:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:35:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:35:36 INFO - Intel Iris: 11:35:36 INFO - Chipset Model: Intel Iris 11:35:36 INFO - Type: GPU 11:35:36 INFO - Bus: Built-In 11:35:36 INFO - VRAM (Dynamic, Max): 1536 MB 11:35:36 INFO - Vendor: Intel (0x8086) 11:35:36 INFO - Device ID: 0x0a2e 11:35:36 INFO - Revision ID: 0x0009 11:35:36 INFO - Displays: 11:35:36 INFO - Display: 11:35:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:35:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:35:36 INFO - Display Serial Number: 1600x1200 60 11:35:36 INFO - Main Display: Yes 11:35:36 INFO - Mirror: Off 11:35:36 INFO - Online: Yes 11:35:36 INFO - Rotation: Supported 11:35:36 INFO - Return code: 0 11:35:36 INFO - Running post-action listener: _resource_record_post_action 11:35:36 INFO - [mozharness: 2017-05-20 18:35:36.545948Z] Finished run-tests step (success) 11:35:36 INFO - Running post-run listener: _resource_record_post_run 11:35:36 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 33965056; Write bytes: 565202944; Read time: 210; Write time: 1731 11:35:36 INFO - TinderboxPrint: CPU usage
54.0% 11:35:36 INFO - TinderboxPrint: I/O read bytes / time
33,965,056 / 210 11:35:36 INFO - TinderboxPrint: I/O write bytes / time
565,202,944 / 1,731 11:35:36 INFO - TinderboxPrint: CPU idle
2,205.2 (46.0%) 11:35:36 INFO - TinderboxPrint: CPU system
955.5 (20.0%) 11:35:36 INFO - TinderboxPrint: CPU user
1,628.9 (34.0%) 11:35:36 INFO - TinderboxPrint: Swap in / out
275,992,576 / 0 11:35:36 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229999104; Write bytes: 228077568; Read time: 16883; Write time: 470 11:35:36 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 33043456; Write bytes: 318693376; Read time: 200; Write time: 1210 11:35:37 INFO - Running post-run listener: _upload_blobber_files 11:35:37 INFO - Blob upload gear active. 11:35:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:35:37 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 11:35:37 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'] 11:35:37 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 11:35:37 INFO - (blobuploader) - INFO - Open directory for files ... 11:35:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:35:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:35:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:38 INFO - (blobuploader) - INFO - Done attempting. 11:35:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:35:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:35:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:39 INFO - (blobuploader) - INFO - Done attempting. 11:35:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:35:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:35:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:42 INFO - (blobuploader) - INFO - Done attempting. 11:35:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:35:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:35:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:44 INFO - (blobuploader) - INFO - Done attempting. 11:35:44 INFO - (blobuploader) - INFO - Iteration through files over. 11:35:44 INFO - Return code: 0 11:35:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:35:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:35:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62bbaa2b28ddf3c55012426aba040439fe021a15569edc83da0cbe7d18e676e3c2972817391b5477d7d6157412ea9238be05308717510afb05aff3a75e42f381", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b57f06bb5981127b4a006d0238f5776e7e9d3dd00924369d2cfd3a4915de8f79d9d6c1a6fe2da3146c90e641604c52829103e5a0700263ec99951a288fb87c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1cf2a08ab82c0d177327dfd7df78a4363658272d070e3aa49eefe765a7a82f6fb1c04b7a722b63fc56dd6070c2c41c2b6a7fce5800f8136d2eac32271d8a9a65", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5580460a498d46788955d28c3d264eb0b1f5977b3ca2f9b0aa693a61a108c11b261ad7773a25e541d049c61a302c5b8ad80a20f39cc367105c347f5fe9006049"} 11:35:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:35:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:35:44 INFO - Contents: 11:35:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62bbaa2b28ddf3c55012426aba040439fe021a15569edc83da0cbe7d18e676e3c2972817391b5477d7d6157412ea9238be05308717510afb05aff3a75e42f381", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b57f06bb5981127b4a006d0238f5776e7e9d3dd00924369d2cfd3a4915de8f79d9d6c1a6fe2da3146c90e641604c52829103e5a0700263ec99951a288fb87c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1cf2a08ab82c0d177327dfd7df78a4363658272d070e3aa49eefe765a7a82f6fb1c04b7a722b63fc56dd6070c2c41c2b6a7fce5800f8136d2eac32271d8a9a65", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5580460a498d46788955d28c3d264eb0b1f5977b3ca2f9b0aa693a61a108c11b261ad7773a25e541d049c61a302c5b8ad80a20f39cc367105c347f5fe9006049"} 11:35:44 INFO - Running post-run listener: copy_logs_to_upload_dir 11:35:44 INFO - Copying logs to upload dir... 11:35:44 INFO - mkdir: /builds/slave/test/build/upload/logs 11:35:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.315254 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-20 11:35:44.096108) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-20 11:35:44.100211) ========= 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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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/62bbaa2b28ddf3c55012426aba040439fe021a15569edc83da0cbe7d18e676e3c2972817391b5477d7d6157412ea9238be05308717510afb05aff3a75e42f381", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b57f06bb5981127b4a006d0238f5776e7e9d3dd00924369d2cfd3a4915de8f79d9d6c1a6fe2da3146c90e641604c52829103e5a0700263ec99951a288fb87c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1cf2a08ab82c0d177327dfd7df78a4363658272d070e3aa49eefe765a7a82f6fb1c04b7a722b63fc56dd6070c2c41c2b6a7fce5800f8136d2eac32271d8a9a65", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5580460a498d46788955d28c3d264eb0b1f5977b3ca2f9b0aa693a61a108c11b261ad7773a25e541d049c61a302c5b8ad80a20f39cc367105c347f5fe9006049"} build_url:https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011192 build_url: 'https://queue.taskcluster.net/v1/task/as-j4BcPQfu6JGYK2E6l5A/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62bbaa2b28ddf3c55012426aba040439fe021a15569edc83da0cbe7d18e676e3c2972817391b5477d7d6157412ea9238be05308717510afb05aff3a75e42f381", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b57f06bb5981127b4a006d0238f5776e7e9d3dd00924369d2cfd3a4915de8f79d9d6c1a6fe2da3146c90e641604c52829103e5a0700263ec99951a288fb87c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1cf2a08ab82c0d177327dfd7df78a4363658272d070e3aa49eefe765a7a82f6fb1c04b7a722b63fc56dd6070c2c41c2b6a7fce5800f8136d2eac32271d8a9a65", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5580460a498d46788955d28c3d264eb0b1f5977b3ca2f9b0aa693a61a108c11b261ad7773a25e541d049c61a302c5b8ad80a20f39cc367105c347f5fe9006049"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-20 11:35:44.133376) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-20 11:35:44.133772) ========= 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.OIz6uvDLmr/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.pyL3d8fnTC/Listeners TMPDIR=/var/folders/fy/0ys7dn115ps74h1p_jqwz2w400000w/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.005226 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-20 11:35:44.183405) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-20 11:35:44.183850) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-20 11:35:44.184360) ========= ========= Total master_lag: 0.23 =========