builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0358 starttime: 1496167888.85 results: success (0) revision: c3cd8c58fa87d571874f2bcc34ec0864ac7acd5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:28.845563) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:28.846106) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:28.846407) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:28.872010) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:28.872416) ========= 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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-30 11:11:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2017-05-30 11:11:29 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144454 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:29.040474) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:29.040787) ========= 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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.027502 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:29.090070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 11:11:29.090495) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev c3cd8c58fa87d571874f2bcc34ec0864ac7acd5f --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 c3cd8c58fa87d571874f2bcc34ec0864ac7acd5f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5uxOhoyzQW/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.eJNJbC9vTi/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-30 11:11:29,191 truncating revision to first 12 chars 2017-05-30 11:11:29,191 Setting DEBUG logging. 2017-05-30 11:11:29,191 attempt 1/10 2017-05-30 11:11:29,191 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/c3cd8c58fa87?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 11:11:30,541 unpacking tar archive at: mozilla-central-c3cd8c58fa87/testing/mozharness/ program finished with exit code 0 elapsedTime=1.643027 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 11:11:30.755982) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:30.756378) ========= script_repo_revision: c3cd8c58fa87d571874f2bcc34ec0864ac7acd5f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:30.756865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:30.757172) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 11:11:30.776522) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-30 11:11:30.776994) ========= /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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:11:30 INFO - MultiFileLogger online at 20170530 11:11:30 in /builds/slave/test 11:11:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:11:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:11:30 INFO - {'append_to_log': False, 11:11:30 INFO - 'base_work_dir': '/builds/slave/test', 11:11:30 INFO - 'blob_upload_branch': 'Firefox', 11:11:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:11:30 INFO - 'branch': 'Firefox', 11:11:30 INFO - 'buildbot_json_path': 'buildprops.json', 11:11:30 INFO - 'config_files': ('talos/mac_config.py',), 11:11:30 INFO - 'default_actions': ('clobber', 11:11:30 INFO - 'read-buildbot-config', 11:11:30 INFO - 'download-and-extract', 11:11:30 INFO - 'populate-webroot', 11:11:30 INFO - 'create-virtualenv', 11:11:30 INFO - 'install', 11:11:30 INFO - 'setup-mitmproxy', 11:11:30 INFO - 'run-tests'), 11:11:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:11:30 INFO - 'download_minidump_stackwalk': True, 11:11:30 INFO - 'download_symbols': 'ondemand', 11:11:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:11:30 INFO - 'tooltool.py': '/tools/tooltool.py', 11:11:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:11:30 INFO - '/tools/misc-python/virtualenv.py')}, 11:11:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:11:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:11:30 INFO - 'gecko_profile': False, 11:11:30 INFO - 'gecko_profile_interval': 0, 11:11:30 INFO - 'installer_path': 'installer.exe', 11:11:30 INFO - 'log_level': 'info', 11:11:30 INFO - 'log_name': 'talos', 11:11:30 INFO - 'log_to_console': True, 11:11:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:11:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:11:30 INFO - 'opt_config_files': (), 11:11:30 INFO - 'pip_index': False, 11:11:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:30 INFO - 'cmd': ('bash', 11:11:30 INFO - '-c', 11:11:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:30 INFO - 'enabled': True, 11:11:30 INFO - 'halt_on_failure': False, 11:11:30 INFO - 'name': 'check_screen_resolution'},), 11:11:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:30 INFO - 'cmd': ('bash', 11:11:30 INFO - '-c', 11:11:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:30 INFO - 'enabled': True, 11:11:30 INFO - 'halt_on_failure': False, 11:11:30 INFO - 'name': 'check_screen_resolution'},), 11:11:30 INFO - 'run_cmd_checks_enabled': True, 11:11:30 INFO - 'suite': 'dromaeojs-e10s', 11:11:30 INFO - 'system_bits': '32', 11:11:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:11:30 INFO - 'title': 't-yosemite-r7-0358', 11:11:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:11:30 INFO - 'use_talos_json': True, 11:11:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:11:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:11:30 INFO - 'work_dir': 'build'} 11:11:30 INFO - [mozharness: 2017-05-30 18:11:30.985528Z] Running clobber step. 11:11:30 INFO - Running pre-action listener: _resource_record_pre_action 11:11:30 INFO - Running main action method: clobber 11:11:30 INFO - rmtree: /builds/slave/test/build 11:11:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:11:33 INFO - Running post-action listener: _resource_record_post_action 11:11:33 INFO - [mozharness: 2017-05-30 18:11:33.436658Z] Finished clobber step (success) 11:11:33 INFO - [mozharness: 2017-05-30 18:11:33.436842Z] Running read-buildbot-config step. 11:11:33 INFO - Running pre-action listener: _resource_record_pre_action 11:11:33 INFO - Running main action method: read_buildbot_config 11:11:33 INFO - Using buildbot properties: 11:11:33 INFO - { 11:11:33 INFO - "project": "", 11:11:33 INFO - "product": "firefox", 11:11:33 INFO - "who": "ryanvm@gmail.com", 11:11:33 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 11:11:33 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:11:33 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 11:11:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:11:33 INFO - "stage_platform": "macosx64", 11:11:33 INFO - "basedir": "/builds/slave/test", 11:11:33 INFO - "buildnumber": 169, 11:11:33 INFO - "platform": "yosemite_r7", 11:11:33 INFO - "parent_task_id": "cCGfMGbeS9Wr9oX4p8oz6Q", 11:11:33 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:11:33 INFO - "slavebuilddir": "test", 11:11:33 INFO - "taskId": "RTuDKMjZTzKrGnhjQGMUFQ", 11:11:33 INFO - "branch": "mozilla-central", 11:11:33 INFO - "script_repo_revision": "production", 11:11:33 INFO - "revision": "c3cd8c58fa87d571874f2bcc34ec0864ac7acd5f", 11:11:33 INFO - "slavename": "t-yosemite-r7-0358", 11:11:33 INFO - "repo_path": "mozilla-central" 11:11:33 INFO - } 11:11:33 INFO - Finding installer, test and symbols from parent task. 11:11:33 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RTuDKMjZTzKrGnhjQGMUFQ'}, attempt #1 11:11:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RTuDKMjZTzKrGnhjQGMUFQ'}, attempt #1 11:11:34 INFO - Task dependencies: Ea70IlRETz6mcHGWKXpm4Q cCGfMGbeS9Wr9oX4p8oz6Q 11:11:34 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q'}, attempt #1 11:11:35 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:11:35 INFO - Set test_url: https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/test_packages.json 11:11:35 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/target.crashreporter-symbols.zip 11:11:35 INFO - Running post-action listener: _resource_record_post_action 11:11:35 INFO - [mozharness: 2017-05-30 18:11:35.549394Z] Finished read-buildbot-config step (success) 11:11:35 INFO - [mozharness: 2017-05-30 18:11:35.549538Z] Running download-and-extract step. 11:11:35 INFO - Running pre-action listener: _resource_record_pre_action 11:11:35 INFO - Running main action method: download_and_extract 11:11:35 INFO - mkdir: /builds/slave/test/build/tests 11:11:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:35 INFO - trying https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:11:35 INFO - Downloading https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:11:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 11:11:36 INFO - Downloaded 1325 bytes. 11:11:36 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:11:36 INFO - Using the following test package requirements: 11:11:36 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:11:36 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:11:36 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:11:36 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:11:36 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'jsshell-mac.zip'], 11:11:36 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:11:36 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:11:36 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:11:36 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:11:36 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:11:36 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:11:36 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:11:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:11:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:11:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:11:36 INFO - Fetch https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:11:38 INFO - Content-Length response header: 57630693 11:11:38 INFO - Bytes received: 57630693 11:11:56 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:11:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:11:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:11:56 INFO - Fetch https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:11:58 INFO - Content-Length response header: 57630693 11:11:58 INFO - Bytes received: 57630693 11:12:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:12:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:12:15 INFO - Fetch https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:12:16 INFO - Content-Length response header: 14447425 11:12:16 INFO - Bytes received: 14447425 11:12: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')]} 11:12:16 INFO - trying https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:12:16 INFO - Downloading https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:12:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 11:12:18 INFO - Downloaded 67948823 bytes. 11:12:18 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:12:18 INFO - mkdir: /builds/slave/test/properties 11:12:18 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:12:18 INFO - Writing to file /builds/slave/test/properties/build_url 11:12:18 INFO - Contents: 11:12:18 INFO - build_url:https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:12:18 INFO - Running post-action listener: _resource_record_post_action 11:12:18 INFO - Running post-action listener: set_extra_try_arguments 11:12:18 INFO - [mozharness: 2017-05-30 18:12:18.908390Z] Finished download-and-extract step (success) 11:12:18 INFO - [mozharness: 2017-05-30 18:12:18.908476Z] Running populate-webroot step. 11:12:18 INFO - Running pre-action listener: _resource_record_pre_action 11:12:18 INFO - Running main action method: populate_webroot 11:12:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:12:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:12:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:12:18 INFO - '--tppagecycles', 11:12:18 INFO - '7'], 11:12:18 INFO - 'tests': ['tsvgm']}}, 11:12:18 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:12:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:12:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:12:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:12:18 INFO - 'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:12:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:12:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:12:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:12:18 INFO - 'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['damp', 'tps']}, 11:12:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:12:18 INFO - 'tests': ['damp', 'tps']}, 11:12:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['dromaeo_dom']}, 11:12:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:12:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:12:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:12:18 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:12:18 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:12:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['a11yr', 11:12:18 INFO - 'ts_paint', 11:12:18 INFO - 'tpaint', 11:12:18 INFO - 'sessionrestore', 11:12:18 INFO - 'sessionrestore_no_auto_restore', 11:12:18 INFO - 'tabpaint']}, 11:12:18 INFO - 'other-e10s': {'tests': ['a11yr', 11:12:18 INFO - 'ts_paint', 11:12:18 INFO - 'tpaint', 11:12:18 INFO - 'sessionrestore', 11:12:18 INFO - 'sessionrestore_no_auto_restore', 11:12:18 INFO - 'tabpaint']}, 11:12:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:12:18 INFO - 'ts_paint', 11:12:18 INFO - 'tpaint', 11:12:18 INFO - 'sessionrestore', 11:12:18 INFO - 'sessionrestore_no_auto_restore', 11:12:18 INFO - 'tabpaint']}, 11:12:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:12:18 INFO - 'ts_paint', 11:12:18 INFO - 'tpaint', 11:12:18 INFO - 'sessionrestore', 11:12:18 INFO - 'sessionrestore_no_auto_restore', 11:12:18 INFO - 'tabpaint']}, 11:12:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['a11yr', 11:12:18 INFO - 'ts_paint', 11:12:18 INFO - 'tpaint', 11:12:18 INFO - 'sessionrestore', 11:12:18 INFO - 'sessionrestore_no_auto_restore', 11:12:18 INFO - 'tabpaint']}, 11:12:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['a11yr', 11:12:18 INFO - 'ts_paint', 11:12:18 INFO - 'tpaint', 11:12:18 INFO - 'sessionrestore', 11:12:18 INFO - 'sessionrestore_no_auto_restore', 11:12:18 INFO - 'tabpaint']}, 11:12:18 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:12:18 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:12:18 INFO - 'bloom_basic_ref']}, 11:12:18 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 11:12:18 INFO - 'talos_options': ['--mitmproxy', 11:12:18 INFO - 'mitmproxy-recording-1.mp', 11:12:18 INFO - '--firstNonBlankPaint'], 11:12:18 INFO - 'tests': ['Quantum_1']}, 11:12:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['tsvgx', 11:12:18 INFO - 'tsvgr_opacity', 11:12:18 INFO - 'tart', 11:12:18 INFO - 'tscrollx', 11:12:18 INFO - 'cart', 11:12:18 INFO - 'tsvg_static']}, 11:12:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:12:18 INFO - 'tsvgr_opacity', 11:12:18 INFO - 'tart', 11:12:18 INFO - 'tscrollx', 11:12:18 INFO - 'cart', 11:12:18 INFO - 'tsvg_static']}, 11:12:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:12:18 INFO - 'talos_options': ['--disable-e10s'], 11:12:18 INFO - 'tests': ['tp5o']}, 11:12:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:12:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:12:18 INFO - 'talos_options': ['--disable-e10s', 11:12:18 INFO - '--xperf_path', 11:12:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:12:18 INFO - 'tests': ['tp5n']}, 11:12:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:12:18 INFO - 'talos_options': ['--xperf_path', 11:12:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:12:18 INFO - 'tests': ['tp5n']}}, 11:12:18 INFO - 'talos.zip': {'path': '', 11:12:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:12:18 INFO - Running post-action listener: _resource_record_post_action 11:12:18 INFO - [mozharness: 2017-05-30 18:12:18.930509Z] Finished populate-webroot step (success) 11:12:18 INFO - [mozharness: 2017-05-30 18:12:18.930608Z] Running create-virtualenv step. 11:12:18 INFO - Running pre-action listener: _resource_record_pre_action 11:12:18 INFO - Running main action method: create_virtualenv 11:12:18 INFO - Creating virtualenv /builds/slave/test/build/venv 11:12:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:12:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:12:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:12:19 INFO - Using real prefix '/tools/python27' 11:12:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:12:19 INFO - Installing distribute.............................................................................................................................................................................................done. 11:12:22 INFO - Installing pip.................done. 11:12:22 INFO - Return code: 0 11:12:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:12:22 INFO - Reading from file tmpfile_stdout 11:12:22 INFO - Output received: 11:12:22 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:12:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:22 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:22 INFO - 'HOME': '/Users/cltbld', 11:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:22 INFO - 'LOGNAME': 'cltbld', 11:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:22 INFO - 'MOZ_NO_REMOTE': '1', 11:12:22 INFO - 'NO_EM_RESTART': '1', 11:12:22 INFO - 'PAGER': '/bin/cat', 11:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:22 INFO - 'PWD': '/builds/slave/test', 11:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:22 INFO - 'SHELL': '/bin/bash', 11:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:22 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:22 INFO - 'USER': 'cltbld', 11:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:22 INFO - 'XPC_FLAGS': '0x0', 11:12:22 INFO - 'XPC_SERVICE_NAME': '0', 11:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:12:23 INFO - Cleaning up... 11:12:23 INFO - Return code: 0 11:12:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:12:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:23 INFO - 'HOME': '/Users/cltbld', 11:12:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:23 INFO - 'LOGNAME': 'cltbld', 11:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:23 INFO - 'MOZ_NO_REMOTE': '1', 11:12:23 INFO - 'NO_EM_RESTART': '1', 11:12:23 INFO - 'PAGER': '/bin/cat', 11:12:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:23 INFO - 'PWD': '/builds/slave/test', 11:12:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:23 INFO - 'SHELL': '/bin/bash', 11:12:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:23 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:23 INFO - 'USER': 'cltbld', 11:12:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:23 INFO - 'XPC_FLAGS': '0x0', 11:12:23 INFO - 'XPC_SERVICE_NAME': '0', 11:12:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:23 INFO - Downloading/unpacking psutil>=3.1.1 11:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:12:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:12:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:12:25 INFO - Installing collected packages: psutil 11:12:25 INFO - Running setup.py install for psutil 11:12:25 INFO - building 'psutil._psutil_osx' extension 11:12:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:12:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:12:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:12:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:12:26 INFO - building 'psutil._psutil_posix' extension 11:12:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:12:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:12:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:12:26 INFO - ^ 11:12:26 INFO - 1 warning generated. 11:12:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:12:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:12:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:12:26 INFO - Successfully installed psutil 11:12:26 INFO - Cleaning up... 11:12:26 INFO - Return code: 0 11:12:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:27 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:12:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:12:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:27 INFO - 'HOME': '/Users/cltbld', 11:12:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:27 INFO - 'LOGNAME': 'cltbld', 11:12:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:27 INFO - 'MOZ_NO_REMOTE': '1', 11:12:27 INFO - 'NO_EM_RESTART': '1', 11:12:27 INFO - 'PAGER': '/bin/cat', 11:12:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:27 INFO - 'PWD': '/builds/slave/test', 11:12:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:27 INFO - 'SHELL': '/bin/bash', 11:12:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:27 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:27 INFO - 'USER': 'cltbld', 11:12:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:27 INFO - 'XPC_FLAGS': '0x0', 11:12:27 INFO - 'XPC_SERVICE_NAME': '0', 11:12:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:12:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:12:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:12:29 INFO - Installing collected packages: mozsystemmonitor 11:12:29 INFO - Running setup.py install for mozsystemmonitor 11:12:29 INFO - Successfully installed mozsystemmonitor 11:12:29 INFO - Cleaning up... 11:12:29 INFO - Return code: 0 11:12:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:29 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:12:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:12:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:29 INFO - 'HOME': '/Users/cltbld', 11:12:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:29 INFO - 'LOGNAME': 'cltbld', 11:12:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:29 INFO - 'MOZ_NO_REMOTE': '1', 11:12:29 INFO - 'NO_EM_RESTART': '1', 11:12:29 INFO - 'PAGER': '/bin/cat', 11:12:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:29 INFO - 'PWD': '/builds/slave/test', 11:12:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:29 INFO - 'SHELL': '/bin/bash', 11:12:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:29 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:29 INFO - 'USER': 'cltbld', 11:12:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:29 INFO - 'XPC_FLAGS': '0x0', 11:12:29 INFO - 'XPC_SERVICE_NAME': '0', 11:12:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:30 INFO - Downloading/unpacking jsonschema==2.5.1 11:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:12:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:31 INFO - Downloading functools32-3.2.3-2.tar.gz 11:12:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:12:31 INFO - warning: no files found matching '*.txt' 11:12:31 INFO - no previously-included directories found matching 'build' 11:12:31 INFO - no previously-included directories found matching 'dist' 11:12:31 INFO - no previously-included directories found matching '.git*' 11:12:31 INFO - Installing collected packages: jsonschema, functools32 11:12:31 INFO - Running setup.py install for functools32 11:12:31 INFO - warning: no files found matching '*.txt' 11:12:31 INFO - no previously-included directories found matching 'build' 11:12:31 INFO - no previously-included directories found matching 'dist' 11:12:31 INFO - no previously-included directories found matching '.git*' 11:12:31 INFO - Successfully installed jsonschema functools32 11:12:31 INFO - Cleaning up... 11:12:32 INFO - Return code: 0 11:12:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:32 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:12:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:32 INFO - 'HOME': '/Users/cltbld', 11:12:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:32 INFO - 'LOGNAME': 'cltbld', 11:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:32 INFO - 'MOZ_NO_REMOTE': '1', 11:12:32 INFO - 'NO_EM_RESTART': '1', 11:12:32 INFO - 'PAGER': '/bin/cat', 11:12:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:32 INFO - 'PWD': '/builds/slave/test', 11:12:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:32 INFO - 'SHELL': '/bin/bash', 11:12:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:32 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:32 INFO - 'USER': 'cltbld', 11:12:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:32 INFO - 'XPC_FLAGS': '0x0', 11:12:32 INFO - 'XPC_SERVICE_NAME': '0', 11:12:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:12:32 INFO - Cleaning up... 11:12:32 INFO - Return code: 0 11:12:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:32 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:12:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:12:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:32 INFO - 'HOME': '/Users/cltbld', 11:12:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:32 INFO - 'LOGNAME': 'cltbld', 11:12:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:32 INFO - 'MOZ_NO_REMOTE': '1', 11:12:32 INFO - 'NO_EM_RESTART': '1', 11:12:32 INFO - 'PAGER': '/bin/cat', 11:12:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:32 INFO - 'PWD': '/builds/slave/test', 11:12:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:32 INFO - 'SHELL': '/bin/bash', 11:12:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:32 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:32 INFO - 'USER': 'cltbld', 11:12:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:32 INFO - 'XPC_FLAGS': '0x0', 11:12:32 INFO - 'XPC_SERVICE_NAME': '0', 11:12:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:32 INFO - Downloading/unpacking blobuploader==1.2.4 11:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:33 INFO - Downloading blobuploader-1.2.4.tar.gz 11:12:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:12:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:12:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:34 INFO - Downloading docopt-0.6.1.tar.gz 11:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:12:34 INFO - Installing collected packages: blobuploader, requests, docopt 11:12:34 INFO - Running setup.py install for blobuploader 11:12:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:12:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:12:35 INFO - Running setup.py install for requests 11:12:35 INFO - Running setup.py install for docopt 11:12:35 INFO - Successfully installed blobuploader requests docopt 11:12:35 INFO - Cleaning up... 11:12:35 INFO - Return code: 0 11:12:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:35 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:12:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:35 INFO - 'HOME': '/Users/cltbld', 11:12:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:35 INFO - 'LOGNAME': 'cltbld', 11:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:35 INFO - 'MOZ_NO_REMOTE': '1', 11:12:35 INFO - 'NO_EM_RESTART': '1', 11:12:35 INFO - 'PAGER': '/bin/cat', 11:12:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:35 INFO - 'PWD': '/builds/slave/test', 11:12:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:35 INFO - 'SHELL': '/bin/bash', 11:12:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:35 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:35 INFO - 'USER': 'cltbld', 11:12:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:35 INFO - 'XPC_FLAGS': '0x0', 11:12:35 INFO - 'XPC_SERVICE_NAME': '0', 11:12:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-PeCXT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-RGfRXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-EstQDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-G_hKLi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-qwyicC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Gn7jlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-r9KujD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-qQ23Xj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ZhbapF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:36 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-dCYgm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-rpEAYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-qY9dIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Xn9eus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-uFDkQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-skz0jZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-g2B3ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:37 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ckP3GB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:12:37 INFO - Running setup.py install for manifestparser 11:12:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:12:37 INFO - Running setup.py install for mozcrash 11:12:38 INFO - Running setup.py install for mozdebug 11:12:38 INFO - Running setup.py install for mozdevice 11:12:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:12:38 INFO - Running setup.py install for mozfile 11:12:38 INFO - Running setup.py install for mozhttpd 11:12:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:12:38 INFO - Running setup.py install for mozinfo 11:12:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:12:38 INFO - Running setup.py install for mozInstall 11:12:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:12:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:12:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:12:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:12:38 INFO - Running setup.py install for mozleak 11:12:39 INFO - Running setup.py install for mozlog 11:12:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:12:39 INFO - Running setup.py install for moznetwork 11:12:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:12:39 INFO - Running setup.py install for mozprocess 11:12:39 INFO - Running setup.py install for mozprofile 11:12:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:12:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:12:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:12:39 INFO - Running setup.py install for mozrunner 11:12:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:12:39 INFO - Running setup.py install for mozscreenshot 11:12:40 INFO - Running setup.py install for moztest 11:12:40 INFO - Running setup.py install for mozversion 11:12:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:12:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:12:40 INFO - Cleaning up... 11:12:40 INFO - Return code: 0 11:12:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:40 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:12:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:12:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:40 INFO - 'HOME': '/Users/cltbld', 11:12:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:40 INFO - 'LOGNAME': 'cltbld', 11:12:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:40 INFO - 'MOZ_NO_REMOTE': '1', 11:12:40 INFO - 'NO_EM_RESTART': '1', 11:12:40 INFO - 'PAGER': '/bin/cat', 11:12:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:40 INFO - 'PWD': '/builds/slave/test', 11:12:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:40 INFO - 'SHELL': '/bin/bash', 11:12:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:40 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:40 INFO - 'USER': 'cltbld', 11:12:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:40 INFO - 'XPC_FLAGS': '0x0', 11:12:40 INFO - 'XPC_SERVICE_NAME': '0', 11:12:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-iOeadk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ZhxYSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:12:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:40 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-hRTFga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ZawGbe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-oB_GiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-QYf35n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-7wJsHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-PRhkm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-z1eZDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-3YPIqc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-kA4hF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:12:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:41 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-EQhCmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:42 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-aS9s0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:42 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-IQ8NTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:42 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-y7gYpP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:42 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ts6v95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:12:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:42 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-aOLth2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:12:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:12:42 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:12:42 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:12:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:43 INFO - Downloading blessings-1.6.tar.gz 11:12:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:12:44 INFO - Installing collected packages: blessings 11:12:44 INFO - Running setup.py install for blessings 11:12:44 INFO - Successfully installed blessings 11:12:44 INFO - Cleaning up... 11:12:44 INFO - Return code: 0 11:12:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:12:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:44 INFO - Reading from file tmpfile_stdout 11:12:44 INFO - Current package versions: 11:12:44 INFO - blessings == 1.6 11:12:44 INFO - blobuploader == 1.2.4 11:12:44 INFO - docopt == 0.6.1 11:12:44 INFO - functools32 == 3.2.3-2 11:12:44 INFO - jsonschema == 2.5.1 11:12:44 INFO - manifestparser == 1.1 11:12:44 INFO - mozInstall == 1.12 11:12:44 INFO - mozcrash == 1.0 11:12:44 INFO - mozdebug == 0.1 11:12:44 INFO - mozdevice == 0.51 11:12:44 INFO - mozfile == 1.2 11:12:44 INFO - mozhttpd == 0.7 11:12:44 INFO - mozinfo == 0.9 11:12:44 INFO - mozleak == 0.1 11:12:44 INFO - mozlog == 3.4 11:12:44 INFO - moznetwork == 0.27 11:12:44 INFO - mozprocess == 0.25 11:12:44 INFO - mozprofile == 0.28 11:12:44 INFO - mozrunner == 6.13 11:12:44 INFO - mozscreenshot == 0.1 11:12:44 INFO - mozsystemmonitor == 0.3 11:12:44 INFO - moztest == 0.8 11:12:44 INFO - mozversion == 1.4 11:12:44 INFO - psutil == 3.1.1 11:12:44 INFO - requests == 1.2.3 11:12:44 INFO - wsgiref == 0.1.2 11:12:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:44 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:12:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:12:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:44 INFO - 'HOME': '/Users/cltbld', 11:12:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:44 INFO - 'LOGNAME': 'cltbld', 11:12:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:44 INFO - 'MOZ_NO_REMOTE': '1', 11:12:44 INFO - 'NO_EM_RESTART': '1', 11:12:44 INFO - 'PAGER': '/bin/cat', 11:12:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:44 INFO - 'PWD': '/builds/slave/test', 11:12:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:44 INFO - 'SHELL': '/bin/bash', 11:12:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:44 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:44 INFO - 'USER': 'cltbld', 11:12:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:44 INFO - 'XPC_FLAGS': '0x0', 11:12:44 INFO - 'XPC_SERVICE_NAME': '0', 11:12:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:12:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:12:44 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:46 INFO - Downloading marionette_driver-1.4.0.tar.gz 11:12:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 11:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:12:46 INFO - Installing collected packages: marionette-driver 11:12:46 INFO - Running setup.py install for marionette-driver 11:12:46 INFO - Successfully installed marionette-driver 11:12:46 INFO - Cleaning up... 11:12:46 INFO - Return code: 0 11:12:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:46 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 0x10a714c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a718768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff31a566ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, '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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:12:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:12:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:12:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:46 INFO - 'HOME': '/Users/cltbld', 11:12:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:46 INFO - 'LOGNAME': 'cltbld', 11:12:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:46 INFO - 'MOZ_NO_REMOTE': '1', 11:12:46 INFO - 'NO_EM_RESTART': '1', 11:12:46 INFO - 'PAGER': '/bin/cat', 11:12:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:46 INFO - 'PWD': '/builds/slave/test', 11:12:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:46 INFO - 'SHELL': '/bin/bash', 11:12:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:12:46 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:12:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:46 INFO - 'USER': 'cltbld', 11:12:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:46 INFO - 'XPC_FLAGS': '0x0', 11:12:46 INFO - 'XPC_SERVICE_NAME': '0', 11:12:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:12:46 INFO - Cleaning up... 11:12:46 INFO - Return code: 0 11:12:46 INFO - Running post-action listener: _resource_record_post_action 11:12:46 INFO - Running post-action listener: _start_resource_monitoring 11:12:46 INFO - Starting resource monitoring. 11:12:46 INFO - [mozharness: 2017-05-30 18:12:46.831964Z] Finished create-virtualenv step (success) 11:12:46 INFO - [mozharness: 2017-05-30 18:12:46.832901Z] Running install step. 11:12:46 INFO - Running pre-action listener: _resource_record_pre_action 11:12:46 INFO - Running main action method: install 11:12:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:47 INFO - Reading from file tmpfile_stdout 11:12:47 INFO - Detecting whether we're running mozinstall >=1.0... 11:12:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:12:47 INFO - Reading from file tmpfile_stdout 11:12:47 INFO - Output received: 11:12:47 INFO - Usage: mozinstall [options] installer 11:12:47 INFO - Options: 11:12:47 INFO - -h, --help show this help message and exit 11:12:47 INFO - -d DEST, --destination=DEST 11:12:47 INFO - Directory to install application into. [default: 11:12:47 INFO - "/builds/slave/test"] 11:12:47 INFO - --app=APP Application being installed. [default: firefox] 11:12:47 INFO - mkdir: /builds/slave/test/build/application 11:12:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:12:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:13:08 INFO - Reading from file tmpfile_stdout 11:13:08 INFO - Output received: 11:13:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:13:08 INFO - Running post-action listener: _resource_record_post_action 11:13:08 INFO - [mozharness: 2017-05-30 18:13:08.563199Z] Finished install step (success) 11:13:08 INFO - [mozharness: 2017-05-30 18:13:08.563341Z] Running setup-mitmproxy step. 11:13:08 INFO - Running pre-action listener: _resource_record_pre_action 11:13:08 INFO - Running main action method: setup_mitmproxy 11:13:08 INFO - Skipping: mitmproxy is not required 11:13:08 INFO - Running post-action listener: _resource_record_post_action 11:13:08 INFO - [mozharness: 2017-05-30 18:13:08.563984Z] Finished setup-mitmproxy step (success) 11:13:08 INFO - [mozharness: 2017-05-30 18:13:08.564089Z] Running run-tests step. 11:13:08 INFO - Running pre-action listener: _resource_record_pre_action 11:13:08 INFO - Running main action method: run_tests 11:13:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:13:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:13:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:13:08 INFO - 2017-05-30 11:13:08.579 screenresolution[1624:7392] starting screenresolution argv=screenresolution get 11:13:08 INFO - 2017-05-30 11:13:08.581 screenresolution[1624:7392] Display 0: 1600x1200x32@60 11:13:08 INFO - 2017-05-30 11:13:08.587 screenresolution[1625:7394] starting screenresolution argv=screenresolution list 11:13:08 INFO - Available Modes on Display 0 11:13:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:13:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:13:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:13:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:13:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:13:08 INFO - Intel Iris: 11:13:08 INFO - Chipset Model: Intel Iris 11:13:08 INFO - Type: GPU 11:13:08 INFO - Bus: Built-In 11:13:08 INFO - VRAM (Dynamic, Max): 1536 MB 11:13:08 INFO - Vendor: Intel (0x8086) 11:13:08 INFO - Device ID: 0x0a2e 11:13:08 INFO - Revision ID: 0x0009 11:13:08 INFO - Displays: 11:13:08 INFO - Display: 11:13:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:13:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:13:08 INFO - Display Serial Number: 1600x1200 60 11:13:08 INFO - Main Display: Yes 11:13:08 INFO - Mirror: Off 11:13:08 INFO - Online: Yes 11:13:08 INFO - Rotation: Supported 11:13:08 INFO - Return code: 0 11:13:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:13:08 INFO - Python 2.7.3 11:13:08 INFO - Return code: 0 11:13:08 INFO - grabbing minidump binary from tooltool 11:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:13:08 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 0x10a69dc60>, '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 0x10a432cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a45bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:13:08 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:13:08 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:13:08 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:13:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:13:08 INFO - Return code: 0 11:13:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:13:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:13:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:13:08 INFO - ENV: RUST_BACKTRACE is now 1 11:13:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:13:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:13:08 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/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:13:08 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/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0358 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:13:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5uxOhoyzQW/Render', 11:13:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:13:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:13:08 INFO - 'HOME': '/Users/cltbld', 11:13:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:13:08 INFO - 'LOGNAME': 'cltbld', 11:13:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:13:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:13:08 INFO - 'MOZ_NO_REMOTE': '1', 11:13:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:13:08 INFO - 'NO_EM_RESTART': '1', 11:13:08 INFO - 'PAGER': '/bin/cat', 11:13:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:13:08 INFO - 'PWD': '/builds/slave/test', 11:13:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:13:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:13:08 INFO - 'RUST_BACKTRACE': '1', 11:13:08 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:13:08 INFO - 'SHELL': '/bin/bash', 11:13:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJNJbC9vTi/Listeners', 11:13:08 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 11:13:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:13:08 INFO - 'USER': 'cltbld', 11:13:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:13:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:13:08 INFO - 'XPC_FLAGS': '0x0', 11:13:08 INFO - 'XPC_SERVICE_NAME': '0', 11:13:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:13:08 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/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:13:09 INFO - mozversion application_buildid: 20170530091722 11:13:09 INFO - mozversion application_changeset: c3cd8c58fa87d571874f2bcc34ec0864ac7acd5f 11:13:09 INFO - mozversion application_display_name: Nightly 11:13:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:13:09 INFO - mozversion application_name: Firefox 11:13:09 INFO - mozversion application_remotingname: firefox 11:13:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:13:09 INFO - mozversion application_vendor: Mozilla 11:13:09 INFO - mozversion application_version: 55.0a1 11:13:09 INFO - mozversion platform_buildid: 20170530091722 11:13:09 INFO - mozversion platform_changeset: c3cd8c58fa87d571874f2bcc34ec0864ac7acd5f 11:13:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:13:09 INFO - mozversion platform_version: 55.0a1 11:13:09 INFO - using testdate: 1496167989 11:13:09 INFO - actual date: 1496167989 11:13:09 INFO - starting webserver on 'localhost:49226' 11:13:09 INFO - SUITE-START | Running 2 tests 11:13:09 INFO - TEST-START | dromaeo_css 11:13:09 INFO - operating with platform_type : mac_ 11:13:09 INFO - Initialising browser for dromaeo_css test... 11:13:09 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpLOvvAv/profile 11:13:09 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 11:13:43 INFO - TEST-INFO | 1635: killed by SIGTERM 11:13:43 INFO - Browser initialized. 11:13:43 INFO - Running cycle 1/1 for dromaeo_css test... 11:13:43 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpLOvvAv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:13:45 INFO - PID 1645 | 2017-05-30 11:13:45.398 plugin-container[1646:7986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 11:13:45 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:13:45 INFO - PID 1645 | 2017-05-30 11:13:45.400 plugin-container[1646:7986] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a1f, name = 'com.apple.CFPasteboardClient' 11:13:45 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:13:45 INFO - PID 1645 | 2017-05-30 11:13:45.400 plugin-container[1646:7986] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:13:46 INFO - PID 1645 | 2017-05-30 11:13:46.099 plugin-container[1647:8028] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 11:13:46 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:13:46 INFO - PID 1645 | 2017-05-30 11:13:46.100 plugin-container[1647:8028] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471f, name = 'com.apple.CFPasteboardClient' 11:13:46 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:13:46 INFO - PID 1645 | 2017-05-30 11:13:46.100 plugin-container[1647:8028] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:13:55 INFO - PID 1645 | RSS: Main: 261734400 11:13:55 INFO - PID 1645 | 11:16:57 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 11:16:58 INFO - PID 1645 | RSS: Main: 268861440 11:16:58 INFO - PID 1645 | 11:20:00 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 11:20:01 INFO - PID 1645 | RSS: Main: 281858048 11:20:01 INFO - PID 1645 | 11:23:03 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 11:23:04 INFO - PID 1645 | RSS: Main: 282640384 11:23:04 INFO - PID 1645 | 11:26:06 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 11:26:06 INFO - PID 1645 | RSS: Main: 286343168 11:26:06 INFO - PID 1645 | 11:29:09 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 11:29:10 INFO - PID 1645 | RSS: Main: 286441472 11:29:10 INFO - PID 1645 | 11:32:11 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 11:32:12 INFO - PID 1645 | RSS: Main: 293371904 11:32:12 INFO - PID 1645 | 11:32:12 INFO - PID 1645 | __start_tp_report 11:32:12 INFO - PID 1645 | _x_x_mozilla_page_load 11:32:12 INFO - PID 1645 | _x_x_mozilla_page_load_details 11:32:12 INFO - PID 1645 | |i|pagename|runs| 11:32:12 INFO - PID 1645 | |0;dojo.html;2761.0888406825743;3538.1508437974894;3555.5733312002562;3563.376409128402;3601.9819900900493;5973.522118230548;6111.236095488064;6117.480014198798;6121.6939153042285;6208.255009398873;6107.128129456014;6131.468419844962;6142.897836592515;6176.75292988281;6204.472619827309;5361.544268737142;5388.838334849961;5416.756245968922;5440.183972404131;5443.237946687447;5483.067878460652;5504.4770746779195;5521.834344969658;5523.889522209553;5558.888822223553;5324.946750532484;5398.2982212312545;5429.185622156708;5468.835934921959;5513.393526712059;5384;5413.296271484721;5416.485433883775;5432.837014889559;5441;4443.488998765151;5386.91919621202;5391.406945236021;5439.0209762242785;5453.890922181574;6117.388261173847;6149.985252433343;6226.564140510166;6229.688515574203;6232.065190221503;6522.7390904363765;6533.379328963789;6644.60132392058;6668.299828517985;6684.933150668479;6706.597604143767;6709.2284387295595;6716.966415167942;6718.932810671929;6762.425193858528;6287.182666253358;6299.779507717208;6306.3378345274;6314.147590075327;6326.746930122744;6660.234073081658;6727.730890764315;6789.117414736152;6866.725330986705;6909.7581584644295;6273.905891411632;6405.871882562323;6523.934760652332;6558.475321974326;6563.310852360529;6628.83427914306;6672.065910772496;6687.230968438692;6708.865822683519;6711.865762684719;6403.775867844603;6598.7030583623855;6605;6622;6623.271440141581;6478.805635830932;6533.444657204096;6543.345665433419;6548.246951600627;6568.737250510022;5299.5230429261555;5311.176767601028;5318.574514038867;5322.92015619766;5328.973355133199;4712.151812673752;4725.385699858996;4726.976365118152;4728.432588089451;4790.640701947368;4924.852254432372;5194.68831870089;6129.389354848411;6235.625862448268;6301.77943771975;6653.500987425962;6677;6708.6310252936555;6714.328567143344;6733.966330168416;6327.557071005078;6335.429811317003;6337.176167098248;6340.936590634127;6353.745850165941;5611.3266408031295;5746.166805813134;5756.798512052058;5854.765809367663;5858.8242352729485;6764.323567643295;6851.520393572402;6851.8972215415815;6870.141232345957;6896.655167241517;6733.66331683404;6737.191537015589;6751.696173672099;6755.560888542229;6767.966160169168;6655.567388119756;6818.18181818185;6881.13985751781;6898.344657257552;6910.101686780888;5529.198266251451;6462.515311351762;6505.544611877123;6525.314841941527;6611.768588099586;6674.799756007327;6692.230393504809;6710.83222919431;6730.663466826737;6731.798046058626;6274.905876411949;6284.214473190851;6366.713497892699;6461;6486.935130648633;6470.48236141117;6478.643674597943;6481.416672499309;6486.772962946275;6613;6331.303556608861;6333.954897442055;6338.176037115146;6338.302786693368;6360.173177486897;6293.024581189923;6364.331745166876;6394.744210231539;6450.8064758057335;6476.028595710681;5586.497215250647;6258.061290806416;6290.21372328459;6307.495400367868;6336.936630633634 11:32:12 INFO - PID 1645 | |1;ext.html;667.412676844377;774.9496282741621;831.3224721851691;1255.667248179232;1327.256736227712;39330;40251.39622905659;40355.19289614209;40365.59634403655;40396.99007524813;39867.803965880994;40320.79839600805;40362.7981860091;40439.79780101095;40460.98847528813;7877.6848926042885;8238.835223295531;8484.830303393928;8490.363222758288;8507.064222935473;8362.58187090646;8477.237048665618;8482.406231563793;8486;8495.10801365857;8449.859268998698;8503.149685031509;8503.744887653349;8504.319654427662;8510;8398.328133749286;8400.537970411626;8458.534780587066;8460.280876125506;8462.323014158852;8208.958955205186;8237;8243.670253189865;8279.172082791732;8295.502269863828;37557.24885502275;37590.62409375926;37692;37793;37799.81100094482;3190.7287880530184;3580;3622.6920711739535;3623.2572322673786;3630.2013557017412;30768.230794230098;30937.381252375053;30958.84520577405;30967.35489676544;31013.689863101306;3075.338802157547;3098.256418459576;3100.3954228925368;3119.750419966397;3122.281875168724;243202;248253;279855;293812;295463;48113.278300825514;48553.78615534569;48565.5143448561;48640.27039594409;48724.78188045257;50241.497585024415;50359.49640503621;50468.48594542169;53089;53674.19488707672;14443.85556144425;14495.927520362331;14595.416183352547;14598.051126676732;14617.415303387745;11573.88426115745;11666.358350290815;11683.065354771597;11692.064634829194;11724.70688232801;11277.661670149908;13571.457141714418;13746.793798093036;13751.518696845762;13875.930620347035;5180;5190.688558686491;5467.535259502907;5893.116032595145;6089.939100609025;883.5008220355479;995.4823491784232;1014.7260239735231;1024.9846252306222;1046.9685909422728;23509.177178798658;24151.894177989783;24756;24884.004639814204;24901.87549062243;1025.7281820317621;1121.4673030310537;1135.233717240859;1164.458526785032;1172.3141961952183;11377.089832813355;12622.81065784014;12744.299063551409;12778.361081945866;12842.743145137232;326361;327326;327811.36094319855;328509;328614;45874.5412545877;55674;55705.44294557084;55745.72127139419;55802.72098639562;1507.6080219143103;1539.1611571693454;1545.5672411724736;1545.6445017646004;1545.7604071368955;21705.782942170375;22863.771362286163;24363.147289845478;24375.390615234763;24418.51162976766;39028;39152.629657961836;39228;39286.80356598199;39324;272542;272597;272669;272694;273610;35335.293294134484;35707.32195085431;35985.92042238737;36059.459108112875;36090.63909360978;45338.773306133255;45914.540854591025;45917.54082459266;45995.31007034964;46191.61425157251;28456.008557001234;29447.705522944496;30903.845480772452;30969;31134.688653114084;24733;30530;31111.730758697646;31246;31403.371932561677;37088;37710.81144594259;37739.81130094332;37807.24385512329;37839;28460.857695711387;29151.979680711072;29213.269668258465;30787.38425231438;31433.8428307857;22728.09087636476;24310.75689243085;24317;24402.87798561067;24785.380365491008 11:32:12 INFO - PID 1645 | |2;jquery.html;424.40522982990035;424.80883602378935;429.3302448180838;430.9288967320392;439.52092219480767;21213.99999999998;21602.13591456342;22276.88615569223;22939.655905161435;23099.42251443715;22726.77273227267;22894.65658015127;23857.403564910885;24059.278221653378;24110;5970.0746384310505;6216.564840461169;6220.191375121228;6223.159873417076;6236.937630623704;5258.039904054741;5873.207117039187;6005.12925625784;6055;6101.664408457533;5988.730507127189;5990.580659353847;6005.939940600603;6025.307089684698;6038.184845045928;5799.65202087874;6001.579889407722;6020.8494787630225;6029.577929544934;6067.453929146398;4899.7550122494085;5390;5406.91889621656;5420.620556561043;5422.132458806612;26985;27840.721592784015;28110.718892811015;28309.292267693472;28561.285967850763;2124.3308357867377;2183.6069507488646;2186.9015894284794;2189.4745261137214;2190.452386903274;17920.641587168182;17960.730589041174;17975.910120449313;18318;18552.907235463736;2122.734658167729;2160.0711693971584;2182.4216582605623;2184.421128400975;2214.1143542582936;164515;165590;167003;169123;173266.1336693321;10609.893901061045;12420.130590858553;12427.254364738144;12524.81212781809;13006.739865202831;13439.260840653858;16499.917500412423;16704.582385440463;16709.91645041767;16729.665406691798;8803.471791692518;9053.366264361432;9091.045440228812;9122.81754364909;9317.72046838596;7586.786114221698;8597.656093756179;8618.39671223021;8680.392372533988;8799.692010779592;8095.311898488694;8201.179882011751;8214.424990250625;8242.670293188205;8275.462094963808;1905.6665083610312;2257.932262032142;2261.2311813983324;2262.9434264143197;2424.2121310574134;931.2736065868634;959.7504648791364;962.821877952581;965.8840939087214;995.2386424385351;20357.287494937606;20941.581168376546;20958.056887440103;21070.683939740917;21233;938.807544453399;955.8757361543094;973.9026097390346;987.1559816357055;987.8666380038746;10640.201984851012;10845.457727113613;10860.945695271474;10866;10869.29349592274;541555;545695;547267;547570.4079234838;554841;131657;133586;136157;137854.3107284457;139452.60547394396;1156.450685924196;1170.5434880396651;1220.2373516552154;1221.1940119521073;1222.1567118688076;21577.460563486038;21773.12907483683;21901.89049054808;22133.557328853654;22175.347395781522;60157.09764353626;60231.39768602258;60416.69791651014;60623.69688151531;60675.69662151838;79502.60248698719;80208.19791802007;80525;81236;81870.77193841978;7233.891491627735;8792.516411597424;8907.910920890708;8971.820563588823;9214.170724634814;14113.788293175405;14288.857111428753;14397.64005899819;14484.493042743454;14882.18148001827;11810.76378472443;11909.22590031645;12119.394030298274;12297.508099675913;12316;9977.551010204703;11272.774544509228;11561.017313528444;11664.241824281395;11679.124065694938;11987.22083064598;12166.452509637265;12355.567555135527;12377.443015064524;12410.689732756753;8152.592370381576;8189.894364260748;8333.33333333344;8677.913220867964;8917.821643566962;8601.526916019679;11920.794652989225;12056.276623402624;12161.817572736592;12316.13787034899 11:32:12 INFO - PID 1645 | |3;mootools.html;1374.5395292576989;1455.468753904825;1456.4174330267892;1460.0436713952365;1463.3488097796485;11675.124365672566;11798.23311484753;12098.334591597459;12102.939485302582;12113.878861211408;11842.881571184285;11910.40447977602;12060.21608595443;12070.637880863565;12092.03263738901;2513.1455305196228;2565.4997275531277;2565.5638541447906;2566.781823545001;2573.3530540454126;2505.110685706575;2545.592705167174;2546.5798143306424;2548.248266761301;2553;2233.6426171812514;2511.824172307939;2521.7982561395042;2522.230719630512;2530.6710127683377;2461.90152393904;2487.2911220302217;2496.414776616843;2503.662005629235;2509.397744541315;2445.5475736988715;2472.505498900209;2482.900683972639;2542.8855701493294;2547.3249588858966;9872.654457094039;11660.416979151008;11780.410979450993;11793.882061179364;11796;6357.841053973688;6610.7355705771715;6631.86736265272;6636.8008959730805;6639.634820084893;4092.856750013735;4273.465816772903;4323.200207961537;4329.2423825830665;4336.522982471925;1076.6339444588878;1202.699325168708;1231.8029115341503;1234.6234398508452;1253.573784913134;15379.846201537954;15777.526674199678;16067.598310042225;16185.514434566981;16320.428784992468;5401.297831281987;5412.404635490093;5521.061419558685;5602.971985140048;5603.2435621191435;11799;11806.291622502677;11826.935575798047;11855.46650400734;11895.524179032915;6210.627362358273;6373.075903993895;6379.808605741835;6386.808395748135;6388.39310265524;5956.6723830188885;5971.19388882504;6081.118237855486;6108.725107370179;6188.504919606421;4287.164003019444;4344.326629372453;4403.977980110143;4428.778561071934;4439.156560253541;1250.9061820363509;1272.0968112639944;1275.1074248026418;1288.0790234982032;1289.9871001290055;633;768.5657603454031;770.4568279363035;784.8704963681043;786.3866184376081;6054.939450605526;6073.392660733891;6278.246610406781;6286.937130628635;6329.461995730322;580.0226618148433;588.7821506042792;608.4736702752176;612.4732729852324;615.5844804756771;3610.3140403323287;3826.598207188261;3955.1891862168186;4257.957420425818;4380;13570.660733481545;13877.306134693225;14634.414623415158;15147.621309467351;15216.239188040554;14701.95696183558;14809.407623695148;14910.627234319229;15123.848761512245;15139.318730657147;8315.459495132918;8499.830003399897;8541.615627296787;8553.23020928107;8571;6487.740490380332;6948.652567371712;7051.365377116084;7099.432045436456;7182.60495672743;12020.459079341626;13725.382357793724;13796.793048104488;13804.792928106288;13869.890408767073;11293.153013523854;13791.586252412444;13963.860361396662;13995.51015714445;14054.718905622034;9357.812843743222;9443.433393996382;9543.093406126407;9562.521873906417;9579.664711735057;9433.52832358393;10728;10845.511951962339;10885.891141088488;10886.020258176803;9013.414128081653;9303.069693030642;9366.95316523413;9375.437473751597;9492.810143797224;9074.90925090767;9087.727368178965;9121.407108537924;9172.403793753385;9203.171714545722;7724.575148366894;8183.427160098974;8302.460340078118;8311.044229913638;8314.667413303641;6003.7898673546215;6015.639061656315;6080.72636731357;6169.6915154241215;6223.190985171898;6000.459958603747;6060.8181404625675;6129.969350153221;6179.84550386226;6339.334369891095 11:32:12 INFO - PID 1645 | |4;prototype.html;380.5889639189675;386.4203694458312;398.7726995612502;409.7766717139159;410.42540443379266;6032.245969253843;6238.477104099531;6282.403171698681;6287.491002159483;6347.49220062395;5932.902413053588;6019.398060193989;6054.788082417116;6059.394060593939;6069.42340477655;4462.464504259493;4462.955370446302;4471.508134105254;4492.663050271226;4507.301368287921;3662.6997415917367;4379.2336341140335;4413.9185899454515;4428.933565996513;4470.642348612119;3746.288205241009;4405.383246345515;4408.515063343029;4413;4432.290833466646;3984.681225501967;4327.480702315742;4394.516603173648;4400.559944005574;4478.328250762379;3183.4747266700965;3345.8327083645963;3389.7288216942834;3689.3728066228805;3774.1885494618514;5898.702285497184;5899.528037756969;5929.070929070929;5931.932252194603;5986.551008674323;4366.978165109186;4397.360784372486;4854.635902307341;5301.443348448396;5311.362636483608;5930.75454154628;6250.249970003583;6358.566374157949;6534.607923524603;6575.375339342755;3776.7356285060055;3986.740861843971;4151.14901445203;4280.614744672995;4296.0548679290505;5585.184814935163;6390.360963903666;6614.7023383947835;6641.601503909781;6853.965730171317;4226.197022565702;4393.846215382447;4412.845550405785;4422.933655995163;4448.799804008828;4397.582229688174;4483;4511.8195272189405;4513.11994161142;4520.61574766142;4252.383404406406;4574.428196475477;4802.543758342952;5298.470152984748;5300.337457817773;5418.588728015995;5718.826845798653;6076.422739839708;6577.6053436793945;6671.5997040177745;1173.5833779008499;1523.3449616664718;1524.2226464503183;1531.5328824708347;1534.171547364433;955.4921176805325;1003.6136087606324;1009.4750729620556;1012.9088382045651;1016.8830584482731;5105.795768169231;6260.968695156586;6275.3724627536885;6284;6318.4313411793155;5710.885782284331;6797.150356205474;6800.387965083068;6819.624920629314;6843.863122737517;5572.777088916398;5575.526080283222;5963.612365196248;6174.646873953851;6235.657038862906;2891.43616994684;3059.938801223963;3312.271300313927;3437.1407148212948;3603.2613314271034;6189.938100618936;6924.896126558106;7026.789196324118;7056.541324813973;7069.505134640629;6669.466442684574;7027.297270273034;7034.8944765828555;7071.25751796054;7091.89362159568;5694.743736531866;6098.573099883054;6420.357964203542;7115.26062465944;7208.603526805866;5989.382866626073;6607.00894865774;6887.931120688729;6913.792586222421;6950.965245173944;6070.969645151923;6666.999850067392;6674.432673222636;6760.661966901734;6770.729170833111;6256.343083976299;6530.20030277736;6994.230634630085;7050.330218629222;7111.573305601681;3883.4563161158026;4134.875953721393;4398.450193725785;4406.713563618355;4423.424954755861;6522.825891339605;6576.605403675603;6874.587524748531;6911.274316196727;6944.708246278817;6134.191047936697;6478.838029049311;6539.869202616016;6631.403173714389;6649.800505984828;5630.634008789416;6605.14133162705;6617.536772425884;6626.536142470173;6640.568363056386;6353.332900045429;6382.425581697669;6415.518836087219;6447.806565803034;6464.095026696173;5776.306843178845;6002.549808764447;6538.934610653832;6602.966985165043;6639.900401493885;5610.410906854885;6238.220222472191;6397.648129352744;6403.64779937108;6421.903671444838 11:32:12 INFO - PID 1645 | |5;yui.html;746.2649290448907;800.6797281087568;809.5711069961519;812.3379445751712;850.8340873529664;14451;14490;15062.623434414145;17152.571185720364;17453.04008279544;16210.513684589452;18104.869955691553;18139.365122220726;18242.452726418196;18347.449576512692;974.6734843827309;985.7289245457484;997.7854761226334;1000.5447521377756;1008.621766837436;1017.0846238385475;1017.9464254496597;1020.505055048301;1021.3055122516686;1021.6322124035341;862.8705694145897;949.9835176361296;1046.911012563933;1047.010575006619;1073.1468482556386;1038.7091614347994;1039.3555995282973;1039.433508737736;1039.6517166749147;1049.3074570783285;761.958092304923;763.3320844261272;763.5075376382258;767.2672597669268;771.918948510404;16880.915595422066;17380.913095434444;17563.473095807145;17578;17578.472645820646;15275.770863437057;17375.73936390955;17416.564585885328;17918.731219031724;17941.102944852704;12351.938240308831;14375.209363485004;14634.195119268436;14712.411503539846;14777.187254700988;3557.839897204632;3566.4115420955773;3578.6600272974288;3582.964170358315;3588.4796704477967;136046.3197684005;139447.60552394448;140585;141171;141426;12730.10889237763;12821.781930716566;13818.86181138196;14201.502947396999;14295.28523573838;26116;26792.59811102835;27141.864290678815;27171.45657086847;27246;7212.459065570103;7774.689012439552;7900.644470998704;8030.799230019179;8044.1955804419085;3380.3408335374365;3440.2947395783804;3879.86420475282;3883.786391748481;3895.922081558353;4073.5111786585207;4289.678274129453;4307.956920430818;4315.244832154361;4315.611594956406;750.5684231566871;807.6809660184194;910.6357457017247;933.9486328252011;935.7567032571444;612.6293592376549;615.1449485215554;630.7098734582052;648.2447948140364;653.9574927629689;11185.888141118485;12280.07899407548;12366.319852408204;12406.317652529016;12419.44112514921;618.8700372921737;628.434409031875;630.9211348581428;643.5398689936718;667.4160109903834;5547.916781248285;5702.372738998707;5704.3154821421695;5802.3037235531165;5828.795992140254;98206.59863707422;138788.6121138796;139394;141411;144354;96792.03207967972;117456;118443.40778296054;119064;124099;94419.52790236005;95095;95621;96729.99999999859;97211.51394243125;8887.644494220418;10270.178385728974;10673.039426451656;11144.832827507431;11257;33694.49458258079;41956.58043419527;42318.1536369277;42769.93075173146;42987.78506107449;57508;57964.13053804281;58169.7647211753;58176;58194;14400.711985760017;14557.781633275297;14801.111933284037;14827.481038163613;14851.034682745587;15373.461928832437;15555.41250513706;16285.104319262555;16338.754918676466;16441.67116657636;12474.251544907334;14483.493077742229;14608.780868287196;14658.853411466176;14844.62888427798;14727.337269823098;15155.318010689325;15231.695366092838;15346.386144554093;15409.76885346743;13926.791098133333;14499.130052196902;14567.34446949911;14597.854021460074;16175.352985880432;9885.901140988499;11229.38238396896;11250.493727782434;11433.828492572451;11463.254888432226;11213;13527.661808454868;13577.660558485723;13610.523631672844;13661.658458538219 11:32:12 INFO - PID 1645 | __end_tp_report 11:32:12 INFO - PID 1645 | __start_cc_report 11:32:12 INFO - PID 1645 | _x_x_mozilla_cycle_collect,461 11:32:12 INFO - PID 1645 | __end_cc_report 11:32:12 INFO - PID 1645 | __startTimestamp1496169132175__endTimestamp 11:32:12 INFO - PID 1645 | 11:32:12 INFO - PID 1645 | ------- Summary: start ------- 11:32:12 INFO - PID 1645 | Number of tests: 6 11:32:12 INFO - PID 1645 | 11:32:12 INFO - PID 1645 | [#0] dojo.html Cycles:165 Average:6102.49 Median:6335.43 stddev:744.79 (11.8%) stddev-sans-first:699.43 11:32:12 INFO - PID 1645 | Values: 2761.1 3538.2 3555.6 3563.4 3602.0 5973.5 6111.2 6117.5 6121.7 6208.3 6107.1 6131.5 6142.9 6176.8 6204.5 5361.5 5388.8 5416.8 5440.2 5443.2 5483.1 5504.5 5521.8 5523.9 5558.9 5324.9 5398.3 5429.2 5468.8 5513.4 5384.0 5413.3 5416.5 5432.8 5441.0 4443.5 5386.9 5391.4 5439.0 5453.9 6117.4 6150.0 6226.6 6229.7 6232.1 6522.7 6533.4 6644.6 6668.3 6684.9 6706.6 6709.2 6717.0 6718.9 6762.4 6287.2 6299.8 6306.3 6314.1 6326.7 6660.2 6727.7 6789.1 6866.7 6909.8 6273.9 6405.9 6523.9 6558.5 6563.3 6628.8 6672.1 6687.2 6708.9 6711.9 6403.8 6598.7 6605.0 6622.0 6623.3 6478.8 6533.4 6543.3 6548.2 6568.7 5299.5 5311.2 5318.6 5322.9 5329.0 4712.2 4725.4 4727.0 4728.4 4790.6 4924.9 5194.7 6129.4 6235.6 6301.8 6653.5 6677.0 6708.6 6714.3 6734.0 6327.6 6335.4 6337.2 6340.9 6353.7 5611.3 5746.2 5756.8 5854.8 5858.8 6764.3 6851.5 6851.9 6870.1 6896.7 6733.7 6737.2 6751.7 6755.6 6768.0 6655.6 6818.2 6881.1 6898.3 6910.1 5529.2 6462.5 6505.5 6525.3 6611.8 6674.8 6692.2 6710.8 6730.7 6731.8 6274.9 6284.2 6366.7 6461.0 6486.9 6470.5 6478.6 6481.4 6486.8 6613.0 6331.3 6334.0 6338.2 6338.3 6360.2 6293.0 6364.3 6394.7 6450.8 6476.0 5586.5 6258.1 6290.2 6307.5 6336.9 11:32:12 INFO - PID 1645 | 11:32:12 INFO - PID 1645 | [#1] ext.html Cycles:180 Average:44631.02 Median:181974.65 stddev:76601.82 (42.1%) stddev-sans-first:76745.59 11:32:12 INFO - PID 1645 | Values: 667.4 774.9 831.3 1255.7 1327.3 39330.0 40251.4 40355.2 40365.6 40397.0 39867.8 40320.8 40362.8 40439.8 40461.0 7877.7 8238.8 8484.8 8490.4 8507.1 8362.6 8477.2 8482.4 8486.0 8495.1 8449.9 8503.1 8503.7 8504.3 8510.0 8398.3 8400.5 8458.5 8460.3 8462.3 8209.0 8237.0 8243.7 8279.2 8295.5 37557.2 37590.6 37692.0 37793.0 37799.8 3190.7 3580.0 3622.7 3623.3 3630.2 30768.2 30937.4 30958.8 30967.4 31013.7 3075.3 3098.3 3100.4 3119.8 3122.3 243202.0 248253.0 279855.0 293812.0 295463.0 48113.3 48553.8 48565.5 48640.3 48724.8 50241.5 50359.5 50468.5 53089.0 53674.2 14443.9 14495.9 14595.4 14598.1 14617.4 11573.9 11666.4 11683.1 11692.1 11724.7 11277.7 13571.5 13746.8 13751.5 13875.9 5180.0 5190.7 5467.5 5893.1 6089.9 883.5 995.5 1014.7 1025.0 1047.0 23509.2 24151.9 24756.0 24884.0 24901.9 1025.7 1121.5 1135.2 1164.5 1172.3 11377.1 12622.8 12744.3 12778.4 12842.7 326361.0 327326.0 327811.4 328509.0 328614.0 45874.5 55674.0 55705.4 55745.7 55802.7 1507.6 1539.2 1545.6 1545.6 1545.8 21705.8 22863.8 24363.1 24375.4 24418.5 39028.0 39152.6 39228.0 39286.8 39324.0 272542.0 272597.0 272669.0 272694.0 273610.0 35335.3 35707.3 35985.9 36059.5 36090.6 45338.8 45914.5 45917.5 45995.3 46191.6 28456.0 29447.7 30903.8 30969.0 31134.7 24733.0 30530.0 31111.7 31246.0 31403.4 37088.0 37710.8 37739.8 37807.2 37839.0 28460.9 29152.0 29213.3 30787.4 31433.8 22728.1 24310.8 24317.0 24402.9 24785.4 11:32:12 INFO - PID 1645 | 11:32:12 INFO - PID 1645 | [#2] jquery.html Cycles:180 Average:36376.15 Median:24084.64 stddev:93613.11 (388.7%) stddev-sans-first:93836.80 11:32:12 INFO - PID 1645 | Values: 424.4 424.8 429.3 430.9 439.5 21214.0 21602.1 22276.9 22939.7 23099.4 22726.8 22894.7 23857.4 24059.3 24110.0 5970.1 6216.6 6220.2 6223.2 6236.9 5258.0 5873.2 6005.1 6055.0 6101.7 5988.7 5990.6 6005.9 6025.3 6038.2 5799.7 6001.6 6020.8 6029.6 6067.5 4899.8 5390.0 5406.9 5420.6 5422.1 26985.0 27840.7 28110.7 28309.3 28561.3 2124.3 2183.6 2186.9 2189.5 2190.5 17920.6 17960.7 17975.9 18318.0 18552.9 2122.7 2160.1 2182.4 2184.4 2214.1 164515.0 165590.0 167003.0 169123.0 173266.1 10609.9 12420.1 12427.3 12524.8 13006.7 13439.3 16499.9 16704.6 16709.9 16729.7 8803.5 9053.4 9091.0 9122.8 9317.7 7586.8 8597.7 8618.4 8680.4 8799.7 8095.3 8201.2 8214.4 8242.7 8275.5 1905.7 2257.9 2261.2 2262.9 2424.2 931.3 959.8 962.8 965.9 995.2 20357.3 20941.6 20958.1 21070.7 21233.0 938.8 955.9 973.9 987.2 987.9 10640.2 10845.5 10860.9 10866.0 10869.3 541555.0 545695.0 547267.0 547570.4 554841.0 131657.0 133586.0 136157.0 137854.3 139452.6 1156.5 1170.5 1220.2 1221.2 1222.2 21577.5 21773.1 21901.9 22133.6 22175.3 60157.1 60231.4 60416.7 60623.7 60675.7 79502.6 80208.2 80525.0 81236.0 81870.8 7233.9 8792.5 8907.9 8971.8 9214.2 14113.8 14288.9 14397.6 14484.5 14882.2 11810.8 11909.2 12119.4 12297.5 12316.0 9977.6 11272.8 11561.0 11664.2 11679.1 11987.2 12166.5 12355.6 12377.4 12410.7 8152.6 8189.9 8333.3 8677.9 8917.8 8601.5 11920.8 12056.3 12161.8 12316.1 11:32:12 INFO - PID 1645 | 11:32:12 INFO - PID 1645 | [#3] mootools.html Cycles:180 Average:7081.44 Median:4024.02 stddev:4474.92 (111.2%) stddev-sans-first:4466.93 11:32:12 INFO - PID 1645 | Values: 1374.5 1455.5 1456.4 1460.0 1463.3 11675.1 11798.2 12098.3 12102.9 12113.9 11842.9 11910.4 12060.2 12070.6 12092.0 2513.1 2565.5 2565.6 2566.8 2573.4 2505.1 2545.6 2546.6 2548.2 2553.0 2233.6 2511.8 2521.8 2522.2 2530.7 2461.9 2487.3 2496.4 2503.7 2509.4 2445.5 2472.5 2482.9 2542.9 2547.3 9872.7 11660.4 11780.4 11793.9 11796.0 6357.8 6610.7 6631.9 6636.8 6639.6 4092.9 4273.5 4323.2 4329.2 4336.5 1076.6 1202.7 1231.8 1234.6 1253.6 15379.8 15777.5 16067.6 16185.5 16320.4 5401.3 5412.4 5521.1 5603.0 5603.2 11799.0 11806.3 11826.9 11855.5 11895.5 6210.6 6373.1 6379.8 6386.8 6388.4 5956.7 5971.2 6081.1 6108.7 6188.5 4287.2 4344.3 4404.0 4428.8 4439.2 1250.9 1272.1 1275.1 1288.1 1290.0 633.0 768.6 770.5 784.9 786.4 6054.9 6073.4 6278.2 6286.9 6329.5 580.0 588.8 608.5 612.5 615.6 3610.3 3826.6 3955.2 4258.0 4380.0 13570.7 13877.3 14634.4 15147.6 15216.2 14702.0 14809.4 14910.6 15123.8 15139.3 8315.5 8499.8 8541.6 8553.2 8571.0 6487.7 6948.7 7051.4 7099.4 7182.6 12020.5 13725.4 13796.8 13804.8 13869.9 11293.2 13791.6 13963.9 13995.5 14054.7 9357.8 9443.4 9543.1 9562.5 9579.7 9433.5 10728.0 10845.5 10885.9 10886.0 9013.4 9303.1 9367.0 9375.4 9492.8 9074.9 9087.7 9121.4 9172.4 9203.2 7724.6 8183.4 8302.5 8311.0 8314.7 6003.8 6015.6 6080.7 6169.7 6223.2 6000.5 6060.8 6130.0 6179.8 6339.3 11:32:12 INFO - PID 1645 | 11:32:12 INFO - PID 1645 | [#4] prototype.html Cycles:180 Average:5234.18 Median:5948.26 stddev:1700.53 (28.6%) stddev-sans-first:1665.82 11:32:12 INFO - PID 1645 | Values: 380.6 386.4 398.8 409.8 410.4 6032.2 6238.5 6282.4 6287.5 6347.5 5932.9 6019.4 6054.8 6059.4 6069.4 4462.5 4463.0 4471.5 4492.7 4507.3 3662.7 4379.2 4413.9 4428.9 4470.6 3746.3 4405.4 4408.5 4413.0 4432.3 3984.7 4327.5 4394.5 4400.6 4478.3 3183.5 3345.8 3389.7 3689.4 3774.2 5898.7 5899.5 5929.1 5931.9 5986.6 4367.0 4397.4 4854.6 5301.4 5311.4 5930.8 6250.2 6358.6 6534.6 6575.4 3776.7 3986.7 4151.1 4280.6 4296.1 5585.2 6390.4 6614.7 6641.6 6854.0 4226.2 4393.8 4412.8 4422.9 4448.8 4397.6 4483.0 4511.8 4513.1 4520.6 4252.4 4574.4 4802.5 5298.5 5300.3 5418.6 5718.8 6076.4 6577.6 6671.6 1173.6 1523.3 1524.2 1531.5 1534.2 955.5 1003.6 1009.5 1012.9 1016.9 5105.8 6261.0 6275.4 6284.0 6318.4 5710.9 6797.2 6800.4 6819.6 6843.9 5572.8 5575.5 5963.6 6174.6 6235.7 2891.4 3059.9 3312.3 3437.1 3603.3 6189.9 6924.9 7026.8 7056.5 7069.5 6669.5 7027.3 7034.9 7071.3 7091.9 5694.7 6098.6 6420.4 7115.3 7208.6 5989.4 6607.0 6887.9 6913.8 6951.0 6071.0 6667.0 6674.4 6760.7 6770.7 6256.3 6530.2 6994.2 7050.3 7111.6 3883.5 4134.9 4398.5 4406.7 4423.4 6522.8 6576.6 6874.6 6911.3 6944.7 6134.2 6478.8 6539.9 6631.4 6649.8 5630.6 6605.1 6617.5 6626.5 6640.6 6353.3 6382.4 6415.5 6447.8 6464.1 5776.3 6002.5 6538.9 6603.0 6639.9 5610.4 6238.2 6397.6 6403.6 6421.9 11:32:12 INFO - PID 1645 | 11:32:12 INFO - PID 1645 | [#5] yui.html Cycles:180 Average:23861.82 Median:17434.80 stddev:36820.49 (211.2%) stddev-sans-first:36882.87 11:32:12 INFO - PID 1645 | Values: 746.3 800.7 809.6 812.3 850.8 14451.0 14490.0 15062.6 17152.6 17453.0 16210.5 18104.9 18139.4 18242.5 18347.4 974.7 985.7 997.8 1000.5 1008.6 1017.1 1017.9 1020.5 1021.3 1021.6 862.9 950.0 1046.9 1047.0 1073.1 1038.7 1039.4 1039.4 1039.7 1049.3 762.0 763.3 763.5 767.3 771.9 16880.9 17380.9 17563.5 17578.0 17578.5 15275.8 17375.7 17416.6 17918.7 17941.1 12351.9 14375.2 14634.2 14712.4 14777.2 3557.8 3566.4 3578.7 3583.0 3588.5 136046.3 139447.6 140585.0 141171.0 141426.0 12730.1 12821.8 13818.9 14201.5 14295.3 26116.0 26792.6 27141.9 27171.5 27246.0 7212.5 7774.7 7900.6 8030.8 8044.2 3380.3 3440.3 3879.9 3883.8 3895.9 4073.5 4289.7 4308.0 4315.2 4315.6 750.6 807.7 910.6 933.9 935.8 612.6 615.1 630.7 648.2 654.0 11185.9 12280.1 12366.3 12406.3 12419.4 618.9 628.4 630.9 643.5 667.4 5547.9 5702.4 5704.3 5802.3 5828.8 98206.6 138788.6 139394.0 141411.0 144354.0 96792.0 117456.0 118443.4 119064.0 124099.0 94419.5 95095.0 95621.0 96730.0 97211.5 8887.6 10270.2 10673.0 11144.8 11257.0 33694.5 41956.6 42318.2 42769.9 42987.8 57508.0 57964.1 58169.8 58176.0 58194.0 14400.7 14557.8 14801.1 14827.5 14851.0 15373.5 15555.4 16285.1 16338.8 16441.7 12474.3 14483.5 14608.8 14658.9 14844.6 14727.3 15155.3 15231.7 15346.4 15409.8 13926.8 14499.1 14567.3 14597.9 16175.4 9885.9 11229.4 11250.5 11433.8 11463.3 11213.0 13527.7 13577.7 13610.5 13661.7 11:32:12 INFO - PID 1645 | -------- Summary: end -------- 11:32:12 INFO - PID 1645 | 11:32:12 INFO - TEST-INFO | 1645: exit 0 11:32:12 INFO - TEST-OK | dromaeo_css | took 1143484ms 11:32:12 INFO - TEST-START | kraken 11:32:12 INFO - operating with platform_type : mac_ 11:32:12 INFO - Initialising browser for kraken test... 11:32:12 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpoZEyJu/profile 11:32:12 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 11:32:46 INFO - TEST-INFO | 1655: killed by SIGTERM 11:32:46 INFO - Browser initialized. 11:32:46 INFO - Running cycle 1/1 for kraken test... 11:32:46 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpoZEyJu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:32:48 INFO - PID 1659 | 2017-05-30 11:32:48.883 plugin-container[1660:10571] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 11:32:48 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:32:48 INFO - PID 1659 | 2017-05-30 11:32:48.884 plugin-container[1660:10571] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 11:32:48 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:32:48 INFO - PID 1659 | 2017-05-30 11:32:48.884 plugin-container[1660:10571] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:32:49 INFO - PID 1659 | 2017-05-30 11:32:49.577 plugin-container[1661:10613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 11:32:49 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:32:49 INFO - PID 1659 | 2017-05-30 11:32:49.578 plugin-container[1661:10613] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 11:32:49 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:32:49 INFO - PID 1659 | 2017-05-30 11:32:49.578 plugin-container[1661:10613] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:32:58 INFO - PID 1659 | RSS: Main: 261910528 11:32:58 INFO - PID 1659 | 11:33:01 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 11:33:02 INFO - PID 1659 | RSS: Main: 271945728 11:33:02 INFO - PID 1659 | 11:33:05 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 11:33:06 INFO - PID 1659 | RSS: Main: 279920640 11:33:06 INFO - PID 1659 | 11:33:09 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 11:33:10 INFO - PID 1659 | RSS: Main: 281899008 11:33:10 INFO - PID 1659 | 11:33:12 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 11:33:12 INFO - PID 1659 | RSS: Main: 283099136 11:33:12 INFO - PID 1659 | 11:33:14 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:33:15 INFO - PID 1659 | RSS: Main: 283332608 11:33:15 INFO - PID 1659 | 11:33:18 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 11:33:19 INFO - PID 1659 | RSS: Main: 291614720 11:33:19 INFO - PID 1659 | 11:33:22 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 11:33:22 INFO - PID 1659 | RSS: Main: 291831808 11:33:22 INFO - PID 1659 | 11:33:25 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 11:33:26 INFO - PID 1659 | RSS: Main: 290435072 11:33:26 INFO - PID 1659 | 11:33:27 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:33:28 INFO - PID 1659 | RSS: Main: 290402304 11:33:28 INFO - PID 1659 | 11:33:30 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:33:31 INFO - PID 1659 | RSS: Main: 290226176 11:33:31 INFO - PID 1659 | 11:33:33 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:33:34 INFO - PID 1659 | RSS: Main: 289787904 11:33:34 INFO - PID 1659 | 11:33:36 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:33:37 INFO - PID 1659 | RSS: Main: 290074624 11:33:37 INFO - PID 1659 | 11:33:40 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:33:41 INFO - PID 1659 | RSS: Main: 289968128 11:33:41 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 11:33:43 INFO - PID 1659 | RSS: Main: 291852288 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | __start_tp_report 11:33:43 INFO - PID 1659 | _x_x_mozilla_page_load 11:33:43 INFO - PID 1659 | _x_x_mozilla_page_load_details 11:33:43 INFO - PID 1659 | |i|pagename|runs| 11:33:43 INFO - PID 1659 | |0;ai-astar;116;99;157;167;168;117;134;170;152;113 11:33:43 INFO - PID 1659 | |1;audio-beat-detection;136;151;124;148;109;114;113;114;136;109 11:33:43 INFO - PID 1659 | |2;audio-dft;216;230;266;277;200;201;199;198;198;200 11:33:43 INFO - PID 1659 | |3;audio-fft;67;76;82;101;57;57;80;85;93;81 11:33:43 INFO - PID 1659 | |4;audio-oscillator;81;71;83;72;70;68;88;73;70;94 11:33:43 INFO - PID 1659 | |5;imaging-gaussian-blur;89;89;90;88;91;88;88;128;136;132 11:33:43 INFO - PID 1659 | |6;imaging-darkroom;99;96;96;97;104;96;96;96;97;95 11:33:43 INFO - PID 1659 | |7;imaging-desaturate;90;95;91;92;98;73;130;75;80;137 11:33:43 INFO - PID 1659 | |8;json-parse-financial;50;48;52;50;51;52;52;52;52;51 11:33:43 INFO - PID 1659 | |9;json-stringify-tinderbox;55;55;67;90;91;64;56;56;54;58 11:33:43 INFO - PID 1659 | |10;stanford-crypto-aes;88;89;87;87;101;84;86;86;124;106 11:33:43 INFO - PID 1659 | |11;stanford-crypto-ccm;138;144;150;139;144;150;151;149;162;134 11:33:43 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;191;196;184;190;201;200;190;188;192;185 11:33:43 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;70;66;68;68;67;65;68;69;68;68 11:33:43 INFO - PID 1659 | __end_tp_report 11:33:43 INFO - PID 1659 | __start_cc_report 11:33:43 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1194 11:33:43 INFO - PID 1659 | __end_cc_report 11:33:43 INFO - PID 1659 | __startTimestamp1496169223313__endTimestamp 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | ------- Summary: start ------- 11:33:43 INFO - PID 1659 | Number of tests: 14 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:139.30 Median:162.00 stddev:26.65 (16.5%) stddev-sans-first:26.90 11:33:43 INFO - PID 1659 | Values: 116.0 99.0 157.0 167.0 168.0 117.0 134.0 170.0 152.0 113.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:130.00 stddev:16.14 (12.4%) stddev-sans-first:16.66 11:33:43 INFO - PID 1659 | Values: 136.0 151.0 124.0 148.0 109.0 114.0 113.0 114.0 136.0 109.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:218.50 Median:208.50 stddev:29.87 (14.3%) stddev-sans-first:31.67 11:33:43 INFO - PID 1659 | Values: 216.0 230.0 266.0 277.0 200.0 201.0 199.0 198.0 198.0 200.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:77.90 Median:80.50 stddev:14.29 (17.8%) stddev-sans-first:14.61 11:33:43 INFO - PID 1659 | Values: 67.0 76.0 82.0 101.0 57.0 57.0 80.0 85.0 93.0 81.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:77.00 stddev:8.93 (11.6%) stddev-sans-first:9.36 11:33:43 INFO - PID 1659 | Values: 81.0 71.0 83.0 72.0 70.0 68.0 88.0 73.0 70.0 94.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:101.90 Median:88.50 stddev:20.88 (23.6%) stddev-sans-first:21.62 11:33:43 INFO - PID 1659 | Values: 89.0 89.0 90.0 88.0 91.0 88.0 88.0 128.0 136.0 132.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:96.00 stddev:2.62 (2.7%) stddev-sans-first:2.69 11:33:43 INFO - PID 1659 | Values: 99.0 96.0 96.0 97.0 104.0 96.0 96.0 96.0 97.0 95.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:90.50 stddev:21.46 (23.7%) stddev-sans-first:22.65 11:33:43 INFO - PID 1659 | Values: 90.0 95.0 91.0 92.0 98.0 73.0 130.0 75.0 80.0 137.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:52.00 stddev:1.33 (2.6%) stddev-sans-first:1.36 11:33:43 INFO - PID 1659 | Values: 50.0 48.0 52.0 50.0 51.0 52.0 52.0 52.0 52.0 51.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:61.00 stddev:14.28 (23.4%) stddev-sans-first:14.72 11:33:43 INFO - PID 1659 | Values: 55.0 55.0 67.0 90.0 91.0 64.0 56.0 56.0 54.0 58.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:86.50 stddev:12.82 (14.8%) stddev-sans-first:13.43 11:33:43 INFO - PID 1659 | Values: 88.0 89.0 87.0 87.0 101.0 84.0 86.0 86.0 124.0 106.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:146.10 Median:149.50 stddev:8.08 (5.4%) stddev-sans-first:8.02 11:33:43 INFO - PID 1659 | Values: 138.0 144.0 150.0 139.0 144.0 150.0 151.0 149.0 162.0 134.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.70 Median:191.50 stddev:5.76 (3.0%) stddev-sans-first:6.10 11:33:43 INFO - PID 1659 | Values: 191.0 196.0 184.0 190.0 201.0 200.0 190.0 188.0 192.0 185.0 11:33:43 INFO - PID 1659 | 11:33:43 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.42 (2.1%) stddev-sans-first:1.24 11:33:43 INFO - PID 1659 | Values: 70.0 66.0 68.0 68.0 67.0 65.0 68.0 69.0 68.0 68.0 11:33:43 INFO - PID 1659 | -------- Summary: end -------- 11:33:43 INFO - PID 1659 | 11:33:44 INFO - TEST-INFO | 1659: exit 0 11:33:44 INFO - TEST-OK | kraken | took 91465ms 11:33:44 INFO - SUITE-END | took 1234s 11:33:44 INFO - Completed test suite (00:20:35) 11:33:44 INFO - javascript benchmark 11:33:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7850.852824578192, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2761.0888406825743, 3538.1508437974894, 3555.5733312002562, 3563.376409128402, 3601.9819900900493, 5973.522118230548, 6111.236095488064, 6117.480014198798, 6121.6939153042285, 6208.255009398873, 6107.128129456014, 6131.468419844962, 6142.897836592515, 6176.75292988281, 6204.472619827309, 5361.544268737142, 5388.838334849961, 5416.756245968922, 5440.183972404131, 5443.237946687447, 5483.067878460652, 5504.4770746779195, 5521.834344969658, 5523.889522209553, 5558.888822223553, 5324.946750532484, 5398.2982212312545, 5429.185622156708, 5468.835934921959, 5513.393526712059, 5384.0, 5413.296271484721, 5416.485433883775, 5432.837014889559, 5441.0, 4443.488998765151, 5386.91919621202, 5391.406945236021, 5439.0209762242785, 5453.890922181574, 6117.388261173847, 6149.985252433343, 6226.564140510166, 6229.688515574203, 6232.065190221503, 6522.7390904363765, 6533.379328963789, 6644.60132392058, 6668.299828517985, 6684.933150668479, 6706.597604143767, 6709.2284387295595, 6716.966415167942, 6718.932810671929, 6762.425193858528, 6287.182666253358, 6299.779507717208, 6306.3378345274, 6314.147590075327, 6326.746930122744, 6660.234073081658, 6727.730890764315, 6789.117414736152, 6866.725330986705, 6909.7581584644295, 6273.905891411632, 6405.871882562323, 6523.934760652332, 6558.475321974326, 6563.310852360529, 6628.83427914306, 6672.065910772496, 6687.230968438692, 6708.865822683519, 6711.865762684719, 6403.775867844603, 6598.7030583623855, 6605.0, 6622.0, 6623.271440141581, 6478.805635830932, 6533.444657204096, 6543.345665433419, 6548.246951600627, 6568.737250510022, 5299.5230429261555, 5311.176767601028, 5318.574514038867, 5322.92015619766, 5328.973355133199, 4712.151812673752, 4725.385699858996, 4726.976365118152, 4728.432588089451, 4790.640701947368, 4924.852254432372, 5194.68831870089, 6129.389354848411, 6235.625862448268, 6301.77943771975, 6653.500987425962, 6677.0, 6708.6310252936555, 6714.328567143344, 6733.966330168416, 6327.557071005078, 6335.429811317003, 6337.176167098248, 6340.936590634127, 6353.745850165941, 5611.3266408031295, 5746.166805813134, 5756.798512052058, 5854.765809367663, 5858.8242352729485, 6764.323567643295, 6851.520393572402, 6851.8972215415815, 6870.141232345957, 6896.655167241517, 6733.66331683404, 6737.191537015589, 6751.696173672099, 6755.560888542229, 6767.966160169168, 6655.567388119756, 6818.18181818185, 6881.13985751781, 6898.344657257552, 6910.101686780888, 5529.198266251451, 6462.515311351762, 6505.544611877123, 6525.314841941527, 6611.768588099586, 6674.799756007327, 6692.230393504809, 6710.83222919431, 6730.663466826737, 6731.798046058626, 6274.905876411949, 6284.214473190851, 6366.713497892699, 6461.0, 6486.935130648633, 6470.48236141117, 6478.643674597943, 6481.416672499309, 6486.772962946275, 6613.0, 6331.303556608861, 6333.954897442055, 6338.176037115146, 6338.302786693368, 6360.173177486897, 6293.024581189923, 6364.331745166876, 6394.744210231539, 6450.8064758057335, 6476.028595710681, 5586.497215250647, 6258.061290806416, 6290.21372328459, 6307.495400367868, 6336.936630633634], "value": 6050.880552248657, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.412676844377, 774.9496282741621, 831.3224721851691, 1255.667248179232, 1327.256736227712, 39330.0, 40251.39622905659, 40355.19289614209, 40365.59634403655, 40396.99007524813, 39867.803965880994, 40320.79839600805, 40362.7981860091, 40439.79780101095, 40460.98847528813, 7877.6848926042885, 8238.835223295531, 8484.830303393928, 8490.363222758288, 8507.064222935473, 8362.58187090646, 8477.237048665618, 8482.406231563793, 8486.0, 8495.10801365857, 8449.859268998698, 8503.149685031509, 8503.744887653349, 8504.319654427662, 8510.0, 8398.328133749286, 8400.537970411626, 8458.534780587066, 8460.280876125506, 8462.323014158852, 8208.958955205186, 8237.0, 8243.670253189865, 8279.172082791732, 8295.502269863828, 37557.24885502275, 37590.62409375926, 37692.0, 37793.0, 37799.81100094482, 3190.7287880530184, 3580.0, 3622.6920711739535, 3623.2572322673786, 3630.2013557017412, 30768.230794230098, 30937.381252375053, 30958.84520577405, 30967.35489676544, 31013.689863101306, 3075.338802157547, 3098.256418459576, 3100.3954228925368, 3119.750419966397, 3122.281875168724, 243202.0, 248253.0, 279855.0, 293812.0, 295463.0, 48113.278300825514, 48553.78615534569, 48565.5143448561, 48640.27039594409, 48724.78188045257, 50241.497585024415, 50359.49640503621, 50468.48594542169, 53089.0, 53674.19488707672, 14443.85556144425, 14495.927520362331, 14595.416183352547, 14598.051126676732, 14617.415303387745, 11573.88426115745, 11666.358350290815, 11683.065354771597, 11692.064634829194, 11724.70688232801, 11277.661670149908, 13571.457141714418, 13746.793798093036, 13751.518696845762, 13875.930620347035, 5180.0, 5190.688558686491, 5467.535259502907, 5893.116032595145, 6089.939100609025, 883.5008220355479, 995.4823491784232, 1014.7260239735231, 1024.9846252306222, 1046.9685909422728, 23509.177178798658, 24151.894177989783, 24756.0, 24884.004639814204, 24901.87549062243, 1025.7281820317621, 1121.4673030310537, 1135.233717240859, 1164.458526785032, 1172.3141961952183, 11377.089832813355, 12622.81065784014, 12744.299063551409, 12778.361081945866, 12842.743145137232, 326361.0, 327326.0, 327811.36094319855, 328509.0, 328614.0, 45874.5412545877, 55674.0, 55705.44294557084, 55745.72127139419, 55802.72098639562, 1507.6080219143103, 1539.1611571693454, 1545.5672411724736, 1545.6445017646004, 1545.7604071368955, 21705.782942170375, 22863.771362286163, 24363.147289845478, 24375.390615234763, 24418.51162976766, 39028.0, 39152.629657961836, 39228.0, 39286.80356598199, 39324.0, 272542.0, 272597.0, 272669.0, 272694.0, 273610.0, 35335.293294134484, 35707.32195085431, 35985.92042238737, 36059.459108112875, 36090.63909360978, 45338.773306133255, 45914.540854591025, 45917.54082459266, 45995.31007034964, 46191.61425157251, 28456.008557001234, 29447.705522944496, 30903.845480772452, 30969.0, 31134.688653114084, 24733.0, 30530.0, 31111.730758697646, 31246.0, 31403.371932561677, 37088.0, 37710.81144594259, 37739.81130094332, 37807.24385512329, 37839.0, 28460.857695711387, 29151.979680711072, 29213.269668258465, 30787.38425231438, 31433.8428307857, 22728.09087636476, 24310.75689243085, 24317.0, 24402.87798561067, 24785.380365491008], "value": 17580.44936710279, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.40522982990035, 424.80883602378935, 429.3302448180838, 430.9288967320392, 439.52092219480767, 21213.99999999998, 21602.13591456342, 22276.88615569223, 22939.655905161435, 23099.42251443715, 22726.77273227267, 22894.65658015127, 23857.403564910885, 24059.278221653378, 24110.0, 5970.0746384310505, 6216.564840461169, 6220.191375121228, 6223.159873417076, 6236.937630623704, 5258.039904054741, 5873.207117039187, 6005.12925625784, 6055.0, 6101.664408457533, 5988.730507127189, 5990.580659353847, 6005.939940600603, 6025.307089684698, 6038.184845045928, 5799.65202087874, 6001.579889407722, 6020.8494787630225, 6029.577929544934, 6067.453929146398, 4899.7550122494085, 5390.0, 5406.91889621656, 5420.620556561043, 5422.132458806612, 26985.0, 27840.721592784015, 28110.718892811015, 28309.292267693472, 28561.285967850763, 2124.3308357867377, 2183.6069507488646, 2186.9015894284794, 2189.4745261137214, 2190.452386903274, 17920.641587168182, 17960.730589041174, 17975.910120449313, 18318.0, 18552.907235463736, 2122.734658167729, 2160.0711693971584, 2182.4216582605623, 2184.421128400975, 2214.1143542582936, 164515.0, 165590.0, 167003.0, 169123.0, 173266.1336693321, 10609.893901061045, 12420.130590858553, 12427.254364738144, 12524.81212781809, 13006.739865202831, 13439.260840653858, 16499.917500412423, 16704.582385440463, 16709.91645041767, 16729.665406691798, 8803.471791692518, 9053.366264361432, 9091.045440228812, 9122.81754364909, 9317.72046838596, 7586.786114221698, 8597.656093756179, 8618.39671223021, 8680.392372533988, 8799.692010779592, 8095.311898488694, 8201.179882011751, 8214.424990250625, 8242.670293188205, 8275.462094963808, 1905.6665083610312, 2257.932262032142, 2261.2311813983324, 2262.9434264143197, 2424.2121310574134, 931.2736065868634, 959.7504648791364, 962.821877952581, 965.8840939087214, 995.2386424385351, 20357.287494937606, 20941.581168376546, 20958.056887440103, 21070.683939740917, 21233.0, 938.807544453399, 955.8757361543094, 973.9026097390346, 987.1559816357055, 987.8666380038746, 10640.201984851012, 10845.457727113613, 10860.945695271474, 10866.0, 10869.29349592274, 541555.0, 545695.0, 547267.0, 547570.4079234838, 554841.0, 131657.0, 133586.0, 136157.0, 137854.3107284457, 139452.60547394396, 1156.450685924196, 1170.5434880396651, 1220.2373516552154, 1221.1940119521073, 1222.1567118688076, 21577.460563486038, 21773.12907483683, 21901.89049054808, 22133.557328853654, 22175.347395781522, 60157.09764353626, 60231.39768602258, 60416.69791651014, 60623.69688151531, 60675.69662151838, 79502.60248698719, 80208.19791802007, 80525.0, 81236.0, 81870.77193841978, 7233.891491627735, 8792.516411597424, 8907.910920890708, 8971.820563588823, 9214.170724634814, 14113.788293175405, 14288.857111428753, 14397.64005899819, 14484.493042743454, 14882.18148001827, 11810.76378472443, 11909.22590031645, 12119.394030298274, 12297.508099675913, 12316.0, 9977.551010204703, 11272.774544509228, 11561.017313528444, 11664.241824281395, 11679.124065694938, 11987.22083064598, 12166.452509637265, 12355.567555135527, 12377.443015064524, 12410.689732756753, 8152.592370381576, 8189.894364260748, 8333.33333333344, 8677.913220867964, 8917.821643566962, 8601.526916019679, 11920.794652989225, 12056.276623402624, 12161.817572736592, 12316.13787034899], "value": 10550.7988717272, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.5395292576989, 1455.468753904825, 1456.4174330267892, 1460.0436713952365, 1463.3488097796485, 11675.124365672566, 11798.23311484753, 12098.334591597459, 12102.939485302582, 12113.878861211408, 11842.881571184285, 11910.40447977602, 12060.21608595443, 12070.637880863565, 12092.03263738901, 2513.1455305196228, 2565.4997275531277, 2565.5638541447906, 2566.781823545001, 2573.3530540454126, 2505.110685706575, 2545.592705167174, 2546.5798143306424, 2548.248266761301, 2553.0, 2233.6426171812514, 2511.824172307939, 2521.7982561395042, 2522.230719630512, 2530.6710127683377, 2461.90152393904, 2487.2911220302217, 2496.414776616843, 2503.662005629235, 2509.397744541315, 2445.5475736988715, 2472.505498900209, 2482.900683972639, 2542.8855701493294, 2547.3249588858966, 9872.654457094039, 11660.416979151008, 11780.410979450993, 11793.882061179364, 11796.0, 6357.841053973688, 6610.7355705771715, 6631.86736265272, 6636.8008959730805, 6639.634820084893, 4092.856750013735, 4273.465816772903, 4323.200207961537, 4329.2423825830665, 4336.522982471925, 1076.6339444588878, 1202.699325168708, 1231.8029115341503, 1234.6234398508452, 1253.573784913134, 15379.846201537954, 15777.526674199678, 16067.598310042225, 16185.514434566981, 16320.428784992468, 5401.297831281987, 5412.404635490093, 5521.061419558685, 5602.971985140048, 5603.2435621191435, 11799.0, 11806.291622502677, 11826.935575798047, 11855.46650400734, 11895.524179032915, 6210.627362358273, 6373.075903993895, 6379.808605741835, 6386.808395748135, 6388.39310265524, 5956.6723830188885, 5971.19388882504, 6081.118237855486, 6108.725107370179, 6188.504919606421, 4287.164003019444, 4344.326629372453, 4403.977980110143, 4428.778561071934, 4439.156560253541, 1250.9061820363509, 1272.0968112639944, 1275.1074248026418, 1288.0790234982032, 1289.9871001290055, 633.0, 768.5657603454031, 770.4568279363035, 784.8704963681043, 786.3866184376081, 6054.939450605526, 6073.392660733891, 6278.246610406781, 6286.937130628635, 6329.461995730322, 580.0226618148433, 588.7821506042792, 608.4736702752176, 612.4732729852324, 615.5844804756771, 3610.3140403323287, 3826.598207188261, 3955.1891862168186, 4257.957420425818, 4380.0, 13570.660733481545, 13877.306134693225, 14634.414623415158, 15147.621309467351, 15216.239188040554, 14701.95696183558, 14809.407623695148, 14910.627234319229, 15123.848761512245, 15139.318730657147, 8315.459495132918, 8499.830003399897, 8541.615627296787, 8553.23020928107, 8571.0, 6487.740490380332, 6948.652567371712, 7051.365377116084, 7099.432045436456, 7182.60495672743, 12020.459079341626, 13725.382357793724, 13796.793048104488, 13804.792928106288, 13869.890408767073, 11293.153013523854, 13791.586252412444, 13963.860361396662, 13995.51015714445, 14054.718905622034, 9357.812843743222, 9443.433393996382, 9543.093406126407, 9562.521873906417, 9579.664711735057, 9433.52832358393, 10728.0, 10845.511951962339, 10885.891141088488, 10886.020258176803, 9013.414128081653, 9303.069693030642, 9366.95316523413, 9375.437473751597, 9492.810143797224, 9074.90925090767, 9087.727368178965, 9121.407108537924, 9172.403793753385, 9203.171714545722, 7724.575148366894, 8183.427160098974, 8302.460340078118, 8311.044229913638, 8314.667413303641, 6003.7898673546215, 6015.639061656315, 6080.72636731357, 6169.6915154241215, 6223.190985171898, 6000.459958603747, 6060.8181404625675, 6129.969350153221, 6179.84550386226, 6339.334369891095], "value": 5300.842814256431, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.5889639189675, 386.4203694458312, 398.7726995612502, 409.7766717139159, 410.42540443379266, 6032.245969253843, 6238.477104099531, 6282.403171698681, 6287.491002159483, 6347.49220062395, 5932.902413053588, 6019.398060193989, 6054.788082417116, 6059.394060593939, 6069.42340477655, 4462.464504259493, 4462.955370446302, 4471.508134105254, 4492.663050271226, 4507.301368287921, 3662.6997415917367, 4379.2336341140335, 4413.9185899454515, 4428.933565996513, 4470.642348612119, 3746.288205241009, 4405.383246345515, 4408.515063343029, 4413.0, 4432.290833466646, 3984.681225501967, 4327.480702315742, 4394.516603173648, 4400.559944005574, 4478.328250762379, 3183.4747266700965, 3345.8327083645963, 3389.7288216942834, 3689.3728066228805, 3774.1885494618514, 5898.702285497184, 5899.528037756969, 5929.070929070929, 5931.932252194603, 5986.551008674323, 4366.978165109186, 4397.360784372486, 4854.635902307341, 5301.443348448396, 5311.362636483608, 5930.75454154628, 6250.249970003583, 6358.566374157949, 6534.607923524603, 6575.375339342755, 3776.7356285060055, 3986.740861843971, 4151.14901445203, 4280.614744672995, 4296.0548679290505, 5585.184814935163, 6390.360963903666, 6614.7023383947835, 6641.601503909781, 6853.965730171317, 4226.197022565702, 4393.846215382447, 4412.845550405785, 4422.933655995163, 4448.799804008828, 4397.582229688174, 4483.0, 4511.8195272189405, 4513.11994161142, 4520.61574766142, 4252.383404406406, 4574.428196475477, 4802.543758342952, 5298.470152984748, 5300.337457817773, 5418.588728015995, 5718.826845798653, 6076.422739839708, 6577.6053436793945, 6671.5997040177745, 1173.5833779008499, 1523.3449616664718, 1524.2226464503183, 1531.5328824708347, 1534.171547364433, 955.4921176805325, 1003.6136087606324, 1009.4750729620556, 1012.9088382045651, 1016.8830584482731, 5105.795768169231, 6260.968695156586, 6275.3724627536885, 6284.0, 6318.4313411793155, 5710.885782284331, 6797.150356205474, 6800.387965083068, 6819.624920629314, 6843.863122737517, 5572.777088916398, 5575.526080283222, 5963.612365196248, 6174.646873953851, 6235.657038862906, 2891.43616994684, 3059.938801223963, 3312.271300313927, 3437.1407148212948, 3603.2613314271034, 6189.938100618936, 6924.896126558106, 7026.789196324118, 7056.541324813973, 7069.505134640629, 6669.466442684574, 7027.297270273034, 7034.8944765828555, 7071.25751796054, 7091.89362159568, 5694.743736531866, 6098.573099883054, 6420.357964203542, 7115.26062465944, 7208.603526805866, 5989.382866626073, 6607.00894865774, 6887.931120688729, 6913.792586222421, 6950.965245173944, 6070.969645151923, 6666.999850067392, 6674.432673222636, 6760.661966901734, 6770.729170833111, 6256.343083976299, 6530.20030277736, 6994.230634630085, 7050.330218629222, 7111.573305601681, 3883.4563161158026, 4134.875953721393, 4398.450193725785, 4406.713563618355, 4423.424954755861, 6522.825891339605, 6576.605403675603, 6874.587524748531, 6911.274316196727, 6944.708246278817, 6134.191047936697, 6478.838029049311, 6539.869202616016, 6631.403173714389, 6649.800505984828, 5630.634008789416, 6605.14133162705, 6617.536772425884, 6626.536142470173, 6640.568363056386, 6353.332900045429, 6382.425581697669, 6415.518836087219, 6447.806565803034, 6464.095026696173, 5776.306843178845, 6002.549808764447, 6538.934610653832, 6602.966985165043, 6639.900401493885, 5610.410906854885, 6238.220222472191, 6397.648129352744, 6403.64779937108, 6421.903671444838], "value": 4702.524150864644, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.2649290448907, 800.6797281087568, 809.5711069961519, 812.3379445751712, 850.8340873529664, 14451.0, 14490.0, 15062.623434414145, 17152.571185720364, 17453.04008279544, 16210.513684589452, 18104.869955691553, 18139.365122220726, 18242.452726418196, 18347.449576512692, 974.6734843827309, 985.7289245457484, 997.7854761226334, 1000.5447521377756, 1008.621766837436, 1017.0846238385475, 1017.9464254496597, 1020.505055048301, 1021.3055122516686, 1021.6322124035341, 862.8705694145897, 949.9835176361296, 1046.911012563933, 1047.010575006619, 1073.1468482556386, 1038.7091614347994, 1039.3555995282973, 1039.433508737736, 1039.6517166749147, 1049.3074570783285, 761.958092304923, 763.3320844261272, 763.5075376382258, 767.2672597669268, 771.918948510404, 16880.915595422066, 17380.913095434444, 17563.473095807145, 17578.0, 17578.472645820646, 15275.770863437057, 17375.73936390955, 17416.564585885328, 17918.731219031724, 17941.102944852704, 12351.938240308831, 14375.209363485004, 14634.195119268436, 14712.411503539846, 14777.187254700988, 3557.839897204632, 3566.4115420955773, 3578.6600272974288, 3582.964170358315, 3588.4796704477967, 136046.3197684005, 139447.60552394448, 140585.0, 141171.0, 141426.0, 12730.10889237763, 12821.781930716566, 13818.86181138196, 14201.502947396999, 14295.28523573838, 26116.0, 26792.59811102835, 27141.864290678815, 27171.45657086847, 27246.0, 7212.459065570103, 7774.689012439552, 7900.644470998704, 8030.799230019179, 8044.1955804419085, 3380.3408335374365, 3440.2947395783804, 3879.86420475282, 3883.786391748481, 3895.922081558353, 4073.5111786585207, 4289.678274129453, 4307.956920430818, 4315.244832154361, 4315.611594956406, 750.5684231566871, 807.6809660184194, 910.6357457017247, 933.9486328252011, 935.7567032571444, 612.6293592376549, 615.1449485215554, 630.7098734582052, 648.2447948140364, 653.9574927629689, 11185.888141118485, 12280.07899407548, 12366.319852408204, 12406.317652529016, 12419.44112514921, 618.8700372921737, 628.434409031875, 630.9211348581428, 643.5398689936718, 667.4160109903834, 5547.916781248285, 5702.372738998707, 5704.3154821421695, 5802.3037235531165, 5828.795992140254, 98206.59863707422, 138788.6121138796, 139394.0, 141411.0, 144354.0, 96792.03207967972, 117456.0, 118443.40778296054, 119064.0, 124099.0, 94419.52790236005, 95095.0, 95621.0, 96729.99999999859, 97211.51394243125, 8887.644494220418, 10270.178385728974, 10673.039426451656, 11144.832827507431, 11257.0, 33694.49458258079, 41956.58043419527, 42318.1536369277, 42769.93075173146, 42987.78506107449, 57508.0, 57964.13053804281, 58169.7647211753, 58176.0, 58194.0, 14400.711985760017, 14557.781633275297, 14801.111933284037, 14827.481038163613, 14851.034682745587, 15373.461928832437, 15555.41250513706, 16285.104319262555, 16338.754918676466, 16441.67116657636, 12474.251544907334, 14483.493077742229, 14608.780868287196, 14658.853411466176, 14844.62888427798, 14727.337269823098, 15155.318010689325, 15231.695366092838, 15346.386144554093, 15409.76885346743, 13926.791098133333, 14499.130052196902, 14567.34446949911, 14597.854021460074, 16175.352985880432, 9885.901140988499, 11229.38238396896, 11250.493727782434, 11433.828492572451, 11463.254888432226, 11213.0, 13527.661808454868, 13577.660558485723, 13610.523631672844, 13661.658458538219], "value": 8368.724267297206, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 99.0, 157.0, 167.0, 168.0, 117.0, 134.0, 170.0, 152.0, 113.0], "value": 139.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 151.0, 124.0, 148.0, 109.0, 114.0, 113.0, 114.0, 136.0, 109.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 230.0, 266.0, 277.0, 200.0, 201.0, 199.0, 198.0, 198.0, 200.0], "value": 218.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 82.0, 101.0, 57.0, 57.0, 80.0, 85.0, 93.0, 81.0], "value": 77.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 83.0, 72.0, 70.0, 68.0, 88.0, 73.0, 70.0, 94.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 88.0, 91.0, 88.0, 88.0, 128.0, 136.0, 132.0], "value": 101.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 96.0, 97.0, 104.0, 96.0, 96.0, 96.0, 97.0, 95.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 91.0, 92.0, 98.0, 73.0, 130.0, 75.0, 80.0, 137.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 52.0, 50.0, 51.0, 52.0, 52.0, 52.0, 52.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 67.0, 90.0, 91.0, 64.0, 56.0, 56.0, 54.0, 58.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 87.0, 101.0, 84.0, 86.0, 86.0, 124.0, 106.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 144.0, 150.0, 139.0, 144.0, 150.0, 151.0, 149.0, 162.0, 134.0], "value": 146.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 196.0, 184.0, 190.0, 201.0, 200.0, 190.0, 188.0, 192.0, 185.0], "value": 191.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 68.0, 68.0, 67.0, 65.0, 68.0, 69.0, 68.0, 68.0], "value": 67.7, "unit": "score"}]}]} 11:33:44 INFO - Return code: 0 11:33:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:33:44 INFO - # TBPL SUCCESS # 11:33:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:33:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:33:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:33:44 INFO - 2017-05-30 11:33:44.638 screenresolution[1667:10988] starting screenresolution argv=screenresolution get 11:33:44 INFO - 2017-05-30 11:33:44.640 screenresolution[1667:10988] Display 0: 1600x1200x32@60 11:33:44 INFO - 2017-05-30 11:33:44.646 screenresolution[1668:10991] starting screenresolution argv=screenresolution list 11:33:44 INFO - Available Modes on Display 0 11:33:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:33:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:33:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:33:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:33:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:33:44 INFO - Intel Iris: 11:33:44 INFO - Chipset Model: Intel Iris 11:33:44 INFO - Type: GPU 11:33:44 INFO - Bus: Built-In 11:33:44 INFO - VRAM (Dynamic, Max): 1536 MB 11:33:44 INFO - Vendor: Intel (0x8086) 11:33:44 INFO - Device ID: 0x0a2e 11:33:44 INFO - Revision ID: 0x0009 11:33:44 INFO - Displays: 11:33:44 INFO - Display: 11:33:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:33:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:33:44 INFO - Display Serial Number: 1600x1200 60 11:33:44 INFO - Main Display: Yes 11:33:44 INFO - Mirror: Off 11:33:44 INFO - Online: Yes 11:33:44 INFO - Rotation: Supported 11:33:44 INFO - Return code: 0 11:33:44 INFO - Running post-action listener: _resource_record_post_action 11:33:44 INFO - [mozharness: 2017-05-30 18:33:44.882994Z] Finished run-tests step (success) 11:33:44 INFO - Running post-run listener: _resource_record_post_run 11:33:44 INFO - Total resource usage - Wall time: 1257s; CPU: 54.0%; Read bytes: 40556032; Write bytes: 571408384; Read time: 288; Write time: 1769 11:33:44 INFO - TinderboxPrint: CPU usage
53.5% 11:33:44 INFO - TinderboxPrint: I/O read bytes / time
40,556,032 / 288 11:33:44 INFO - TinderboxPrint: I/O write bytes / time
571,408,384 / 1,769 11:33:44 INFO - TinderboxPrint: CPU idle
2,337.2 (46.5%) 11:33:44 INFO - TinderboxPrint: CPU system
1,024.3 (20.4%) 11:33:44 INFO - TinderboxPrint: CPU user
1,668.2 (33.2%) 11:33:44 INFO - TinderboxPrint: Swap in / out
286,429,184 / 0 11:33:44 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231008768; Write bytes: 231700480; Read time: 16728; Write time: 477 11:33:44 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:33:44 INFO - run-tests - Wall time: 1236s; CPU: 54.0%; Read bytes: 39560704; Write bytes: 321243136; Read time: 272; Write time: 1242 11:33:45 INFO - Running post-run listener: _upload_blobber_files 11:33:45 INFO - Blob upload gear active. 11:33:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:33:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:33:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:33:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:33:45 INFO - (blobuploader) - INFO - Open directory for files ... 11:33:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:33:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:33:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:33:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:33:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:33:46 INFO - (blobuploader) - INFO - Done attempting. 11:33:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:33:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:33:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:33:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:33:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:33:47 INFO - (blobuploader) - INFO - Done attempting. 11:33:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:33:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:33:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:33:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:33:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:33:48 INFO - (blobuploader) - INFO - Done attempting. 11:33:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:33:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:33:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:33:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:33:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:33:50 INFO - (blobuploader) - INFO - Done attempting. 11:33:50 INFO - (blobuploader) - INFO - Iteration through files over. 11:33:50 INFO - Return code: 0 11:33:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:33:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:33:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06b67794f7441bb0242c7b3a491693da88861c7f3d18f6ef3e0fa0eb686f901b2ccab32b957ea6df6daf6760a52f51b8eb7609b3a9a113048cad04896862cf00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ccf65195a639f3b3d5682b6975eb906acc6760d48da0459c8a6301cb2073e23c9f7399fc22af2ee933389b6c35cf7674b3ad6060fd500a651fe9ae3a028e445", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2f3d832ec4494ab670d6d14acfb71084ac158c4f57bf44883eb88e52df5056121d6c4e5a99160ff6a2733bbcda0c4fafc2a6fe893df354d5bda29349bfffb5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e407426211c738eedc9bd3dda510cc40aae70a47f8eaa1dff6f1565f462e031cd1ddc44e3e03f37be5d49ccfa8e6efe2b8873ed9e6c70133fc93ecf0cf0dbfc7"} 11:33:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:33:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:33:50 INFO - Contents: 11:33:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/06b67794f7441bb0242c7b3a491693da88861c7f3d18f6ef3e0fa0eb686f901b2ccab32b957ea6df6daf6760a52f51b8eb7609b3a9a113048cad04896862cf00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ccf65195a639f3b3d5682b6975eb906acc6760d48da0459c8a6301cb2073e23c9f7399fc22af2ee933389b6c35cf7674b3ad6060fd500a651fe9ae3a028e445", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2f3d832ec4494ab670d6d14acfb71084ac158c4f57bf44883eb88e52df5056121d6c4e5a99160ff6a2733bbcda0c4fafc2a6fe893df354d5bda29349bfffb5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e407426211c738eedc9bd3dda510cc40aae70a47f8eaa1dff6f1565f462e031cd1ddc44e3e03f37be5d49ccfa8e6efe2b8873ed9e6c70133fc93ecf0cf0dbfc7"} 11:33:50 INFO - Running post-run listener: copy_logs_to_upload_dir 11:33:50 INFO - Copying logs to upload dir... 11:33:50 INFO - mkdir: /builds/slave/test/build/upload/logs 11:33:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.887508 ========= master_lag: 0.67 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-05-30 11:33:51.339039) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:33:51.342220) ========= 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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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/06b67794f7441bb0242c7b3a491693da88861c7f3d18f6ef3e0fa0eb686f901b2ccab32b957ea6df6daf6760a52f51b8eb7609b3a9a113048cad04896862cf00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ccf65195a639f3b3d5682b6975eb906acc6760d48da0459c8a6301cb2073e23c9f7399fc22af2ee933389b6c35cf7674b3ad6060fd500a651fe9ae3a028e445", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2f3d832ec4494ab670d6d14acfb71084ac158c4f57bf44883eb88e52df5056121d6c4e5a99160ff6a2733bbcda0c4fafc2a6fe893df354d5bda29349bfffb5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e407426211c738eedc9bd3dda510cc40aae70a47f8eaa1dff6f1565f462e031cd1ddc44e3e03f37be5d49ccfa8e6efe2b8873ed9e6c70133fc93ecf0cf0dbfc7"} build_url:https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011458 build_url: 'https://queue.taskcluster.net/v1/task/cCGfMGbeS9Wr9oX4p8oz6Q/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/06b67794f7441bb0242c7b3a491693da88861c7f3d18f6ef3e0fa0eb686f901b2ccab32b957ea6df6daf6760a52f51b8eb7609b3a9a113048cad04896862cf00", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0ccf65195a639f3b3d5682b6975eb906acc6760d48da0459c8a6301cb2073e23c9f7399fc22af2ee933389b6c35cf7674b3ad6060fd500a651fe9ae3a028e445", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a2f3d832ec4494ab670d6d14acfb71084ac158c4f57bf44883eb88e52df5056121d6c4e5a99160ff6a2733bbcda0c4fafc2a6fe893df354d5bda29349bfffb5a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e407426211c738eedc9bd3dda510cc40aae70a47f8eaa1dff6f1565f462e031cd1ddc44e3e03f37be5d49ccfa8e6efe2b8873ed9e6c70133fc93ecf0cf0dbfc7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:33:51.379831) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:33:51.380360) ========= 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.5uxOhoyzQW/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.eJNJbC9vTi/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.005308 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:33:51.433537) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:33:51.434266) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:33:51.434978) ========= ========= Total master_lag: 0.82 =========