builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0082 starttime: 1495038373.98 results: success (0) revision: 85e5d15c31691c89b82d6068c26260416493071f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:13.978727) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:13.979166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:13.979442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:14.004717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:14.005063) ========= 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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-17 09:26:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.6M=0.001s 2017-05-17 09:26:14 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135189 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:14.162803) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:14.163132) ========= 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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.037020 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:14.220741) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 09:26:14.221075) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 85e5d15c31691c89b82d6068c26260416493071f --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 85e5d15c31691c89b82d6068c26260416493071f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AdL1X0V34Y/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.gxVzMc3bdI/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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-17 09:26:14,308 truncating revision to first 12 chars 2017-05-17 09:26:14,308 Setting DEBUG logging. 2017-05-17 09:26:14,309 attempt 1/10 2017-05-17 09:26:14,309 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/85e5d15c3169?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-17 09:26:15,148 unpacking tar archive at: mozilla-central-85e5d15c3169/testing/mozharness/ program finished with exit code 0 elapsedTime=1.113220 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-17 09:26:15.355803) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:15.356145) ========= script_repo_revision: 85e5d15c31691c89b82d6068c26260416493071f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:15.356511) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:15.356806) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-17 09:26:15.375820) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-17 09:26:15.376227) ========= /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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:26:15 INFO - MultiFileLogger online at 20170517 09:26:15 in /builds/slave/test 09:26:15 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 09:26:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:26:15 INFO - {'append_to_log': False, 09:26:15 INFO - 'base_work_dir': '/builds/slave/test', 09:26:15 INFO - 'blob_upload_branch': 'Firefox', 09:26:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:26:15 INFO - 'branch': 'Firefox', 09:26:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:26:15 INFO - 'config_files': ('talos/mac_config.py',), 09:26:15 INFO - 'default_actions': ('clobber', 09:26:15 INFO - 'read-buildbot-config', 09:26:15 INFO - 'download-and-extract', 09:26:15 INFO - 'populate-webroot', 09:26:15 INFO - 'create-virtualenv', 09:26:15 INFO - 'install', 09:26:15 INFO - 'run-tests'), 09:26:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:26:15 INFO - 'download_minidump_stackwalk': True, 09:26:15 INFO - 'download_symbols': 'ondemand', 09:26:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:26:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:26:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:26:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:26:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:26:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:26:15 INFO - 'gecko_profile': False, 09:26:15 INFO - 'gecko_profile_interval': 0, 09:26:15 INFO - 'installer_path': 'installer.exe', 09:26:15 INFO - 'log_level': 'info', 09:26:15 INFO - 'log_name': 'talos', 09:26:15 INFO - 'log_to_console': True, 09:26:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:26:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:26:15 INFO - 'opt_config_files': (), 09:26:15 INFO - 'pip_index': False, 09:26:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:15 INFO - 'cmd': ('bash', 09:26:15 INFO - '-c', 09:26:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:15 INFO - 'enabled': True, 09:26:15 INFO - 'halt_on_failure': False, 09:26:15 INFO - 'name': 'check_screen_resolution'},), 09:26:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:26:15 INFO - 'cmd': ('bash', 09:26:15 INFO - '-c', 09:26:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:26:15 INFO - 'enabled': True, 09:26:15 INFO - 'halt_on_failure': False, 09:26:15 INFO - 'name': 'check_screen_resolution'},), 09:26:15 INFO - 'run_cmd_checks_enabled': True, 09:26:15 INFO - 'suite': 'dromaeojs-e10s', 09:26:15 INFO - 'system_bits': '32', 09:26:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:26:15 INFO - 'title': 't-yosemite-r7-0082', 09:26:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:26:15 INFO - 'use_talos_json': True, 09:26:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:26:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:26:15 INFO - 'work_dir': 'build'} 09:26:15 INFO - [mozharness: 2017-05-17 16:26:15.578063Z] Running clobber step. 09:26:15 INFO - Running pre-action listener: _resource_record_pre_action 09:26:15 INFO - Running main action method: clobber 09:26:15 INFO - rmtree: /builds/slave/test/build 09:26:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:26:18 INFO - Running post-action listener: _resource_record_post_action 09:26:18 INFO - [mozharness: 2017-05-17 16:26:18.182002Z] Finished clobber step (success) 09:26:18 INFO - [mozharness: 2017-05-17 16:26:18.182126Z] Running read-buildbot-config step. 09:26:18 INFO - Running pre-action listener: _resource_record_pre_action 09:26:18 INFO - Running main action method: read_buildbot_config 09:26:18 INFO - Using buildbot properties: 09:26:18 INFO - { 09:26:18 INFO - "project": "", 09:26:18 INFO - "product": "firefox", 09:26:18 INFO - "who": "ffxbld@noreply.mozilla.org", 09:26:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:26:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:26:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:26:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:26:18 INFO - "stage_platform": "macosx64", 09:26:18 INFO - "basedir": "/builds/slave/test", 09:26:18 INFO - "buildnumber": 68, 09:26:18 INFO - "platform": "yosemite_r7", 09:26:18 INFO - "parent_task_id": "V92bddFaSrSUUY_zvPx1hQ", 09:26:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:26:18 INFO - "slavebuilddir": "test", 09:26:18 INFO - "taskId": "MUVmduh5TvqwMPGzVGqq7A", 09:26:18 INFO - "branch": "mozilla-central", 09:26:18 INFO - "script_repo_revision": "production", 09:26:18 INFO - "revision": "85e5d15c31691c89b82d6068c26260416493071f", 09:26:18 INFO - "slavename": "t-yosemite-r7-0082", 09:26:18 INFO - "repo_path": "mozilla-central" 09:26:18 INFO - } 09:26:18 INFO - Finding installer, test and symbols from parent task. 09:26:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MUVmduh5TvqwMPGzVGqq7A'}, attempt #1 09:26:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MUVmduh5TvqwMPGzVGqq7A'}, attempt #1 09:26:19 INFO - Task dependencies: U_FxPXOWRmG8HmSUvte0IQ 09:26:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ'}, attempt #1 09:26:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:26:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/test_packages.json 09:26:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/target.crashreporter-symbols.zip 09:26:19 INFO - Running post-action listener: _resource_record_post_action 09:26:19 INFO - [mozharness: 2017-05-17 16:26:19.902844Z] Finished read-buildbot-config step (success) 09:26:19 INFO - [mozharness: 2017-05-17 16:26:19.902989Z] Running download-and-extract step. 09:26:19 INFO - Running pre-action listener: _resource_record_pre_action 09:26:19 INFO - Running main action method: download_and_extract 09:26:19 INFO - mkdir: /builds/slave/test/build/tests 09:26:19 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')]} 09:26:19 INFO - trying https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:26:19 INFO - Downloading https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/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 09:26:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/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 09:26:20 INFO - Downloaded 1325 bytes. 09:26:20 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:26:20 INFO - Using the following test package requirements: 09:26:20 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:26:20 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:26:20 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:26:20 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:26:20 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'jsshell-mac.zip'], 09:26:20 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:26:20 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:26:20 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:26:20 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:26:20 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:26:20 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:26:20 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:26:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:26:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:26:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:26:20 INFO - Fetch https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:26:22 INFO - Content-Length response header: 56567101 09:26:22 INFO - Bytes received: 56567101 09:26:40 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 09:26:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:26:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:26:40 INFO - Fetch https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:26:42 INFO - Content-Length response header: 56567101 09:26:42 INFO - Bytes received: 56567101 09:26:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:26:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:26:59 INFO - Fetch https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:27:00 INFO - Content-Length response header: 14410069 09:27:00 INFO - Bytes received: 14410069 09:27: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')]} 09:27:00 INFO - trying https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:27:00 INFO - Downloading https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:27:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/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 09:27:03 INFO - Downloaded 75915018 bytes. 09:27:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:27:03 INFO - mkdir: /builds/slave/test/properties 09:27:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:27:03 INFO - Writing to file /builds/slave/test/properties/build_url 09:27:03 INFO - Contents: 09:27:03 INFO - build_url:https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:27:03 INFO - Running post-action listener: _resource_record_post_action 09:27:03 INFO - Running post-action listener: set_extra_try_arguments 09:27:03 INFO - [mozharness: 2017-05-17 16:27:03.046604Z] Finished download-and-extract step (success) 09:27:03 INFO - [mozharness: 2017-05-17 16:27:03.046751Z] Running populate-webroot step. 09:27:03 INFO - Running pre-action listener: _resource_record_pre_action 09:27:03 INFO - Running main action method: populate_webroot 09:27:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:27:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:27:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:27:03 INFO - '--tppagecycles', 09:27:03 INFO - '7'], 09:27:03 INFO - 'tests': ['tsvgm']}}, 09:27:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:27:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:27:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:27:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:27:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:27:03 INFO - 'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:27:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:27:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:27:03 INFO - 'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['damp', 'tps']}, 09:27:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:27:03 INFO - 'tests': ['damp', 'tps']}, 09:27:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['dromaeo_dom']}, 09:27:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:27:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:27:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:27:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:27:03 INFO - 'tests': ['ts_paint', 'tp5o']}, 09:27:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['a11yr', 09:27:03 INFO - 'ts_paint', 09:27:03 INFO - 'tpaint', 09:27:03 INFO - 'sessionrestore', 09:27:03 INFO - 'sessionrestore_no_auto_restore', 09:27:03 INFO - 'tabpaint']}, 09:27:03 INFO - 'other-e10s': {'tests': ['a11yr', 09:27:03 INFO - 'ts_paint', 09:27:03 INFO - 'tpaint', 09:27:03 INFO - 'sessionrestore', 09:27:03 INFO - 'sessionrestore_no_auto_restore', 09:27:03 INFO - 'tabpaint']}, 09:27:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:27:03 INFO - 'ts_paint', 09:27:03 INFO - 'tpaint', 09:27:03 INFO - 'sessionrestore', 09:27:03 INFO - 'sessionrestore_no_auto_restore', 09:27:03 INFO - 'tabpaint']}, 09:27:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:27:03 INFO - 'ts_paint', 09:27:03 INFO - 'tpaint', 09:27:03 INFO - 'sessionrestore', 09:27:03 INFO - 'sessionrestore_no_auto_restore', 09:27:03 INFO - 'tabpaint']}, 09:27:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['a11yr', 09:27:03 INFO - 'ts_paint', 09:27:03 INFO - 'tpaint', 09:27:03 INFO - 'sessionrestore', 09:27:03 INFO - 'sessionrestore_no_auto_restore', 09:27:03 INFO - 'tabpaint']}, 09:27:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['a11yr', 09:27:03 INFO - 'ts_paint', 09:27:03 INFO - 'tpaint', 09:27:03 INFO - 'sessionrestore', 09:27:03 INFO - 'sessionrestore_no_auto_restore', 09:27:03 INFO - 'tabpaint']}, 09:27:03 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:27:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:27:03 INFO - 'bloom_basic_ref']}, 09:27:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['tsvgx', 09:27:03 INFO - 'tsvgr_opacity', 09:27:03 INFO - 'tart', 09:27:03 INFO - 'tscrollx', 09:27:03 INFO - 'cart', 09:27:03 INFO - 'tsvg_static']}, 09:27:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:27:03 INFO - 'tsvgr_opacity', 09:27:03 INFO - 'tart', 09:27:03 INFO - 'tscrollx', 09:27:03 INFO - 'cart', 09:27:03 INFO - 'tsvg_static']}, 09:27:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:27:03 INFO - 'talos_options': ['--disable-e10s'], 09:27:03 INFO - 'tests': ['tp5o']}, 09:27:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:27:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:27:03 INFO - 'talos_options': ['--disable-e10s', 09:27:03 INFO - '--xperf_path', 09:27:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:03 INFO - 'tests': ['tp5n']}, 09:27:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:27:03 INFO - 'talos_options': ['--xperf_path', 09:27:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:27:03 INFO - 'tests': ['tp5n']}}, 09:27:03 INFO - 'talos.zip': {'path': '', 09:27:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:27:03 INFO - Running post-action listener: _resource_record_post_action 09:27:03 INFO - [mozharness: 2017-05-17 16:27:03.068392Z] Finished populate-webroot step (success) 09:27:03 INFO - [mozharness: 2017-05-17 16:27:03.068528Z] Running create-virtualenv step. 09:27:03 INFO - Running pre-action listener: _resource_record_pre_action 09:27:03 INFO - Running main action method: create_virtualenv 09:27:03 INFO - Creating virtualenv /builds/slave/test/build/venv 09:27:03 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 09:27:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:27:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:27:03 INFO - Using real prefix '/tools/python27' 09:27:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:27:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:27:06 INFO - Installing pip.................done. 09:27:06 INFO - Return code: 0 09:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:27:07 INFO - Reading from file tmpfile_stdout 09:27:07 INFO - Output received: 09:27:07 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) 09:27:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:27:07 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')]} 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:07 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')]} 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:07 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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:07 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 09:27:07 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 09:27:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:07 INFO - 'HOME': '/Users/cltbld', 09:27:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:07 INFO - 'LOGNAME': 'cltbld', 09:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:07 INFO - 'MOZ_NO_REMOTE': '1', 09:27:07 INFO - 'NO_EM_RESTART': '1', 09:27:07 INFO - 'PAGER': '/bin/cat', 09:27:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:07 INFO - 'PWD': '/builds/slave/test', 09:27:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:07 INFO - 'SHELL': '/bin/bash', 09:27:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:07 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:07 INFO - 'USER': 'cltbld', 09:27:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:07 INFO - 'XPC_FLAGS': '0x0', 09:27:07 INFO - 'XPC_SERVICE_NAME': '0', 09:27:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:07 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 09:27:07 INFO - Cleaning up... 09:27:07 INFO - Return code: 0 09:27:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:27:07 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')]} 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:07 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')]} 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:07 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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:07 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 09:27:07 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 09:27:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:07 INFO - 'HOME': '/Users/cltbld', 09:27:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:07 INFO - 'LOGNAME': 'cltbld', 09:27:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:07 INFO - 'MOZ_NO_REMOTE': '1', 09:27:07 INFO - 'NO_EM_RESTART': '1', 09:27:07 INFO - 'PAGER': '/bin/cat', 09:27:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:07 INFO - 'PWD': '/builds/slave/test', 09:27:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:07 INFO - 'SHELL': '/bin/bash', 09:27:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:07 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:07 INFO - 'USER': 'cltbld', 09:27:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:07 INFO - 'XPC_FLAGS': '0x0', 09:27:07 INFO - 'XPC_SERVICE_NAME': '0', 09:27:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:07 INFO - Downloading/unpacking psutil>=3.1.1 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:27:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:09 INFO - Installing collected packages: psutil 09:27:09 INFO - Running setup.py install for psutil 09:27:09 INFO - building 'psutil._psutil_osx' extension 09:27:09 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 09:27:10 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 09:27:10 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 09:27:10 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 09:27:10 INFO - building 'psutil._psutil_posix' extension 09:27:10 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 09:27:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:27:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:27:10 INFO - ^ 09:27:10 INFO - 1 warning generated. 09:27:10 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 09:27:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:27:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:27:10 INFO - Successfully installed psutil 09:27:10 INFO - Cleaning up... 09:27:10 INFO - Return code: 0 09:27:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:27:10 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')]} 09:27:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27: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')]} 09:27:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:11 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 09:27:11 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 09:27:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:11 INFO - 'HOME': '/Users/cltbld', 09:27:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:11 INFO - 'LOGNAME': 'cltbld', 09:27:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:11 INFO - 'MOZ_NO_REMOTE': '1', 09:27:11 INFO - 'NO_EM_RESTART': '1', 09:27:11 INFO - 'PAGER': '/bin/cat', 09:27:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:11 INFO - 'PWD': '/builds/slave/test', 09:27:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:11 INFO - 'SHELL': '/bin/bash', 09:27:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:11 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:11 INFO - 'USER': 'cltbld', 09:27:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:11 INFO - 'XPC_FLAGS': '0x0', 09:27:11 INFO - 'XPC_SERVICE_NAME': '0', 09:27:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:27:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:27:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:27:13 INFO - Installing collected packages: mozsystemmonitor 09:27:13 INFO - Running setup.py install for mozsystemmonitor 09:27:13 INFO - Successfully installed mozsystemmonitor 09:27:13 INFO - Cleaning up... 09:27:13 INFO - Return code: 0 09:27:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:27:13 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')]} 09:27:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:13 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')]} 09:27:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:13 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 09:27:13 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 09:27:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:13 INFO - 'HOME': '/Users/cltbld', 09:27:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:13 INFO - 'LOGNAME': 'cltbld', 09:27:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:13 INFO - 'MOZ_NO_REMOTE': '1', 09:27:13 INFO - 'NO_EM_RESTART': '1', 09:27:13 INFO - 'PAGER': '/bin/cat', 09:27:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:13 INFO - 'PWD': '/builds/slave/test', 09:27:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:13 INFO - 'SHELL': '/bin/bash', 09:27:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:13 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:13 INFO - 'USER': 'cltbld', 09:27:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:13 INFO - 'XPC_FLAGS': '0x0', 09:27:13 INFO - 'XPC_SERVICE_NAME': '0', 09:27:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:14 INFO - Downloading/unpacking jsonschema==2.5.1 09:27:14 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 09:27:14 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 09:27:14 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 09:27:14 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 09:27:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:27:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:15 INFO - Downloading functools32-3.2.3-2.tar.gz 09:27:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:27:15 INFO - warning: no files found matching '*.txt' 09:27:15 INFO - no previously-included directories found matching 'build' 09:27:15 INFO - no previously-included directories found matching 'dist' 09:27:15 INFO - no previously-included directories found matching '.git*' 09:27:15 INFO - Installing collected packages: jsonschema, functools32 09:27:15 INFO - Running setup.py install for functools32 09:27:16 INFO - warning: no files found matching '*.txt' 09:27:16 INFO - no previously-included directories found matching 'build' 09:27:16 INFO - no previously-included directories found matching 'dist' 09:27:16 INFO - no previously-included directories found matching '.git*' 09:27:16 INFO - Successfully installed jsonschema functools32 09:27:16 INFO - Cleaning up... 09:27:16 INFO - Return code: 0 09:27:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:27:16 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')]} 09:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:16 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')]} 09:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:16 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 09:27:16 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 09:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:16 INFO - 'HOME': '/Users/cltbld', 09:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:16 INFO - 'LOGNAME': 'cltbld', 09:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:16 INFO - 'MOZ_NO_REMOTE': '1', 09:27:16 INFO - 'NO_EM_RESTART': '1', 09:27:16 INFO - 'PAGER': '/bin/cat', 09:27:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:16 INFO - 'PWD': '/builds/slave/test', 09:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:16 INFO - 'SHELL': '/bin/bash', 09:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:16 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:16 INFO - 'USER': 'cltbld', 09:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:16 INFO - 'XPC_FLAGS': '0x0', 09:27:16 INFO - 'XPC_SERVICE_NAME': '0', 09:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:27:16 INFO - Cleaning up... 09:27:16 INFO - Return code: 0 09:27:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:27:16 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')]} 09:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:16 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')]} 09:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:16 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 09:27:16 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 09:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:16 INFO - 'HOME': '/Users/cltbld', 09:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:16 INFO - 'LOGNAME': 'cltbld', 09:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:16 INFO - 'MOZ_NO_REMOTE': '1', 09:27:16 INFO - 'NO_EM_RESTART': '1', 09:27:16 INFO - 'PAGER': '/bin/cat', 09:27:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:16 INFO - 'PWD': '/builds/slave/test', 09:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:16 INFO - 'SHELL': '/bin/bash', 09:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:16 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:16 INFO - 'USER': 'cltbld', 09:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:16 INFO - 'XPC_FLAGS': '0x0', 09:27:16 INFO - 'XPC_SERVICE_NAME': '0', 09:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:27:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:27:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:27:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:27:18 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 09:27:18 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 09:27:18 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 09:27:18 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 09:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:27:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:27:18 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 09:27:18 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 09:27:18 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 09:27:18 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 09:27:18 INFO - Downloading docopt-0.6.1.tar.gz 09:27:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:27:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:27:19 INFO - Running setup.py install for blobuploader 09:27:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:27:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:27:19 INFO - Running setup.py install for requests 09:27:19 INFO - Running setup.py install for docopt 09:27:19 INFO - Successfully installed blobuploader requests docopt 09:27:19 INFO - Cleaning up... 09:27:19 INFO - Return code: 0 09:27:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:19 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')]} 09:27:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:19 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')]} 09:27:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:19 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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:19 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 09:27:19 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 09:27:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:19 INFO - 'HOME': '/Users/cltbld', 09:27:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:19 INFO - 'LOGNAME': 'cltbld', 09:27:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:19 INFO - 'MOZ_NO_REMOTE': '1', 09:27:19 INFO - 'NO_EM_RESTART': '1', 09:27:19 INFO - 'PAGER': '/bin/cat', 09:27:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:19 INFO - 'PWD': '/builds/slave/test', 09:27:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:19 INFO - 'SHELL': '/bin/bash', 09:27:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:19 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:19 INFO - 'USER': 'cltbld', 09:27:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:19 INFO - 'XPC_FLAGS': '0x0', 09:27:19 INFO - 'XPC_SERVICE_NAME': '0', 09:27:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7v9Z4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-32ziPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ApBgMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-UI3nGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-dwj21Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-dtZvdi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-xNUL51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-uhGdL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:20 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-pwAX1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Xc2z1X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Tex5D8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-e6zTtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-TBkOBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-6DzDeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-W9MbCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-vTVv2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:21 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-xn3NWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:27:21 INFO - Running setup.py install for manifestparser 09:27:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:27:22 INFO - Running setup.py install for mozcrash 09:27:22 INFO - Running setup.py install for mozdebug 09:27:22 INFO - Running setup.py install for mozdevice 09:27:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:27:22 INFO - Running setup.py install for mozfile 09:27:22 INFO - Running setup.py install for mozhttpd 09:27:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:27:22 INFO - Running setup.py install for mozinfo 09:27:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:27:22 INFO - Running setup.py install for mozInstall 09:27:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Running setup.py install for mozleak 09:27:23 INFO - Running setup.py install for mozlog 09:27:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Running setup.py install for moznetwork 09:27:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Running setup.py install for mozprocess 09:27:23 INFO - Running setup.py install for mozprofile 09:27:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:27:23 INFO - Running setup.py install for mozrunner 09:27:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Running setup.py install for mozscreenshot 09:27:24 INFO - Running setup.py install for moztest 09:27:24 INFO - Running setup.py install for mozversion 09:27:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:27:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:27:24 INFO - Cleaning up... 09:27:24 INFO - Return code: 0 09:27:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:24 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')]} 09:27:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:24 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')]} 09:27:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:24 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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:24 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 09:27:24 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 09:27:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:24 INFO - 'HOME': '/Users/cltbld', 09:27:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:24 INFO - 'LOGNAME': 'cltbld', 09:27:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:24 INFO - 'MOZ_NO_REMOTE': '1', 09:27:24 INFO - 'NO_EM_RESTART': '1', 09:27:24 INFO - 'PAGER': '/bin/cat', 09:27:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:24 INFO - 'PWD': '/builds/slave/test', 09:27:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:24 INFO - 'SHELL': '/bin/bash', 09:27:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:24 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:24 INFO - 'USER': 'cltbld', 09:27:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:24 INFO - 'XPC_FLAGS': '0x0', 09:27:24 INFO - 'XPC_SERVICE_NAME': '0', 09:27:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:27:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-btP0zX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:27:24 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)) 09:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:27:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-K4OGD6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:27:24 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)) 09:27:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:27:24 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-dKMr4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7kSTbu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-9yBeyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-hvsM2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-QFHzhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ZJvYwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-girGTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-tb5vbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Q1GP3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:27:25 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-D_Z6eu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:27:25 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)) 09:27:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:27:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-s6OlPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:27:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-ZpcPsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-dtfoMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:27:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-G2skSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:27:26 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)) 09:27:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:27:26 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-bhqhSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:27:26 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)) 09:27:26 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)) 09:27:26 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)) 09:27:26 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)) 09:27:26 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)) 09:27:26 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)) 09:27:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:27: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 09:27: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 09:27: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 09:27: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 09:27:27 INFO - Downloading blessings-1.6.tar.gz 09:27:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:27:28 INFO - Installing collected packages: blessings 09:27:28 INFO - Running setup.py install for blessings 09:27:28 INFO - Successfully installed blessings 09:27:28 INFO - Cleaning up... 09:27:28 INFO - Return code: 0 09:27:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:27:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:28 INFO - Reading from file tmpfile_stdout 09:27:28 INFO - Current package versions: 09:27:28 INFO - blessings == 1.6 09:27:28 INFO - blobuploader == 1.2.4 09:27:28 INFO - docopt == 0.6.1 09:27:28 INFO - functools32 == 3.2.3-2 09:27:28 INFO - jsonschema == 2.5.1 09:27:28 INFO - manifestparser == 1.1 09:27:28 INFO - mozInstall == 1.12 09:27:28 INFO - mozcrash == 1.0 09:27:28 INFO - mozdebug == 0.1 09:27:28 INFO - mozdevice == 0.50 09:27:28 INFO - mozfile == 1.2 09:27:28 INFO - mozhttpd == 0.7 09:27:28 INFO - mozinfo == 0.9 09:27:28 INFO - mozleak == 0.1 09:27:28 INFO - mozlog == 3.4 09:27:28 INFO - moznetwork == 0.27 09:27:28 INFO - mozprocess == 0.25 09:27:28 INFO - mozprofile == 0.28 09:27:28 INFO - mozrunner == 6.13 09:27:28 INFO - mozscreenshot == 0.1 09:27:28 INFO - mozsystemmonitor == 0.3 09:27:28 INFO - moztest == 0.8 09:27:28 INFO - mozversion == 1.4 09:27:28 INFO - psutil == 3.1.1 09:27:28 INFO - requests == 1.2.3 09:27:28 INFO - wsgiref == 0.1.2 09:27:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:27:28 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')]} 09:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:28 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')]} 09:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:28 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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:28 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 09:27:28 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 09:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:28 INFO - 'HOME': '/Users/cltbld', 09:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:28 INFO - 'LOGNAME': 'cltbld', 09:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:28 INFO - 'MOZ_NO_REMOTE': '1', 09:27:28 INFO - 'NO_EM_RESTART': '1', 09:27:28 INFO - 'PAGER': '/bin/cat', 09:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:28 INFO - 'PWD': '/builds/slave/test', 09:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:28 INFO - 'SHELL': '/bin/bash', 09:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:28 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:28 INFO - 'USER': 'cltbld', 09:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:28 INFO - 'XPC_FLAGS': '0x0', 09:27:28 INFO - 'XPC_SERVICE_NAME': '0', 09:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 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)) 09:27:28 INFO - Cleaning up... 09:27:28 INFO - Return code: 0 09:27:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:27:28 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')]} 09:27:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:27:28 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')]} 09:27:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:27:28 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 0x1039b7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039bb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81b04d7e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, '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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 09:27:28 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 09:27:28 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 09:27:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:28 INFO - 'HOME': '/Users/cltbld', 09:27:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:28 INFO - 'LOGNAME': 'cltbld', 09:27:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:28 INFO - 'MOZ_NO_REMOTE': '1', 09:27:28 INFO - 'NO_EM_RESTART': '1', 09:27:28 INFO - 'PAGER': '/bin/cat', 09:27:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:28 INFO - 'PWD': '/builds/slave/test', 09:27:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:28 INFO - 'SHELL': '/bin/bash', 09:27:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:28 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:28 INFO - 'USER': 'cltbld', 09:27:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:28 INFO - 'XPC_FLAGS': '0x0', 09:27:28 INFO - 'XPC_SERVICE_NAME': '0', 09:27:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:27:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:27:29 INFO - Cleaning up... 09:27:29 INFO - Return code: 0 09:27:29 INFO - Running post-action listener: _resource_record_post_action 09:27:29 INFO - Running post-action listener: _start_resource_monitoring 09:27:29 INFO - Starting resource monitoring. 09:27:29 INFO - [mozharness: 2017-05-17 16:27:29.181649Z] Finished create-virtualenv step (success) 09:27:29 INFO - [mozharness: 2017-05-17 16:27:29.182588Z] Running install step. 09:27:29 INFO - Running pre-action listener: _resource_record_pre_action 09:27:29 INFO - Running main action method: install 09:27:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:27:29 INFO - Reading from file tmpfile_stdout 09:27:29 INFO - Detecting whether we're running mozinstall >=1.0... 09:27:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:27:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:27:29 INFO - Reading from file tmpfile_stdout 09:27:29 INFO - Output received: 09:27:29 INFO - Usage: mozinstall [options] installer 09:27:29 INFO - Options: 09:27:29 INFO - -h, --help show this help message and exit 09:27:29 INFO - -d DEST, --destination=DEST 09:27:29 INFO - Directory to install application into. [default: 09:27:29 INFO - "/builds/slave/test"] 09:27:29 INFO - --app=APP Application being installed. [default: firefox] 09:27:29 INFO - mkdir: /builds/slave/test/build/application 09:27:29 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'] 09:27:29 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 09:27:50 INFO - Reading from file tmpfile_stdout 09:27:50 INFO - Output received: 09:27:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:27:50 INFO - Running post-action listener: _resource_record_post_action 09:27:50 INFO - [mozharness: 2017-05-17 16:27:50.020768Z] Finished install step (success) 09:27:50 INFO - [mozharness: 2017-05-17 16:27:50.020933Z] Running run-tests step. 09:27:50 INFO - Running pre-action listener: _resource_record_pre_action 09:27:50 INFO - Running main action method: run_tests 09:27:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:50 INFO - 2017-05-17 09:27:50.035 screenresolution[1627:7505] starting screenresolution argv=screenresolution get 09:27:50 INFO - 2017-05-17 09:27:50.038 screenresolution[1627:7505] Display 0: 1600x1200x32@60 09:27:50 INFO - 2017-05-17 09:27:50.043 screenresolution[1628:7508] starting screenresolution argv=screenresolution list 09:27:50 INFO - Available Modes on Display 0 09:27:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:50 INFO - Intel Iris: 09:27:50 INFO - Chipset Model: Intel Iris 09:27:50 INFO - Type: GPU 09:27:50 INFO - Bus: Built-In 09:27:50 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:50 INFO - Vendor: Intel (0x8086) 09:27:50 INFO - Device ID: 0x0a2e 09:27:50 INFO - Revision ID: 0x0009 09:27:50 INFO - Displays: 09:27:50 INFO - Display: 09:27:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:50 INFO - Display Serial Number: 1600x1200 60 09:27:50 INFO - Main Display: Yes 09:27:50 INFO - Mirror: Off 09:27:50 INFO - Online: Yes 09:27:50 INFO - Rotation: Supported 09:27:50 INFO - Return code: 0 09:27:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:27:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:27:50 INFO - Python 2.7.3 09:27:50 INFO - Return code: 0 09:27:50 INFO - grabbing minidump binary from tooltool 09:27:50 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')]} 09:27:50 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 0x1036c7d30>, '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 0x10367bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036bcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:27:50 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 09:27:50 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 09:27:50 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 09:27:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:27:50 INFO - Return code: 0 09:27:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:27:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:27:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:27:50 INFO - ENV: RUST_BACKTRACE is now 1 09:27:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:27:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:27:50 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/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 09:27:50 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/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0082 --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 09:27:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AdL1X0V34Y/Render', 09:27:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:27:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:27:50 INFO - 'HOME': '/Users/cltbld', 09:27:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:27:50 INFO - 'LOGNAME': 'cltbld', 09:27:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:27:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:27:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:27:50 INFO - 'MOZ_NO_REMOTE': '1', 09:27:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:27:50 INFO - 'NO_EM_RESTART': '1', 09:27:50 INFO - 'PAGER': '/bin/cat', 09:27:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:27:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:27:50 INFO - 'PWD': '/builds/slave/test', 09:27:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:27:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:27:50 INFO - 'RUST_BACKTRACE': '1', 09:27:50 INFO - 'SHELL': '/bin/bash', 09:27:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gxVzMc3bdI/Listeners', 09:27:50 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 09:27:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:27:50 INFO - 'USER': 'cltbld', 09:27:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:27:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:27:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:27:50 INFO - 'XPC_FLAGS': '0x0', 09:27:50 INFO - 'XPC_SERVICE_NAME': '0', 09:27:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:27:50 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/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 09:27:50 INFO - mozversion application_buildid: 20170517081417 09:27:50 INFO - mozversion application_changeset: 85e5d15c31691c89b82d6068c26260416493071f 09:27:50 INFO - mozversion application_display_name: Nightly 09:27:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:27:50 INFO - mozversion application_name: Firefox 09:27:50 INFO - mozversion application_remotingname: firefox 09:27:50 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:27:50 INFO - mozversion application_vendor: Mozilla 09:27:50 INFO - mozversion application_version: 55.0a1 09:27:50 INFO - mozversion platform_buildid: 20170517081417 09:27:50 INFO - mozversion platform_changeset: 85e5d15c31691c89b82d6068c26260416493071f 09:27:50 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:27:50 INFO - mozversion platform_version: 55.0a1 09:27:50 INFO - using testdate: 1495038470 09:27:50 INFO - actual date: 1495038470 09:27:50 INFO - starting webserver on 'localhost:49225' 09:27:50 INFO - SUITE-START | Running 2 tests 09:27:50 INFO - TEST-START | dromaeo_css 09:27:50 INFO - operating with platform_type : mac_ 09:27:50 INFO - Initialising browser for dromaeo_css test... 09:27:50 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpUAddKP/profile http://localhost:49225/getInfo.html) 09:27:51 INFO - PID 1638 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 09:27:53 INFO - PID 1638 | 2017-05-17 09:27:53.151 plugin-container[1640:7690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c37, name = 'com.apple.tsm.portname' 09:27:53 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:53 INFO - PID 1638 | 2017-05-17 09:27:53.154 plugin-container[1640:7690] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e07, name = 'com.apple.CFPasteboardClient' 09:27:53 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:53 INFO - PID 1638 | 2017-05-17 09:27:53.154 plugin-container[1640:7690] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:53 INFO - PID 1638 | __metrics Screen width/height:1600/1200 09:27:53 INFO - PID 1638 | colorDepth:24 09:27:53 INFO - PID 1638 | Browser inner width/height: 1024/691 09:27:53 INFO - PID 1638 | __metrics 09:27:54 INFO - TEST-INFO | 1638: exit 0 09:27:54 INFO - Browser initialized. 09:27:54 INFO - Running cycle 1/1 for dromaeo_css test... 09:27:54 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpUAddKP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:56 INFO - PID 1642 | 2017-05-17 09:27:56.458 plugin-container[1643:7849] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 09:27:56 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:56 INFO - PID 1642 | 2017-05-17 09:27:56.458 plugin-container[1643:7849] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 09:27:56 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:56 INFO - PID 1642 | 2017-05-17 09:27:56.458 plugin-container[1643:7849] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:57 INFO - PID 1642 | 2017-05-17 09:27:57.052 plugin-container[1644:7895] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 09:27:57 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:57 INFO - PID 1642 | 2017-05-17 09:27:57.053 plugin-container[1644:7895] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 09:27:57 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:57 INFO - PID 1642 | 2017-05-17 09:27:57.053 plugin-container[1644:7895] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:28:06 INFO - PID 1642 | RSS: Main: 234590208 09:28:06 INFO - PID 1642 | 09:31:10 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 09:31:11 INFO - PID 1642 | RSS: Main: 256344064 09:31:11 INFO - PID 1642 | 09:34:13 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 09:34:14 INFO - PID 1642 | RSS: Main: 265490432 09:34:14 INFO - PID 1642 | 09:37:15 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 09:37:16 INFO - PID 1642 | RSS: Main: 273272832 09:37:16 INFO - PID 1642 | 09:40:18 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 09:40:18 INFO - PID 1642 | RSS: Main: 271179776 09:40:18 INFO - PID 1642 | 09:43:21 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 09:43:22 INFO - PID 1642 | RSS: Main: 272596992 09:43:22 INFO - PID 1642 | 09:46:23 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 09:46:24 INFO - PID 1642 | RSS: Main: 273870848 09:46:24 INFO - PID 1642 | 09:46:24 INFO - PID 1642 | __start_tp_report 09:46:24 INFO - PID 1642 | _x_x_mozilla_page_load 09:46:24 INFO - PID 1642 | _x_x_mozilla_page_load_details 09:46:24 INFO - PID 1642 | |i|pagename|runs| 09:46:24 INFO - PID 1642 | |0;dojo.html;3575.320689069077;3597.6941959933406;3616.8734094306706;3629.0745859805747;3643.1984963308064;6135.416406698443;6137.938620613792;6198.44214020738;6215;6236.251649802024;6211.596246243991;6224.626522408658;6228.065790131483;6263.279722831864;6338.30278669346;5388.515033646971;5460.6177567570285;5489.313835770508;5504.2018907258425;5536.944630553683;5525.336959564858;5637.774489020455;5664.178694089354;5693.202951586761;5735.598508104435;4409.669274804402;5531.3915469298345;5584.776608935637;5625.465580769841;5661.8584535386535;5442.782288708487;5518.91721624176;5570.41510641385;5572.554195664377;5577.442255774431;4904.754762261909;5476.698781567012;5554.833354999357;5561.165825126263;5561.5272701820395;6020;6301.4328710416285;6316.589421687576;6334.113224148577;6342.921703310449;6472.5145614078665;6548.67256637171;6629.171353580802;6736.393724564764;6783.525153239275;6839.452843772487;6861.519693621449;6868.793936181923;6870.931290687079;6888.655567221669;6341.3341599132345;6350.047492876106;6350.396712312323;6370.33111523288;6374.713137908805;6721.899171512431;6814.352636499525;6838.7264509420065;7000.369966703021;7001.29987001306;6532.379423954717;6574.671266436659;6582.605043697394;6613.4709223262025;6618.7352505899225;6773.424258937947;6774.3225677431865;6788.898166527506;6798.52410331272;6835.658217089126;5048.545630893237;5588.329400471888;6087.208662873798;6469.838254043687;6540.117084193668;5410.91883621746;6703.798886033427;6715.160604924385;6749.932500675029;6773.559718618274;5372.167314066326;5383.111786555253;5384.542313903362;5407.729613519387;5426.050441172779;4628.907421851544;4641.582257596832;4756.072565849626;4777;4811.422629284508;6270.153529273581;6324.873502529924;6330.240371155399;6354.046892966092;6363.204599425072;6862.4853136015;6887.552309099892;6923.653817309114;6934.826629334206;6941.444684425234;6208.906866397007;6369.585976911486;6370.012648039561;6381.617102973836;6385.233771947396;5905.379935106838;5920.733566989496;5956.493698035629;5969.134475501028;5976.671283079386;7011.614361210182;7032.824179395556;7038.929610703827;7049.823754406181;7068.54054486467;6762.289959554373;6878.105846239957;6909.378155965987;6911;6938.306169383021;6788.185417749902;6855.108835851307;6863.176418829573;7036.542624729581;7040.415906421014;6706.228783689743;6742.393184613408;6770.492213084137;6776.627285499148;6789.321067893171;6829.453643708591;6846.796434709557;6848.520603557703;6925.203601585882;6928.064711264116;6424.775132870327;6671.733130674718;6672.4328432081875;6677.03183038447;6679.933200668125;6465.547411681137;6530.8367290818105;6608.173978252718;6620.139381880324;6663.733450661919;6443.420092191725;6459.967700161657;6505.544611877123;6520.673966301761;6536.477081833537;6436.774712885027;6440.194975628046;6571.57284776488;6659.600423974577;6694.497912656473;6286.339934307015;6512.511561632802;6542.34576542342;6547.214334279917;6552.08270842092 09:46:24 INFO - PID 1642 | |1;ext.html;715.6350261366703;797.429837666069;836.6193382011184;1224.2165014390787;1261.5836773864626;38799;39074.8046259769;39272;39349.409758853566;39565.61520346788;39844.60155398445;39937;40273.597264027354;40376.788696339085;40394;7892.921070789291;8013.358931285512;8282.213189746995;8522.616482258314;8534.57327133644;8550.914490855104;8603.182697643713;8625.913740862605;8648.437851539662;8650.43772154808;7250.891236631454;7262.382697470722;8593.355498337618;8595.871061934076;8637.740867773977;7277.7991631380755;8580.828383432328;8581.95709021451;8619.784505387353;8642.22220000203;7100.28997100291;8379.49723016621;8433.915660843375;8444.831103377897;8473.703420380258;31470.527942080887;36954.63045369539;37391.81304093462;37464.625353746385;37495.62504374949;3425.6231814500384;3480.7817263957663;3493.4759786032046;3573.5175751273505;3580.5882323532865;25815.35461613456;27005.919763209447;30372.392552148835;30791.538126928113;30856.53715194274;2589.4044369794865;3091.2426455518544;3104.425681248976;3135.3885992231526;3135.404273188087;261765;262052;284742.9999999979;307288.4635576808;307691;47982.520174797806;48048.51951480441;48220.758896205996;48361.03277934422;48457.515424845304;50260;50364.99270014579;50443;50463.74768126136;50501.74749126231;14076.014678972579;14137.575872723835;14226.28868556568;14372.712545749235;14556.272186390637;11535.365554894393;11612.12909031826;11646.068314534818;11661.53353865836;11662.416879156008;10804.621838235624;12135.453904574315;12405;13512.93243533776;13727.794083088762;5972.641641501524;5989.970050149721;6050.9394906050375;6058.879107365152;6092.238470191226;1004.7789486313;1012.1447376966598;1018.2617602238317;1050.99474502627;1052.921030922684;23249.418764530685;24205.626995255916;24561.89471473788;24585.13952011671;24607;1109.312226419625;1115.7991561519004;1126.6507382711386;1140.4810811080938;1155.0817100405197;10529.683789526309;11618.477168527437;12291.631251062483;12378.938105309417;12417.689557760948;317120;318761.4061929722;319059;320050;323860.380698095;52956.47043529592;53668.73165634147;53760.19359709608;53863.730681346344;53998;1490.1580606957034;1522.9543113706827;1560.1185330288351;1560.4772401245482;1565.272148450953;17007.404740834012;20638.07128679183;21872;22591.887040564692;23887.76112238925;37186.442203367515;37268.813655931546;37422.625773741915;37437;37455.06362340963;266847;269902;270784;270873;270875;23342.416439589146;28476;32991.83504082464;33688.663113369534;33825;33056;41636.96264041757;42085.36871946862;42937.926551836455;43159.568404315556;30525.710571706768;30874.228144296572;30886.53670194994;31281.530777037908;31458.370832583678;30998.380032399677;31017.534736978512;31057.825952184034;31119.910803121784;31480.842595786875;37197.07007324838;37546;37609.4358584616;37624.43563346497;37630.62369376381;23740.52518949646;29477.410451791275;31251.84374078115;31778.841105794323;31806.522902156023;22238.110475580797;23638.409039773454;24627;24653;24819 09:46:24 INFO - PID 1642 | |2;jquery.html;366.4265424610485;433.6855779559817;436.0602900748887;447.6396500816843;463.85388602590183;19726.506837329056;21288.680669789945;21895.233666821663;22153.556928861413;22259.66610500842;22023.889880550574;22633.660495092547;23072.30783076506;23793.524129517442;24040.398990025256;5943.583949123574;6156.6613836238985;6162.969185154068;6164.075388691687;6180.660063696495;5882.264716910387;6125.754969801203;6160.322364539919;6172.166757487749;6196.318404975472;5964.433378829004;6041.486473649745;6054.485368743662;6056.273247210319;6144.047672610753;6098.512119030489;6106.389361063881;6120.724567394478;6128.846778830521;6138.693065346737;5374.435684253175;5385.3268341457315;5412.350517937873;5421.756020979065;5480.123180291134;27479.587806182924;27574.172774816787;28004.879804807784;28064.719352806416;28461.430771384457;2163.816075633573;2239.29462219402;2241.1371621925514;2242.6411774116227;2244.394013616331;17773.46679599614;17784.91107544454;17987.820121798744;18147.364842230458;18419.539511512186;2144.8391370647264;2147.527543940331;2185.4536365908525;2189.157174487834;2191.1016483241956;158688;160960;162634;163341.18329408276;165055;10376.948115259376;12470.127091103535;12565.371731413576;12568.560100396442;12601.621951341474;13486.93256533711;16136.919315403582;16341.346346146021;16385.672286554203;16459.177041147894;7771.766846994599;8718.607662655195;8741.344399170088;9033.954830225806;9190.816183676288;6608.306127856602;7321.853562928818;8521.531315777567;8558;8665.43674661145;7900.972873526404;7902.130765615778;8011.83976320482;8031.196880312039;8046.476978996347;1864.4313484387121;1931.5750534882304;2226.1651880544796;2248.8313376496503;2399.064364897691;818.0674031603976;884.8938127424622;923.330585325647;926.3284119013662;976.6386437018349;20741.27405540799;21765.45586360322;21795.23716669909;21825.345239642837;21947.231846885283;962.9422234665942;1019.938803671782;1023.8686251691922;1039.4958445154093;1041.958321667125;10400.947995260127;11013.394263315595;11071.5571377144;11114.542140231655;11167.162462815322;528811;531281;534687;539844;543650;113184.43407782908;122264;128310;131139.34430327787;133425;1205.8372119763894;1230.059004861282;1263.387257180267;1265.8227848101274;1267.8415198100238;18814.52963675919;19452.805471945667;20064.996750162725;21008.369748907557;22375.9930803111;53943;53951.460485394644;54363.14038190492;54594;54611;74094.25905740874;74107.62946185234;74614;74793.25206747864;74800;7512.8873066905135;8578.4424012439;8654.653813847628;8890.799312247456;8982.236509896731;11521.308721476738;13838.23889686077;14006.84845909703;14042.78935815943;14079;11149.10807135411;11622.709432263922;11799.76400472003;11860.703482412664;11952.46213920393;9327.580258888502;10694.46796640723;10964.616238431616;11079.667609971713;11110.777784444428;11897.881021190024;11898.405079746151;11982.10134239953;12145.210561313486;12157.392130393268;8613.87079193825;8766.693165739169;8945.418547794372;8952.776180595276;9058.864117038382;11618.244814087057;11640.185187036826;11758.059355251731;11793.351365674971;12004.459799308877 09:46:24 INFO - PID 1642 | |3;mootools.html;1370.3148425787106;1436.8634979676933;1447.8696917277443;1448.6812901161743;1458.5915943535813;11599.30404175749;12050.939745301283;12137.332446715427;12146.271223726582;12146.39268036598;11911;11941.820872686916;12131.696707582314;12133.878661213384;12147;2592.2871210417097;2629.0929629277966;2630.4607555451184;2632.644592979952;2636.578147496401;2526.2295000024983;2567.4608332250154;2571.5114128315654;2597.2467984284535;2598.389378496052;2544.211294498702;2568.935776605581;2600.9739902600923;2602.024240909659;2603.4793041391704;2542.5677634802128;2565.3330134165158;2592.675915510561;2595.4419799743146;2609.0346571768378;2407.8675672837985;2556.194798638423;2590.8315959462575;2591.6501272328182;2593.8054645901634;11643.417829108595;11720.355380454072;11765.176437649368;11803.763924721457;11821.645350639494;6588.736450541973;6779.62712050837;6833.214180369222;6866.759663411806;6866.793996180123;3919.5492518360184;4263.637590804786;4355.716878402894;4414.536473670282;4428.71213371133;1044.6918159143072;1220.4934951994912;1232.963011109668;1287.9806802897965;1288.8453385593696;16370.672586548322;16582.08798516081;16593.751093733605;16651.917625354436;16723.749143762852;5517.613767036269;5536.778528858881;5706.258186435737;5777.855553611109;5793.536517078624;11851.703707407383;12008.879911200776;12029.338386388832;12052.337121458404;12058.758824823455;6579.638119903356;6607.735690572324;6622.801315960529;6630.104935833697;6633.701483433257;6022.2171117754415;6140.785072522441;6254.6872656366995;6302.464290535356;6313.652749098752;4342.956570434319;4408.206522825858;4433.645308375322;4440.3561483585345;4451.931536431298;1271.1292764456377;1278.9104762666525;1285.8714128587067;1297.280009594684;1301.4208677138583;670.5272782688193;797.5653268968426;826.9751907442786;833.3666413525643;841.3731769831393;6781.152355955505;6981.581105133607;7107.431405487549;7142.178649455275;7202.639868006579;793.3613441179906;952.9666461673946;968.2883080935507;969.3311614985637;982.3025651787168;4212.304969679968;4502.257127573979;4546.1589605922345;4651.697639653412;4758.929240920766;12194.81707774384;15023.699526009637;15047.849521504644;15357.846421535642;15414.537563873102;15273.312700928485;15294.235288235544;15312.310946007234;15313.923430382776;15330.080195188324;6409.871802563923;8295.543745094077;8409.873851892227;8627.6980305689;8689.21797038257;6114.327423983269;6973.965130174317;7204.387627051759;7229.49393542447;7256.092988376453;12317.384130793604;13532.661683457993;13533.526326578523;13792.448302067805;13853.16880987144;13659.726805463637;13843.269591301087;13867.930660347038;13885.305734713425;13948.093373930662;7680.084745762828;9211.631534738535;9304.674336398193;9530.666426675032;9555.23558115363;10612.681619551426;10754.13966882632;10850.186236032487;10930.836037459603;10948.781024379627;8531.573421329032;8597.398182127441;8816.250618697482;8934.642614295355;8952.23905967974;8653.61058752345;8731.301495880187;8842;8881.555922203994;8885.777855553404;8242.917570824215;8379.581020948806;8404.285635721033;8434.367422443218;8440.409171357946;6027.909581356371;6105.938940610537;6172.22847144107;6245.937540624535;6274.654893980693;6136.20229370179;6196.47329976957;6244.812655620339;6291.653959032297;6324.557280990287 09:46:24 INFO - PID 1642 | |4;prototype.html;396.0475057486744;398.8723608445297;401.9468991242944;405.8291828076004;411.8764370688793;6449.419552240297;6480.708368123434;6489.253735820382;6508.609483430998;6524.70638821253;5341.893162136755;6106.17566628505;6268.561200715952;6300.5589608727405;6392.7442902283965;4777.140114779347;4812.350332705092;4812.893034602043;4814.205656066745;4814.339053026718;3874.728768986172;4093.9590604094024;4137.66898648109;4842.12841688496;4854.344663470421;4326.437563116806;4366.67249956253;4640.837570685027;4751.714897106184;4788.569028787409;4762.595179409737;4763.19025765619;4787.832425865078;4789.904631540053;4798.376211092535;4118.567550407194;4226.922134855623;4232.132412855357;4243.8090285937205;4264;4828.830990915301;5089.821856235051;6196.535259855484;6220.875582488325;6318.115463835067;5262.026525092831;5351.143816989302;5637.238972738669;5661.631993920382;5686.800961966353;6363.522735794834;6630.900536491957;6746.595204287759;6778.932210677879;6798.796036118924;4413.360062790909;4416.84541041062;4423.646108311327;4438.733675979419;4438.757147998565;5522.790883646573;6210.937890621081;6670.666466676646;6779.796606101825;7046.189688185822;3729.2541491701227;4457.398251236106;4480.395146655225;4489.932651010237;4493.236149854532;3832;4546.340780586863;4546.5453454654935;4551.977240113778;4558.886027849264;5316.202569614588;5381.408045115034;5402.972985135049;5437.401885792559;5466.753996070186;5845.707714614252;6639.734410623618;6749.122614060141;6786.219584747817;6806.727730890808;1357.911735737174;1507.6833864888497;1539.3605809812514;1550.4263422533509;1559.5243450747405;843.7384410832661;983.8081574093127;1003.7390278527636;1008.9607704064825;1020.9280255731452;6273.027680709497;6296.338884417162;6306.873862522724;6331.461825744771;6353.555251132377;5832.766689332379;6799.4900382471515;6950.443964482829;6982.650867456607;7050.858982820315;6004.369541198199;6090.725917333731;6416.326285740023;6428.389303016206;6484.610923344614;3463.1861512544533;3521.9119522012156;3690.6195503899835;3697.5193224880595;3795.981020094882;5958.702064896738;6150.907736383958;7263.256818363555;7275.381592564586;7305.963470182615;6513.54405191632;6757.459403247728;6925.965370173218;7327.706891724378;7334.523255988343;6904.723811047502;7232.783016509513;7272.381847543018;7299.41604671625;7363.190049094595;6281.937180628318;6852.965735171292;7063.964680176772;7078.433725302067;7082.441862790384;5483.75323110469;5752.367239603724;6772.762953296611;6974.755883544051;7026.859462810613;5766.682832444256;5830.766769329349;6500.349965003462;6630.7679231226675;6651.368120028589;4284.892877678083;5088.898222035465;5118.206677964921;5131.87170320745;5132.615053871049;6829.52193346461;7034.929650703428;7115.719170549351;7126.180489243803;7153.928460715326;6741.764038258638;6791.320867913169;6804.489663275175;6804.795856124324;6863.416609588241;6257.8422991746675;6303.842403939938;6812.693428795617;6835.350641689031;6874.6562671867205;6181.3200547938795;6661.100751398693;6673.299303573249;6687.866242675022;6688.197416310074;6522.739090436519;6726.159230096238;6802.387785099365;6816.454683625398;6887.552309099892;5564.888702226014;6675.531383095711;6691.063251144747;6695.732170713117;6706.195256569243 09:46:24 INFO - PID 1642 | |5;yui.html;777.4907435629661;799.7800604833666;845.1970627903494;854.9099897630521;872.1496540872648;14481.710365792678;15182.84817151828;15533.766993495092;15726.921365393186;19469.026548672544;14648.707025859476;14775.335109920079;18370.081495925217;18596.628067438643;18774.249030038816;965.3194497878997;983.8475036369374;990.6631745206628;994.3636072913341;996.1383403356117;914.1498406481969;1006.079437314856;1009.5154325923525;1012.71643939697;1013.8225810483173;959.5825815770128;1018.2007124407342;1050.1808589300358;1053.157474020784;1054.836500342448;1046.1578429364358;1048.3447845096814;1048.7745134796055;1050.0916707048386;1057.4765491081926;773.5088218980932;773.6750564762757;777.1451403456153;778.8909552662575;780.8906753054576;14363.56909292723;16963.3214671413;17445.47663570095;17691.557711057198;17772.822271777248;14498.855011449856;17076.65846683072;17487.737683934625;17530.737038944426;17559.385421510186;14492.20292883891;14530.273486325746;14636.780448293284;14704.485343012942;14992.025518341383;3550;3562.87529936451;3587.8744243951596;3596.2447885943984;3605.06268370223;101771;112733;113000;131159.5398437147;133803.99999999904;13484.86515134836;13509.729805403837;13529.729405412034;13617.25105119228;13636.795448068287;26037.609435858478;26225.868870655908;26235.344116397242;26399;26583.33541661435;7736.342410895025;7880.408969327324;7935.801604959806;7966.362690984707;7974.202579741979;3865.342891708416;3942.8225729842225;3946.2896678598127;3949.7037722170953;3953.248882712333;4299.247631664446;4343.1747967886;4414.84548040817;4440.800163992628;4440.822367105279;872.9781755456164;900.2258058070055;901.8241442918699;905.2260317428504;907.3195103672246;654.1104098426136;654.9508786841006;661.490652197805;667.3060017581661;694.0248950224942;10749.570017199381;11162.832557511643;11502.827457588142;11910.702232444084;12162.08784341178;607.1348328631682;620.1503939602683;620.9937900620936;623.9438450539474;643.6556442303439;4082.857100001486;5056.84829455117;5340.519353258225;5496.120620700669;5547.306586676666;109581;111238.3314250287;112134;112613;114682.27976580359;83769;84626.57686711528;86162.56918715367;88276.55861720651;88402.11597884068;80333.59833200913;81695.18304816994;82614;83032.16967830365;83806;8942.50816205115;12294.20087694297;12732.771764941284;12832.615021549369;12858.742825143258;31193.908213212428;40170.59829401669;40539.13530376374;41149;41647.750567483024;68425.65787171033;71611.64194179204;73069.6346518264;73358.63320683576;74763.62618186875;12518.499260029861;16591.834081659028;17191;17198.82801171972;17293.481195564153;17705.46883593443;18682.71975920335;18776.15507302202;18889.05554722286;18950.52623684419;13231.3384330785;14343.56969290923;16113.67772644517;16594.917025414794;17303.653926921645;16423;16696.66606667884;16951.915240423717;17317.826821732124;17492.82507174912;16214.51356459308;16457;16795.076270805224;16896.155192240585;16936.57658558546;9793.412395256308;10429.895701042893;11774.3524106175;11990.580329688768;12227.082968777555;12110.757784844078;12850.67873303175;12853.293068881301;13063.934680326538;13071.803922941353 09:46:24 INFO - PID 1642 | __end_tp_report 09:46:24 INFO - PID 1642 | __start_cc_report 09:46:24 INFO - PID 1642 | _x_x_mozilla_cycle_collect,351 09:46:24 INFO - PID 1642 | __end_cc_report 09:46:24 INFO - PID 1642 | __startTimestamp1495039584226__endTimestamp 09:46:24 INFO - PID 1642 | 09:46:24 INFO - PID 1642 | ------- Summary: start ------- 09:46:24 INFO - PID 1642 | Number of tests: 6 09:46:24 INFO - PID 1642 | 09:46:24 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6216.33 Median:6440.19 stddev:752.17 (11.7%) stddev-sans-first:725.38 09:46:24 INFO - PID 1642 | Values: 3575.3 3597.7 3616.9 3629.1 3643.2 6135.4 6137.9 6198.4 6215.0 6236.3 6211.6 6224.6 6228.1 6263.3 6338.3 5388.5 5460.6 5489.3 5504.2 5536.9 5525.3 5637.8 5664.2 5693.2 5735.6 4409.7 5531.4 5584.8 5625.5 5661.9 5442.8 5518.9 5570.4 5572.6 5577.4 4904.8 5476.7 5554.8 5561.2 5561.5 6020.0 6301.4 6316.6 6334.1 6342.9 6472.5 6548.7 6629.2 6736.4 6783.5 6839.5 6861.5 6868.8 6870.9 6888.7 6341.3 6350.0 6350.4 6370.3 6374.7 6721.9 6814.4 6838.7 7000.4 7001.3 6532.4 6574.7 6582.6 6613.5 6618.7 6773.4 6774.3 6788.9 6798.5 6835.7 5048.5 5588.3 6087.2 6469.8 6540.1 5410.9 6703.8 6715.2 6749.9 6773.6 5372.2 5383.1 5384.5 5407.7 5426.1 4628.9 4641.6 4756.1 4777.0 4811.4 6270.2 6324.9 6330.2 6354.0 6363.2 6862.5 6887.6 6923.7 6934.8 6941.4 6208.9 6369.6 6370.0 6381.6 6385.2 5905.4 5920.7 5956.5 5969.1 5976.7 7011.6 7032.8 7038.9 7049.8 7068.5 6762.3 6878.1 6909.4 6911.0 6938.3 6788.2 6855.1 6863.2 7036.5 7040.4 6706.2 6742.4 6770.5 6776.6 6789.3 6829.5 6846.8 6848.5 6925.2 6928.1 6424.8 6671.7 6672.4 6677.0 6679.9 6465.5 6530.8 6608.2 6620.1 6663.7 6443.4 6460.0 6505.5 6520.7 6536.5 6436.8 6440.2 6571.6 6659.6 6694.5 6286.3 6512.5 6542.3 6547.2 6552.1 09:46:24 INFO - PID 1642 | 09:46:24 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:44086.93 Median:175283.96 stddev:76685.45 (43.7%) stddev-sans-first:76831.43 09:46:24 INFO - PID 1642 | Values: 715.6 797.4 836.6 1224.2 1261.6 38799.0 39074.8 39272.0 39349.4 39565.6 39844.6 39937.0 40273.6 40376.8 40394.0 7892.9 8013.4 8282.2 8522.6 8534.6 8550.9 8603.2 8625.9 8648.4 8650.4 7250.9 7262.4 8593.4 8595.9 8637.7 7277.8 8580.8 8582.0 8619.8 8642.2 7100.3 8379.5 8433.9 8444.8 8473.7 31470.5 36954.6 37391.8 37464.6 37495.6 3425.6 3480.8 3493.5 3573.5 3580.6 25815.4 27005.9 30372.4 30791.5 30856.5 2589.4 3091.2 3104.4 3135.4 3135.4 261765.0 262052.0 284743.0 307288.5 307691.0 47982.5 48048.5 48220.8 48361.0 48457.5 50260.0 50365.0 50443.0 50463.7 50501.7 14076.0 14137.6 14226.3 14372.7 14556.3 11535.4 11612.1 11646.1 11661.5 11662.4 10804.6 12135.5 12405.0 13512.9 13727.8 5972.6 5990.0 6050.9 6058.9 6092.2 1004.8 1012.1 1018.3 1051.0 1052.9 23249.4 24205.6 24561.9 24585.1 24607.0 1109.3 1115.8 1126.7 1140.5 1155.1 10529.7 11618.5 12291.6 12378.9 12417.7 317120.0 318761.4 319059.0 320050.0 323860.4 52956.5 53668.7 53760.2 53863.7 53998.0 1490.2 1523.0 1560.1 1560.5 1565.3 17007.4 20638.1 21872.0 22591.9 23887.8 37186.4 37268.8 37422.6 37437.0 37455.1 266847.0 269902.0 270784.0 270873.0 270875.0 23342.4 28476.0 32991.8 33688.7 33825.0 33056.0 41637.0 42085.4 42937.9 43159.6 30525.7 30874.2 30886.5 31281.5 31458.4 30998.4 31017.5 31057.8 31119.9 31480.8 37197.1 37546.0 37609.4 37624.4 37630.6 23740.5 29477.4 31251.8 31778.8 31806.5 22238.1 23638.4 24627.0 24653.0 24819.0 09:46:24 INFO - PID 1642 | 09:46:24 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:35140.77 Median:2243.52 stddev:91220.80 (4066.0%) stddev-sans-first:91439.33 09:46:24 INFO - PID 1642 | Values: 366.4 433.7 436.1 447.6 463.9 19726.5 21288.7 21895.2 22153.6 22259.7 22023.9 22633.7 23072.3 23793.5 24040.4 5943.6 6156.7 6163.0 6164.1 6180.7 5882.3 6125.8 6160.3 6172.2 6196.3 5964.4 6041.5 6054.5 6056.3 6144.0 6098.5 6106.4 6120.7 6128.8 6138.7 5374.4 5385.3 5412.4 5421.8 5480.1 27479.6 27574.2 28004.9 28064.7 28461.4 2163.8 2239.3 2241.1 2242.6 2244.4 17773.5 17784.9 17987.8 18147.4 18419.5 2144.8 2147.5 2185.5 2189.2 2191.1 158688.0 160960.0 162634.0 163341.2 165055.0 10376.9 12470.1 12565.4 12568.6 12601.6 13486.9 16136.9 16341.3 16385.7 16459.2 7771.8 8718.6 8741.3 9034.0 9190.8 6608.3 7321.9 8521.5 8558.0 8665.4 7901.0 7902.1 8011.8 8031.2 8046.5 1864.4 1931.6 2226.2 2248.8 2399.1 818.1 884.9 923.3 926.3 976.6 20741.3 21765.5 21795.2 21825.3 21947.2 962.9 1019.9 1023.9 1039.5 1042.0 10400.9 11013.4 11071.6 11114.5 11167.2 528811.0 531281.0 534687.0 539844.0 543650.0 113184.4 122264.0 128310.0 131139.3 133425.0 1205.8 1230.1 1263.4 1265.8 1267.8 18814.5 19452.8 20065.0 21008.4 22376.0 53943.0 53951.5 54363.1 54594.0 54611.0 74094.3 74107.6 74614.0 74793.3 74800.0 7512.9 8578.4 8654.7 8890.8 8982.2 11521.3 13838.2 14006.8 14042.8 14079.0 11149.1 11622.7 11799.8 11860.7 11952.5 9327.6 10694.5 10964.6 11079.7 11110.8 11897.9 11898.4 11982.1 12145.2 12157.4 8613.9 8766.7 8945.4 8952.8 9058.9 11618.2 11640.2 11758.1 11793.4 12004.5 09:46:24 INFO - PID 1642 | 09:46:24 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7192.96 Median:4065.93 stddev:4513.55 (111.0%) stddev-sans-first:4505.00 09:46:24 INFO - PID 1642 | Values: 1370.3 1436.9 1447.9 1448.7 1458.6 11599.3 12050.9 12137.3 12146.3 12146.4 11911.0 11941.8 12131.7 12133.9 12147.0 2592.3 2629.1 2630.5 2632.6 2636.6 2526.2 2567.5 2571.5 2597.2 2598.4 2544.2 2568.9 2601.0 2602.0 2603.5 2542.6 2565.3 2592.7 2595.4 2609.0 2407.9 2556.2 2590.8 2591.7 2593.8 11643.4 11720.4 11765.2 11803.8 11821.6 6588.7 6779.6 6833.2 6866.8 6866.8 3919.5 4263.6 4355.7 4414.5 4428.7 1044.7 1220.5 1233.0 1288.0 1288.8 16370.7 16582.1 16593.8 16651.9 16723.7 5517.6 5536.8 5706.3 5777.9 5793.5 11851.7 12008.9 12029.3 12052.3 12058.8 6579.6 6607.7 6622.8 6630.1 6633.7 6022.2 6140.8 6254.7 6302.5 6313.7 4343.0 4408.2 4433.6 4440.4 4451.9 1271.1 1278.9 1285.9 1297.3 1301.4 670.5 797.6 827.0 833.4 841.4 6781.2 6981.6 7107.4 7142.2 7202.6 793.4 953.0 968.3 969.3 982.3 4212.3 4502.3 4546.2 4651.7 4758.9 12194.8 15023.7 15047.8 15357.8 15414.5 15273.3 15294.2 15312.3 15313.9 15330.1 6409.9 8295.5 8409.9 8627.7 8689.2 6114.3 6974.0 7204.4 7229.5 7256.1 12317.4 13532.7 13533.5 13792.4 13853.2 13659.7 13843.3 13867.9 13885.3 13948.1 7680.1 9211.6 9304.7 9530.7 9555.2 10612.7 10754.1 10850.2 10930.8 10948.8 8531.6 8597.4 8816.3 8934.6 8952.2 8653.6 8731.3 8842.0 8881.6 8885.8 8242.9 8379.6 8404.3 8434.4 8440.4 6027.9 6105.9 6172.2 6245.9 6274.7 6136.2 6196.5 6244.8 6291.7 6324.6 09:46:24 INFO - PID 1642 | 09:46:24 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5454.97 Median:6203.74 stddev:1709.44 (27.6%) stddev-sans-first:1671.53 09:46:24 INFO - PID 1642 | Values: 396.0 398.9 401.9 405.8 411.9 6449.4 6480.7 6489.3 6508.6 6524.7 5341.9 6106.2 6268.6 6300.6 6392.7 4777.1 4812.4 4812.9 4814.2 4814.3 3874.7 4094.0 4137.7 4842.1 4854.3 4326.4 4366.7 4640.8 4751.7 4788.6 4762.6 4763.2 4787.8 4789.9 4798.4 4118.6 4226.9 4232.1 4243.8 4264.0 4828.8 5089.8 6196.5 6220.9 6318.1 5262.0 5351.1 5637.2 5661.6 5686.8 6363.5 6630.9 6746.6 6778.9 6798.8 4413.4 4416.8 4423.6 4438.7 4438.8 5522.8 6210.9 6670.7 6779.8 7046.2 3729.3 4457.4 4480.4 4489.9 4493.2 3832.0 4546.3 4546.5 4552.0 4558.9 5316.2 5381.4 5403.0 5437.4 5466.8 5845.7 6639.7 6749.1 6786.2 6806.7 1357.9 1507.7 1539.4 1550.4 1559.5 843.7 983.8 1003.7 1009.0 1020.9 6273.0 6296.3 6306.9 6331.5 6353.6 5832.8 6799.5 6950.4 6982.7 7050.9 6004.4 6090.7 6416.3 6428.4 6484.6 3463.2 3521.9 3690.6 3697.5 3796.0 5958.7 6150.9 7263.3 7275.4 7306.0 6513.5 6757.5 6926.0 7327.7 7334.5 6904.7 7232.8 7272.4 7299.4 7363.2 6281.9 6853.0 7064.0 7078.4 7082.4 5483.8 5752.4 6772.8 6974.8 7026.9 5766.7 5830.8 6500.3 6630.8 6651.4 4284.9 5088.9 5118.2 5131.9 5132.6 6829.5 7034.9 7115.7 7126.2 7153.9 6741.8 6791.3 6804.5 6804.8 6863.4 6257.8 6303.8 6812.7 6835.4 6874.7 6181.3 6661.1 6673.3 6687.9 6688.2 6522.7 6726.2 6802.4 6816.5 6887.6 5564.9 6675.5 6691.1 6695.7 6706.2 09:46:24 INFO - PID 1642 | 09:46:24 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:22103.15 Median:18728.48 stddev:31033.20 (165.7%) stddev-sans-first:31078.94 09:46:24 INFO - PID 1642 | Values: 777.5 799.8 845.2 854.9 872.1 14481.7 15182.8 15533.8 15726.9 19469.0 14648.7 14775.3 18370.1 18596.6 18774.2 965.3 983.8 990.7 994.4 996.1 914.1 1006.1 1009.5 1012.7 1013.8 959.6 1018.2 1050.2 1053.2 1054.8 1046.2 1048.3 1048.8 1050.1 1057.5 773.5 773.7 777.1 778.9 780.9 14363.6 16963.3 17445.5 17691.6 17772.8 14498.9 17076.7 17487.7 17530.7 17559.4 14492.2 14530.3 14636.8 14704.5 14992.0 3550.0 3562.9 3587.9 3596.2 3605.1 101771.0 112733.0 113000.0 131159.5 133804.0 13484.9 13509.7 13529.7 13617.3 13636.8 26037.6 26225.9 26235.3 26399.0 26583.3 7736.3 7880.4 7935.8 7966.4 7974.2 3865.3 3942.8 3946.3 3949.7 3953.2 4299.2 4343.2 4414.8 4440.8 4440.8 873.0 900.2 901.8 905.2 907.3 654.1 655.0 661.5 667.3 694.0 10749.6 11162.8 11502.8 11910.7 12162.1 607.1 620.2 621.0 623.9 643.7 4082.9 5056.8 5340.5 5496.1 5547.3 109581.0 111238.3 112134.0 112613.0 114682.3 83769.0 84626.6 86162.6 88276.6 88402.1 80333.6 81695.2 82614.0 83032.2 83806.0 8942.5 12294.2 12732.8 12832.6 12858.7 31193.9 40170.6 40539.1 41149.0 41647.8 68425.7 71611.6 73069.6 73358.6 74763.6 12518.5 16591.8 17191.0 17198.8 17293.5 17705.5 18682.7 18776.2 18889.1 18950.5 13231.3 14343.6 16113.7 16594.9 17303.7 16423.0 16696.7 16951.9 17317.8 17492.8 16214.5 16457.0 16795.1 16896.2 16936.6 9793.4 10429.9 11774.4 11990.6 12227.1 12110.8 12850.7 12853.3 13063.9 13071.8 09:46:24 INFO - PID 1642 | -------- Summary: end -------- 09:46:24 INFO - PID 1642 | 09:46:24 INFO - TEST-INFO | 1642: exit 0 09:46:24 INFO - TEST-OK | dromaeo_css | took 1114269ms 09:46:24 INFO - TEST-START | kraken 09:46:24 INFO - operating with platform_type : mac_ 09:46:24 INFO - Initialising browser for kraken test... 09:46:24 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmppSAbp0/profile http://localhost:49225/getInfo.html) 09:46:25 INFO - PID 1656 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 09:46:27 INFO - PID 1656 | 2017-05-17 09:46:27.286 plugin-container[1657:10205] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973b, name = 'com.apple.tsm.portname' 09:46:27 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:27 INFO - PID 1656 | 2017-05-17 09:46:27.289 plugin-container[1657:10205] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0f, name = 'com.apple.CFPasteboardClient' 09:46:27 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:27 INFO - PID 1656 | 2017-05-17 09:46:27.289 plugin-container[1657:10205] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:27 INFO - PID 1656 | __metrics Screen width/height:1600/1200 09:46:27 INFO - PID 1656 | colorDepth:24 09:46:27 INFO - PID 1656 | Browser inner width/height: 1024/691 09:46:27 INFO - PID 1656 | __metrics 09:46:27 INFO - PID 1656 | 09:46:27 INFO - PID 1656 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 09:46:27 INFO - PID 1656 | 09:46:28 INFO - TEST-INFO | 1656: exit 0 09:46:28 INFO - Browser initialized. 09:46:28 INFO - Running cycle 1/1 for kraken test... 09:46:28 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmppSAbp0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:30 INFO - PID 1659 | 2017-05-17 09:46:30.209 plugin-container[1660:10353] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9737, name = 'com.apple.tsm.portname' 09:46:30 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:30 INFO - PID 1659 | 2017-05-17 09:46:30.209 plugin-container[1660:10353] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973b, name = 'com.apple.CFPasteboardClient' 09:46:30 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:30 INFO - PID 1659 | 2017-05-17 09:46:30.209 plugin-container[1660:10353] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:30 INFO - PID 1659 | 2017-05-17 09:46:30.968 plugin-container[1661:10399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.tsm.portname' 09:46:30 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:30 INFO - PID 1659 | 2017-05-17 09:46:30.968 plugin-container[1661:10399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.CFPasteboardClient' 09:46:30 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:30 INFO - PID 1659 | 2017-05-17 09:46:30.969 plugin-container[1661:10399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:40 INFO - PID 1659 | RSS: Main: 235782144 09:46:40 INFO - PID 1659 | 09:46:42 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 09:46:43 INFO - PID 1659 | RSS: Main: 248008704 09:46:43 INFO - PID 1659 | 09:46:46 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 09:46:46 INFO - PID 1659 | RSS: Main: 256507904 09:46:46 INFO - PID 1659 | 09:46:50 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 09:46:51 INFO - PID 1659 | RSS: Main: 258084864 09:46:51 INFO - PID 1659 | 09:46:52 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 09:46:53 INFO - PID 1659 | RSS: Main: 259223552 09:46:53 INFO - PID 1659 | 09:46:55 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:46:56 INFO - PID 1659 | RSS: Main: 259670016 09:46:56 INFO - PID 1659 | 09:46:59 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 09:46:59 INFO - PID 1659 | RSS: Main: 265244672 09:46:59 INFO - PID 1659 | 09:47:03 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 09:47:03 INFO - PID 1659 | RSS: Main: 266436608 09:47:03 INFO - PID 1659 | 09:47:06 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 09:47:07 INFO - PID 1659 | RSS: Main: 265428992 09:47:07 INFO - PID 1659 | 09:47:08 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:47:09 INFO - PID 1659 | RSS: Main: 265297920 09:47:09 INFO - PID 1659 | 09:47:11 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:47:11 INFO - PID 1659 | RSS: Main: 264941568 09:47:11 INFO - PID 1659 | 09:47:14 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:47:14 INFO - PID 1659 | RSS: Main: 264777728 09:47:14 INFO - PID 1659 | 09:47:17 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:47:18 INFO - PID 1659 | RSS: Main: 264994816 09:47:18 INFO - PID 1659 | 09:47:21 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:47:22 INFO - PID 1659 | RSS: Main: 265142272 09:47:22 INFO - PID 1659 | 09:47:23 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 09:47:24 INFO - PID 1659 | RSS: Main: 267276288 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | __start_tp_report 09:47:24 INFO - PID 1659 | _x_x_mozilla_page_load 09:47:24 INFO - PID 1659 | _x_x_mozilla_page_load_details 09:47:24 INFO - PID 1659 | |i|pagename|runs| 09:47:24 INFO - PID 1659 | |0;ai-astar;100;115;114;112;113;114;113;114;117;118 09:47:24 INFO - PID 1659 | |1;audio-beat-detection;141;112;121;152;108;117;113;110;134;110 09:47:24 INFO - PID 1659 | |2;audio-dft;197;200;201;206;198;203;236;272;206;202 09:47:24 INFO - PID 1659 | |3;audio-fft;81;59;59;88;61;63;60;59;85;79 09:47:24 INFO - PID 1659 | |4;audio-oscillator;82;71;73;99;58;70;96;106;56;120 09:47:24 INFO - PID 1659 | |5;imaging-gaussian-blur;91;92;92;93;92;111;84;123;138;98 09:47:24 INFO - PID 1659 | |6;imaging-darkroom;153;98;102;149;157;99;97;102;98;98 09:47:24 INFO - PID 1659 | |7;imaging-desaturate;91;94;92;91;93;91;90;96;90;90 09:47:24 INFO - PID 1659 | |8;json-parse-financial;46;48;47;49;52;50;52;51;51;49 09:47:24 INFO - PID 1659 | |9;json-stringify-tinderbox;58;65;57;56;66;60;58;57;65;73 09:47:24 INFO - PID 1659 | |10;stanford-crypto-aes;92;80;85;84;82;85;106;108;108;72 09:47:24 INFO - PID 1659 | |11;stanford-crypto-ccm;143;152;140;138;132;173;148;138;219;152 09:47:24 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;190;186;190;191;218;193;185;186;185;188 09:47:24 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;70;70;69;70;83;66;70;70;71;99 09:47:24 INFO - PID 1659 | __end_tp_report 09:47:24 INFO - PID 1659 | __start_cc_report 09:47:24 INFO - PID 1659 | _x_x_mozilla_cycle_collect,949 09:47:24 INFO - PID 1659 | __end_cc_report 09:47:24 INFO - PID 1659 | __startTimestamp1495039644185__endTimestamp 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | ------- Summary: start ------- 09:47:24 INFO - PID 1659 | Number of tests: 14 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:4.92 (4.3%) stddev-sans-first:1.94 09:47:24 INFO - PID 1659 | Values: 100.0 115.0 114.0 112.0 113.0 114.0 113.0 114.0 117.0 118.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:121.80 Median:119.00 stddev:15.26 (12.8%) stddev-sans-first:14.52 09:47:24 INFO - PID 1659 | Values: 141.0 112.0 121.0 152.0 108.0 117.0 113.0 110.0 134.0 110.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:212.10 Median:204.50 stddev:23.84 (11.7%) stddev-sans-first:24.65 09:47:24 INFO - PID 1659 | Values: 197.0 200.0 201.0 206.0 198.0 203.0 236.0 272.0 206.0 202.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:69.40 Median:71.00 stddev:12.20 (17.2%) stddev-sans-first:12.20 09:47:24 INFO - PID 1659 | Values: 81.0 59.0 59.0 88.0 61.0 63.0 60.0 59.0 85.0 79.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:83.10 Median:72.00 stddev:21.32 (29.6%) stddev-sans-first:22.61 09:47:24 INFO - PID 1659 | Values: 82.0 71.0 73.0 99.0 58.0 70.0 96.0 106.0 56.0 120.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:101.40 Median:92.00 stddev:17.18 (18.7%) stddev-sans-first:17.81 09:47:24 INFO - PID 1659 | Values: 91.0 92.0 92.0 93.0 92.0 111.0 84.0 123.0 138.0 98.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:115.30 Median:97.50 stddev:26.14 (26.8%) stddev-sans-first:23.90 09:47:24 INFO - PID 1659 | Values: 153.0 98.0 102.0 149.0 157.0 99.0 97.0 102.0 98.0 98.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:91.50 stddev:1.99 (2.2%) stddev-sans-first:2.09 09:47:24 INFO - PID 1659 | Values: 91.0 94.0 92.0 91.0 93.0 91.0 90.0 96.0 90.0 90.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.50 stddev:2.07 (4.1%) stddev-sans-first:1.76 09:47:24 INFO - PID 1659 | Values: 46.0 48.0 47.0 49.0 52.0 50.0 52.0 51.0 51.0 49.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:61.50 Median:62.50 stddev:5.52 (8.8%) stddev-sans-first:5.71 09:47:24 INFO - PID 1659 | Values: 58.0 65.0 57.0 56.0 66.0 60.0 58.0 57.0 65.0 73.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:83.00 stddev:12.83 (15.5%) stddev-sans-first:13.59 09:47:24 INFO - PID 1659 | Values: 92.0 80.0 85.0 84.0 82.0 85.0 106.0 108.0 108.0 72.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:150.00 stddev:25.69 (17.1%) stddev-sans-first:26.97 09:47:24 INFO - PID 1659 | Values: 143.0 152.0 140.0 138.0 132.0 173.0 148.0 138.0 219.0 152.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.20 Median:190.00 stddev:9.81 (5.2%) stddev-sans-first:10.39 09:47:24 INFO - PID 1659 | Values: 190.0 186.0 190.0 191.0 218.0 193.0 185.0 186.0 185.0 188.0 09:47:24 INFO - PID 1659 | 09:47:24 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:70.00 stddev:9.91 (14.2%) stddev-sans-first:10.41 09:47:24 INFO - PID 1659 | Values: 70.0 70.0 69.0 70.0 83.0 66.0 70.0 70.0 71.0 99.0 09:47:24 INFO - PID 1659 | -------- Summary: end -------- 09:47:24 INFO - PID 1659 | 09:47:24 INFO - TEST-INFO | 1659: exit 0 09:47:25 INFO - TEST-OK | kraken | took 60116ms 09:47:25 INFO - SUITE-END | took 1174s 09:47:25 INFO - Completed test suite (00:19:34) 09:47:25 INFO - javascript benchmark 09:47:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7929.232334776751, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3575.320689069077, 3597.6941959933406, 3616.8734094306706, 3629.0745859805747, 3643.1984963308064, 6135.416406698443, 6137.938620613792, 6198.44214020738, 6215.0, 6236.251649802024, 6211.596246243991, 6224.626522408658, 6228.065790131483, 6263.279722831864, 6338.30278669346, 5388.515033646971, 5460.6177567570285, 5489.313835770508, 5504.2018907258425, 5536.944630553683, 5525.336959564858, 5637.774489020455, 5664.178694089354, 5693.202951586761, 5735.598508104435, 4409.669274804402, 5531.3915469298345, 5584.776608935637, 5625.465580769841, 5661.8584535386535, 5442.782288708487, 5518.91721624176, 5570.41510641385, 5572.554195664377, 5577.442255774431, 4904.754762261909, 5476.698781567012, 5554.833354999357, 5561.165825126263, 5561.5272701820395, 6020.0, 6301.4328710416285, 6316.589421687576, 6334.113224148577, 6342.921703310449, 6472.5145614078665, 6548.67256637171, 6629.171353580802, 6736.393724564764, 6783.525153239275, 6839.452843772487, 6861.519693621449, 6868.793936181923, 6870.931290687079, 6888.655567221669, 6341.3341599132345, 6350.047492876106, 6350.396712312323, 6370.33111523288, 6374.713137908805, 6721.899171512431, 6814.352636499525, 6838.7264509420065, 7000.369966703021, 7001.29987001306, 6532.379423954717, 6574.671266436659, 6582.605043697394, 6613.4709223262025, 6618.7352505899225, 6773.424258937947, 6774.3225677431865, 6788.898166527506, 6798.52410331272, 6835.658217089126, 5048.545630893237, 5588.329400471888, 6087.208662873798, 6469.838254043687, 6540.117084193668, 5410.91883621746, 6703.798886033427, 6715.160604924385, 6749.932500675029, 6773.559718618274, 5372.167314066326, 5383.111786555253, 5384.542313903362, 5407.729613519387, 5426.050441172779, 4628.907421851544, 4641.582257596832, 4756.072565849626, 4777.0, 4811.422629284508, 6270.153529273581, 6324.873502529924, 6330.240371155399, 6354.046892966092, 6363.204599425072, 6862.4853136015, 6887.552309099892, 6923.653817309114, 6934.826629334206, 6941.444684425234, 6208.906866397007, 6369.585976911486, 6370.012648039561, 6381.617102973836, 6385.233771947396, 5905.379935106838, 5920.733566989496, 5956.493698035629, 5969.134475501028, 5976.671283079386, 7011.614361210182, 7032.824179395556, 7038.929610703827, 7049.823754406181, 7068.54054486467, 6762.289959554373, 6878.105846239957, 6909.378155965987, 6911.0, 6938.306169383021, 6788.185417749902, 6855.108835851307, 6863.176418829573, 7036.542624729581, 7040.415906421014, 6706.228783689743, 6742.393184613408, 6770.492213084137, 6776.627285499148, 6789.321067893171, 6829.453643708591, 6846.796434709557, 6848.520603557703, 6925.203601585882, 6928.064711264116, 6424.775132870327, 6671.733130674718, 6672.4328432081875, 6677.03183038447, 6679.933200668125, 6465.547411681137, 6530.8367290818105, 6608.173978252718, 6620.139381880324, 6663.733450661919, 6443.420092191725, 6459.967700161657, 6505.544611877123, 6520.673966301761, 6536.477081833537, 6436.774712885027, 6440.194975628046, 6571.57284776488, 6659.600423974577, 6694.497912656473, 6286.339934307015, 6512.511561632802, 6542.34576542342, 6547.214334279917, 6552.08270842092], "value": 6165.900157591347, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.6350261366703, 797.429837666069, 836.6193382011184, 1224.2165014390787, 1261.5836773864626, 38799.0, 39074.8046259769, 39272.0, 39349.409758853566, 39565.61520346788, 39844.60155398445, 39937.0, 40273.597264027354, 40376.788696339085, 40394.0, 7892.921070789291, 8013.358931285512, 8282.213189746995, 8522.616482258314, 8534.57327133644, 8550.914490855104, 8603.182697643713, 8625.913740862605, 8648.437851539662, 8650.43772154808, 7250.891236631454, 7262.382697470722, 8593.355498337618, 8595.871061934076, 8637.740867773977, 7277.7991631380755, 8580.828383432328, 8581.95709021451, 8619.784505387353, 8642.22220000203, 7100.28997100291, 8379.49723016621, 8433.915660843375, 8444.831103377897, 8473.703420380258, 31470.527942080887, 36954.63045369539, 37391.81304093462, 37464.625353746385, 37495.62504374949, 3425.6231814500384, 3480.7817263957663, 3493.4759786032046, 3573.5175751273505, 3580.5882323532865, 25815.35461613456, 27005.919763209447, 30372.392552148835, 30791.538126928113, 30856.53715194274, 2589.4044369794865, 3091.2426455518544, 3104.425681248976, 3135.3885992231526, 3135.404273188087, 261765.0, 262052.0, 284742.9999999979, 307288.4635576808, 307691.0, 47982.520174797806, 48048.51951480441, 48220.758896205996, 48361.03277934422, 48457.515424845304, 50260.0, 50364.99270014579, 50443.0, 50463.74768126136, 50501.74749126231, 14076.014678972579, 14137.575872723835, 14226.28868556568, 14372.712545749235, 14556.272186390637, 11535.365554894393, 11612.12909031826, 11646.068314534818, 11661.53353865836, 11662.416879156008, 10804.621838235624, 12135.453904574315, 12405.0, 13512.93243533776, 13727.794083088762, 5972.641641501524, 5989.970050149721, 6050.9394906050375, 6058.879107365152, 6092.238470191226, 1004.7789486313, 1012.1447376966598, 1018.2617602238317, 1050.99474502627, 1052.921030922684, 23249.418764530685, 24205.626995255916, 24561.89471473788, 24585.13952011671, 24607.0, 1109.312226419625, 1115.7991561519004, 1126.6507382711386, 1140.4810811080938, 1155.0817100405197, 10529.683789526309, 11618.477168527437, 12291.631251062483, 12378.938105309417, 12417.689557760948, 317120.0, 318761.4061929722, 319059.0, 320050.0, 323860.380698095, 52956.47043529592, 53668.73165634147, 53760.19359709608, 53863.730681346344, 53998.0, 1490.1580606957034, 1522.9543113706827, 1560.1185330288351, 1560.4772401245482, 1565.272148450953, 17007.404740834012, 20638.07128679183, 21872.0, 22591.887040564692, 23887.76112238925, 37186.442203367515, 37268.813655931546, 37422.625773741915, 37437.0, 37455.06362340963, 266847.0, 269902.0, 270784.0, 270873.0, 270875.0, 23342.416439589146, 28476.0, 32991.83504082464, 33688.663113369534, 33825.0, 33056.0, 41636.96264041757, 42085.36871946862, 42937.926551836455, 43159.568404315556, 30525.710571706768, 30874.228144296572, 30886.53670194994, 31281.530777037908, 31458.370832583678, 30998.380032399677, 31017.534736978512, 31057.825952184034, 31119.910803121784, 31480.842595786875, 37197.07007324838, 37546.0, 37609.4358584616, 37624.43563346497, 37630.62369376381, 23740.52518949646, 29477.410451791275, 31251.84374078115, 31778.841105794323, 31806.522902156023, 22238.110475580797, 23638.409039773454, 24627.0, 24653.0, 24819.0], "value": 17262.220534490167, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.4265424610485, 433.6855779559817, 436.0602900748887, 447.6396500816843, 463.85388602590183, 19726.506837329056, 21288.680669789945, 21895.233666821663, 22153.556928861413, 22259.66610500842, 22023.889880550574, 22633.660495092547, 23072.30783076506, 23793.524129517442, 24040.398990025256, 5943.583949123574, 6156.6613836238985, 6162.969185154068, 6164.075388691687, 6180.660063696495, 5882.264716910387, 6125.754969801203, 6160.322364539919, 6172.166757487749, 6196.318404975472, 5964.433378829004, 6041.486473649745, 6054.485368743662, 6056.273247210319, 6144.047672610753, 6098.512119030489, 6106.389361063881, 6120.724567394478, 6128.846778830521, 6138.693065346737, 5374.435684253175, 5385.3268341457315, 5412.350517937873, 5421.756020979065, 5480.123180291134, 27479.587806182924, 27574.172774816787, 28004.879804807784, 28064.719352806416, 28461.430771384457, 2163.816075633573, 2239.29462219402, 2241.1371621925514, 2242.6411774116227, 2244.394013616331, 17773.46679599614, 17784.91107544454, 17987.820121798744, 18147.364842230458, 18419.539511512186, 2144.8391370647264, 2147.527543940331, 2185.4536365908525, 2189.157174487834, 2191.1016483241956, 158688.0, 160960.0, 162634.0, 163341.18329408276, 165055.0, 10376.948115259376, 12470.127091103535, 12565.371731413576, 12568.560100396442, 12601.621951341474, 13486.93256533711, 16136.919315403582, 16341.346346146021, 16385.672286554203, 16459.177041147894, 7771.766846994599, 8718.607662655195, 8741.344399170088, 9033.954830225806, 9190.816183676288, 6608.306127856602, 7321.853562928818, 8521.531315777567, 8558.0, 8665.43674661145, 7900.972873526404, 7902.130765615778, 8011.83976320482, 8031.196880312039, 8046.476978996347, 1864.4313484387121, 1931.5750534882304, 2226.1651880544796, 2248.8313376496503, 2399.064364897691, 818.0674031603976, 884.8938127424622, 923.330585325647, 926.3284119013662, 976.6386437018349, 20741.27405540799, 21765.45586360322, 21795.23716669909, 21825.345239642837, 21947.231846885283, 962.9422234665942, 1019.938803671782, 1023.8686251691922, 1039.4958445154093, 1041.958321667125, 10400.947995260127, 11013.394263315595, 11071.5571377144, 11114.542140231655, 11167.162462815322, 528811.0, 531281.0, 534687.0, 539844.0, 543650.0, 113184.43407782908, 122264.0, 128310.0, 131139.34430327787, 133425.0, 1205.8372119763894, 1230.059004861282, 1263.387257180267, 1265.8227848101274, 1267.8415198100238, 18814.52963675919, 19452.805471945667, 20064.996750162725, 21008.369748907557, 22375.9930803111, 53943.0, 53951.460485394644, 54363.14038190492, 54594.0, 54611.0, 74094.25905740874, 74107.62946185234, 74614.0, 74793.25206747864, 74800.0, 7512.8873066905135, 8578.4424012439, 8654.653813847628, 8890.799312247456, 8982.236509896731, 11521.308721476738, 13838.23889686077, 14006.84845909703, 14042.78935815943, 14079.0, 11149.10807135411, 11622.709432263922, 11799.76400472003, 11860.703482412664, 11952.46213920393, 9327.580258888502, 10694.46796640723, 10964.616238431616, 11079.667609971713, 11110.777784444428, 11897.881021190024, 11898.405079746151, 11982.10134239953, 12145.210561313486, 12157.392130393268, 8613.87079193825, 8766.693165739169, 8945.418547794372, 8952.776180595276, 9058.864117038382, 11618.244814087057, 11640.185187036826, 11758.059355251731, 11793.351365674971, 12004.459799308877], "value": 10380.30276950098, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.3148425787106, 1436.8634979676933, 1447.8696917277443, 1448.6812901161743, 1458.5915943535813, 11599.30404175749, 12050.939745301283, 12137.332446715427, 12146.271223726582, 12146.39268036598, 11911.0, 11941.820872686916, 12131.696707582314, 12133.878661213384, 12147.0, 2592.2871210417097, 2629.0929629277966, 2630.4607555451184, 2632.644592979952, 2636.578147496401, 2526.2295000024983, 2567.4608332250154, 2571.5114128315654, 2597.2467984284535, 2598.389378496052, 2544.211294498702, 2568.935776605581, 2600.9739902600923, 2602.024240909659, 2603.4793041391704, 2542.5677634802128, 2565.3330134165158, 2592.675915510561, 2595.4419799743146, 2609.0346571768378, 2407.8675672837985, 2556.194798638423, 2590.8315959462575, 2591.6501272328182, 2593.8054645901634, 11643.417829108595, 11720.355380454072, 11765.176437649368, 11803.763924721457, 11821.645350639494, 6588.736450541973, 6779.62712050837, 6833.214180369222, 6866.759663411806, 6866.793996180123, 3919.5492518360184, 4263.637590804786, 4355.716878402894, 4414.536473670282, 4428.71213371133, 1044.6918159143072, 1220.4934951994912, 1232.963011109668, 1287.9806802897965, 1288.8453385593696, 16370.672586548322, 16582.08798516081, 16593.751093733605, 16651.917625354436, 16723.749143762852, 5517.613767036269, 5536.778528858881, 5706.258186435737, 5777.855553611109, 5793.536517078624, 11851.703707407383, 12008.879911200776, 12029.338386388832, 12052.337121458404, 12058.758824823455, 6579.638119903356, 6607.735690572324, 6622.801315960529, 6630.104935833697, 6633.701483433257, 6022.2171117754415, 6140.785072522441, 6254.6872656366995, 6302.464290535356, 6313.652749098752, 4342.956570434319, 4408.206522825858, 4433.645308375322, 4440.3561483585345, 4451.931536431298, 1271.1292764456377, 1278.9104762666525, 1285.8714128587067, 1297.280009594684, 1301.4208677138583, 670.5272782688193, 797.5653268968426, 826.9751907442786, 833.3666413525643, 841.3731769831393, 6781.152355955505, 6981.581105133607, 7107.431405487549, 7142.178649455275, 7202.639868006579, 793.3613441179906, 952.9666461673946, 968.2883080935507, 969.3311614985637, 982.3025651787168, 4212.304969679968, 4502.257127573979, 4546.1589605922345, 4651.697639653412, 4758.929240920766, 12194.81707774384, 15023.699526009637, 15047.849521504644, 15357.846421535642, 15414.537563873102, 15273.312700928485, 15294.235288235544, 15312.310946007234, 15313.923430382776, 15330.080195188324, 6409.871802563923, 8295.543745094077, 8409.873851892227, 8627.6980305689, 8689.21797038257, 6114.327423983269, 6973.965130174317, 7204.387627051759, 7229.49393542447, 7256.092988376453, 12317.384130793604, 13532.661683457993, 13533.526326578523, 13792.448302067805, 13853.16880987144, 13659.726805463637, 13843.269591301087, 13867.930660347038, 13885.305734713425, 13948.093373930662, 7680.084745762828, 9211.631534738535, 9304.674336398193, 9530.666426675032, 9555.23558115363, 10612.681619551426, 10754.13966882632, 10850.186236032487, 10930.836037459603, 10948.781024379627, 8531.573421329032, 8597.398182127441, 8816.250618697482, 8934.642614295355, 8952.23905967974, 8653.61058752345, 8731.301495880187, 8842.0, 8881.555922203994, 8885.777855553404, 8242.917570824215, 8379.581020948806, 8404.285635721033, 8434.367422443218, 8440.409171357946, 6027.909581356371, 6105.938940610537, 6172.22847144107, 6245.937540624535, 6274.654893980693, 6136.20229370179, 6196.47329976957, 6244.812655620339, 6291.653959032297, 6324.557280990287], "value": 5461.834331759566, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.0475057486744, 398.8723608445297, 401.9468991242944, 405.8291828076004, 411.8764370688793, 6449.419552240297, 6480.708368123434, 6489.253735820382, 6508.609483430998, 6524.70638821253, 5341.893162136755, 6106.17566628505, 6268.561200715952, 6300.5589608727405, 6392.7442902283965, 4777.140114779347, 4812.350332705092, 4812.893034602043, 4814.205656066745, 4814.339053026718, 3874.728768986172, 4093.9590604094024, 4137.66898648109, 4842.12841688496, 4854.344663470421, 4326.437563116806, 4366.67249956253, 4640.837570685027, 4751.714897106184, 4788.569028787409, 4762.595179409737, 4763.19025765619, 4787.832425865078, 4789.904631540053, 4798.376211092535, 4118.567550407194, 4226.922134855623, 4232.132412855357, 4243.8090285937205, 4264.0, 4828.830990915301, 5089.821856235051, 6196.535259855484, 6220.875582488325, 6318.115463835067, 5262.026525092831, 5351.143816989302, 5637.238972738669, 5661.631993920382, 5686.800961966353, 6363.522735794834, 6630.900536491957, 6746.595204287759, 6778.932210677879, 6798.796036118924, 4413.360062790909, 4416.84541041062, 4423.646108311327, 4438.733675979419, 4438.757147998565, 5522.790883646573, 6210.937890621081, 6670.666466676646, 6779.796606101825, 7046.189688185822, 3729.2541491701227, 4457.398251236106, 4480.395146655225, 4489.932651010237, 4493.236149854532, 3832.0, 4546.340780586863, 4546.5453454654935, 4551.977240113778, 4558.886027849264, 5316.202569614588, 5381.408045115034, 5402.972985135049, 5437.401885792559, 5466.753996070186, 5845.707714614252, 6639.734410623618, 6749.122614060141, 6786.219584747817, 6806.727730890808, 1357.911735737174, 1507.6833864888497, 1539.3605809812514, 1550.4263422533509, 1559.5243450747405, 843.7384410832661, 983.8081574093127, 1003.7390278527636, 1008.9607704064825, 1020.9280255731452, 6273.027680709497, 6296.338884417162, 6306.873862522724, 6331.461825744771, 6353.555251132377, 5832.766689332379, 6799.4900382471515, 6950.443964482829, 6982.650867456607, 7050.858982820315, 6004.369541198199, 6090.725917333731, 6416.326285740023, 6428.389303016206, 6484.610923344614, 3463.1861512544533, 3521.9119522012156, 3690.6195503899835, 3697.5193224880595, 3795.981020094882, 5958.702064896738, 6150.907736383958, 7263.256818363555, 7275.381592564586, 7305.963470182615, 6513.54405191632, 6757.459403247728, 6925.965370173218, 7327.706891724378, 7334.523255988343, 6904.723811047502, 7232.783016509513, 7272.381847543018, 7299.41604671625, 7363.190049094595, 6281.937180628318, 6852.965735171292, 7063.964680176772, 7078.433725302067, 7082.441862790384, 5483.75323110469, 5752.367239603724, 6772.762953296611, 6974.755883544051, 7026.859462810613, 5766.682832444256, 5830.766769329349, 6500.349965003462, 6630.7679231226675, 6651.368120028589, 4284.892877678083, 5088.898222035465, 5118.206677964921, 5131.87170320745, 5132.615053871049, 6829.52193346461, 7034.929650703428, 7115.719170549351, 7126.180489243803, 7153.928460715326, 6741.764038258638, 6791.320867913169, 6804.489663275175, 6804.795856124324, 6863.416609588241, 6257.8422991746675, 6303.842403939938, 6812.693428795617, 6835.350641689031, 6874.6562671867205, 6181.3200547938795, 6661.100751398693, 6673.299303573249, 6687.866242675022, 6688.197416310074, 6522.739090436519, 6726.159230096238, 6802.387785099365, 6816.454683625398, 6887.552309099892, 5564.888702226014, 6675.531383095711, 6691.063251144747, 6695.732170713117, 6706.195256569243], "value": 4909.903739385103, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.4907435629661, 799.7800604833666, 845.1970627903494, 854.9099897630521, 872.1496540872648, 14481.710365792678, 15182.84817151828, 15533.766993495092, 15726.921365393186, 19469.026548672544, 14648.707025859476, 14775.335109920079, 18370.081495925217, 18596.628067438643, 18774.249030038816, 965.3194497878997, 983.8475036369374, 990.6631745206628, 994.3636072913341, 996.1383403356117, 914.1498406481969, 1006.079437314856, 1009.5154325923525, 1012.71643939697, 1013.8225810483173, 959.5825815770128, 1018.2007124407342, 1050.1808589300358, 1053.157474020784, 1054.836500342448, 1046.1578429364358, 1048.3447845096814, 1048.7745134796055, 1050.0916707048386, 1057.4765491081926, 773.5088218980932, 773.6750564762757, 777.1451403456153, 778.8909552662575, 780.8906753054576, 14363.56909292723, 16963.3214671413, 17445.47663570095, 17691.557711057198, 17772.822271777248, 14498.855011449856, 17076.65846683072, 17487.737683934625, 17530.737038944426, 17559.385421510186, 14492.20292883891, 14530.273486325746, 14636.780448293284, 14704.485343012942, 14992.025518341383, 3550.0, 3562.87529936451, 3587.8744243951596, 3596.2447885943984, 3605.06268370223, 101771.0, 112733.0, 113000.0, 131159.5398437147, 133803.99999999904, 13484.86515134836, 13509.729805403837, 13529.729405412034, 13617.25105119228, 13636.795448068287, 26037.609435858478, 26225.868870655908, 26235.344116397242, 26399.0, 26583.33541661435, 7736.342410895025, 7880.408969327324, 7935.801604959806, 7966.362690984707, 7974.202579741979, 3865.342891708416, 3942.8225729842225, 3946.2896678598127, 3949.7037722170953, 3953.248882712333, 4299.247631664446, 4343.1747967886, 4414.84548040817, 4440.800163992628, 4440.822367105279, 872.9781755456164, 900.2258058070055, 901.8241442918699, 905.2260317428504, 907.3195103672246, 654.1104098426136, 654.9508786841006, 661.490652197805, 667.3060017581661, 694.0248950224942, 10749.570017199381, 11162.832557511643, 11502.827457588142, 11910.702232444084, 12162.08784341178, 607.1348328631682, 620.1503939602683, 620.9937900620936, 623.9438450539474, 643.6556442303439, 4082.857100001486, 5056.84829455117, 5340.519353258225, 5496.120620700669, 5547.306586676666, 109581.0, 111238.3314250287, 112134.0, 112613.0, 114682.27976580359, 83769.0, 84626.57686711528, 86162.56918715367, 88276.55861720651, 88402.11597884068, 80333.59833200913, 81695.18304816994, 82614.0, 83032.16967830365, 83806.0, 8942.50816205115, 12294.20087694297, 12732.771764941284, 12832.615021549369, 12858.742825143258, 31193.908213212428, 40170.59829401669, 40539.13530376374, 41149.0, 41647.750567483024, 68425.65787171033, 71611.64194179204, 73069.6346518264, 73358.63320683576, 74763.62618186875, 12518.499260029861, 16591.834081659028, 17191.0, 17198.82801171972, 17293.481195564153, 17705.46883593443, 18682.71975920335, 18776.15507302202, 18889.05554722286, 18950.52623684419, 13231.3384330785, 14343.56969290923, 16113.67772644517, 16594.917025414794, 17303.653926921645, 16423.0, 16696.66606667884, 16951.915240423717, 17317.826821732124, 17492.82507174912, 16214.51356459308, 16457.0, 16795.076270805224, 16896.155192240585, 16936.57658558546, 9793.412395256308, 10429.895701042893, 11774.3524106175, 11990.580329688768, 12227.082968777555, 12110.757784844078, 12850.67873303175, 12853.293068881301, 13063.934680326538, 13071.803922941353], "value": 8387.71466175018, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 115.0, 114.0, 112.0, 113.0, 114.0, 113.0, 114.0, 117.0, 118.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 121.0, 152.0, 108.0, 117.0, 113.0, 110.0, 134.0, 110.0], "value": 121.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 201.0, 206.0, 198.0, 203.0, 236.0, 272.0, 206.0, 202.0], "value": 212.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 88.0, 61.0, 63.0, 60.0, 59.0, 85.0, 79.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 73.0, 99.0, 58.0, 70.0, 96.0, 106.0, 56.0, 120.0], "value": 83.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 92.0, 93.0, 92.0, 111.0, 84.0, 123.0, 138.0, 98.0], "value": 101.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 98.0, 102.0, 149.0, 157.0, 99.0, 97.0, 102.0, 98.0, 98.0], "value": 115.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 92.0, 91.0, 93.0, 91.0, 90.0, 96.0, 90.0, 90.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [46.0, 48.0, 47.0, 49.0, 52.0, 50.0, 52.0, 51.0, 51.0, 49.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 57.0, 56.0, 66.0, 60.0, 58.0, 57.0, 65.0, 73.0], "value": 61.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 80.0, 85.0, 84.0, 82.0, 85.0, 106.0, 108.0, 108.0, 72.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 152.0, 140.0, 138.0, 132.0, 173.0, 148.0, 138.0, 219.0, 152.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 186.0, 190.0, 191.0, 218.0, 193.0, 185.0, 186.0, 185.0, 188.0], "value": 191.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 70.0, 83.0, 66.0, 70.0, 70.0, 71.0, 99.0], "value": 73.8, "unit": "score"}]}]} 09:47:25 INFO - Return code: 0 09:47:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:25 INFO - # TBPL SUCCESS # 09:47:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:25 INFO - 2017-05-17 09:47:25.475 screenresolution[1666:10791] starting screenresolution argv=screenresolution get 09:47:25 INFO - 2017-05-17 09:47:25.477 screenresolution[1666:10791] Display 0: 1600x1200x32@60 09:47:25 INFO - 2017-05-17 09:47:25.483 screenresolution[1667:10795] starting screenresolution argv=screenresolution list 09:47:25 INFO - Available Modes on Display 0 09:47:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:25 INFO - Intel Iris: 09:47:25 INFO - Chipset Model: Intel Iris 09:47:25 INFO - Type: GPU 09:47:25 INFO - Bus: Built-In 09:47:25 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:25 INFO - Vendor: Intel (0x8086) 09:47:25 INFO - Device ID: 0x0a2e 09:47:25 INFO - Revision ID: 0x0009 09:47:25 INFO - Displays: 09:47:25 INFO - Display: 09:47:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:25 INFO - Display Serial Number: 1600x1200 60 09:47:25 INFO - Main Display: Yes 09:47:25 INFO - Mirror: Off 09:47:25 INFO - Online: Yes 09:47:25 INFO - Rotation: Supported 09:47:25 INFO - Return code: 0 09:47:25 INFO - Running post-action listener: _resource_record_post_action 09:47:25 INFO - [mozharness: 2017-05-17 16:47:25.725056Z] Finished run-tests step (success) 09:47:25 INFO - Running post-run listener: _resource_record_post_run 09:47:25 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 46590976; Write bytes: 529776128; Read time: 493; Write time: 1986 09:47:25 INFO - TinderboxPrint: CPU usage
54.1% 09:47:25 INFO - TinderboxPrint: I/O read bytes / time
46,590,976 / 493 09:47:25 INFO - TinderboxPrint: I/O write bytes / time
529,776,128 / 1,986 09:47:25 INFO - TinderboxPrint: CPU idle
2,196.5 (45.9%) 09:47:25 INFO - TinderboxPrint: CPU system
965.4 (20.2%) 09:47:25 INFO - TinderboxPrint: CPU user
1,623.7 (33.9%) 09:47:25 INFO - TinderboxPrint: Swap in / out
255,139,840 / 0 09:47:25 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 206438912; Write bytes: 207542272; Read time: 15799; Write time: 470 09:47:25 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 45575168; Write bytes: 312407552; Read time: 477; Write time: 1487 09:47:26 INFO - Running post-run listener: _upload_blobber_files 09:47:26 INFO - Blob upload gear active. 09:47:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:26 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 09:47:26 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'] 09:47:26 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 09:47:26 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:47:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:47:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:27 INFO - (blobuploader) - INFO - Done attempting. 09:47:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:47:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:47:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:28 INFO - (blobuploader) - INFO - Done attempting. 09:47:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:47:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:47:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:31 INFO - (blobuploader) - INFO - Done attempting. 09:47:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:33 INFO - (blobuploader) - INFO - Done attempting. 09:47:33 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:33 INFO - Return code: 0 09:47:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feab2fcf6b4c5a2f27d4bae704927e9269a07ca760f0f8b5da74b45cc758483f1f8eb2b81ee714b67a3e7994e46e6c420600ae3ce2986f32931d4cbc28184591", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e0ab20da5235388ae85565373263223aa9f1de2e23d02dcfb5a1d7f1eeeb41ff099a7f4acd8d4f33ca31e9f48c9252fe2ca95f772cb077fb79079c5f23f225c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ea62d7bd020c191e6cada9b8279c7eb88bf7bb3a90a0e3203f898cd35cc4b580c051814d4f5367044e82e182eb0a2ce3fd40424e151863269282d3d7fadf24b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdf3a66efe5069980bd578cb2af1ebeb453c9d055d0ff008549723952757a3ad6677b2350b35d9f83e303631efa2b45407f7eed4bc86d228047767632c2eeec7"} 09:47:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:33 INFO - Contents: 09:47:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/feab2fcf6b4c5a2f27d4bae704927e9269a07ca760f0f8b5da74b45cc758483f1f8eb2b81ee714b67a3e7994e46e6c420600ae3ce2986f32931d4cbc28184591", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e0ab20da5235388ae85565373263223aa9f1de2e23d02dcfb5a1d7f1eeeb41ff099a7f4acd8d4f33ca31e9f48c9252fe2ca95f772cb077fb79079c5f23f225c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ea62d7bd020c191e6cada9b8279c7eb88bf7bb3a90a0e3203f898cd35cc4b580c051814d4f5367044e82e182eb0a2ce3fd40424e151863269282d3d7fadf24b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdf3a66efe5069980bd578cb2af1ebeb453c9d055d0ff008549723952757a3ad6677b2350b35d9f83e303631efa2b45407f7eed4bc86d228047767632c2eeec7"} 09:47:33 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:33 INFO - Copying logs to upload dir... 09:47:33 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.022373 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-17 09:47:33.459876) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 09:47:33.464585) ========= 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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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/feab2fcf6b4c5a2f27d4bae704927e9269a07ca760f0f8b5da74b45cc758483f1f8eb2b81ee714b67a3e7994e46e6c420600ae3ce2986f32931d4cbc28184591", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e0ab20da5235388ae85565373263223aa9f1de2e23d02dcfb5a1d7f1eeeb41ff099a7f4acd8d4f33ca31e9f48c9252fe2ca95f772cb077fb79079c5f23f225c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ea62d7bd020c191e6cada9b8279c7eb88bf7bb3a90a0e3203f898cd35cc4b580c051814d4f5367044e82e182eb0a2ce3fd40424e151863269282d3d7fadf24b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdf3a66efe5069980bd578cb2af1ebeb453c9d055d0ff008549723952757a3ad6677b2350b35d9f83e303631efa2b45407f7eed4bc86d228047767632c2eeec7"} build_url:https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011705 build_url: 'https://queue.taskcluster.net/v1/task/V92bddFaSrSUUY_zvPx1hQ/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/feab2fcf6b4c5a2f27d4bae704927e9269a07ca760f0f8b5da74b45cc758483f1f8eb2b81ee714b67a3e7994e46e6c420600ae3ce2986f32931d4cbc28184591", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5e0ab20da5235388ae85565373263223aa9f1de2e23d02dcfb5a1d7f1eeeb41ff099a7f4acd8d4f33ca31e9f48c9252fe2ca95f772cb077fb79079c5f23f225c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ea62d7bd020c191e6cada9b8279c7eb88bf7bb3a90a0e3203f898cd35cc4b580c051814d4f5367044e82e182eb0a2ce3fd40424e151863269282d3d7fadf24b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cdf3a66efe5069980bd578cb2af1ebeb453c9d055d0ff008549723952757a3ad6677b2350b35d9f83e303631efa2b45407f7eed4bc86d228047767632c2eeec7"}' ========= master_lag: 0.37 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-17 09:47:33.850892) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 09:47:33.854241) ========= 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.AdL1X0V34Y/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.gxVzMc3bdI/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005265 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-17 09:47:33.890556) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 09:47:33.890894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-17 09:47:33.891197) ========= ========= Total master_lag: 0.53 =========