builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0247 starttime: 1497028693.74 results: success (0) revision: 2d20b365eee19434657f6b365d310e8b70904d2b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:13.737286) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:13.737735) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:13.738015) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:13.763761) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:13.764072) ========= 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.FMtETx8hEC/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.vj0lsSXx32/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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-06-09 10:18:13-- 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% 151M=0s 2017-06-09 10:18:14 (151 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.560810 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:14.350899) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:14.351238) ========= 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.FMtETx8hEC/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.vj0lsSXx32/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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.024184 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:14.399442) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-09 10:18:14.399792) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2d20b365eee19434657f6b365d310e8b70904d2b --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 2d20b365eee19434657f6b365d310e8b70904d2b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FMtETx8hEC/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.vj0lsSXx32/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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-06-09 10:18:14,501 truncating revision to first 12 chars 2017-06-09 10:18:14,501 Setting DEBUG logging. 2017-06-09 10:18:14,501 attempt 1/10 2017-06-09 10:18:14,501 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2d20b365eee1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-09 10:18:15,775 unpacking tar archive at: mozilla-central-2d20b365eee1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.571859 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-09 10:18:15.993498) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:15.993878) ========= script_repo_revision: 2d20b365eee19434657f6b365d310e8b70904d2b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:15.994274) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:15.994550) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-09 10:18:16.013797) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-09 10:18:16.014478) ========= /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.FMtETx8hEC/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.vj0lsSXx32/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:18:16 INFO - MultiFileLogger online at 20170609 10:18:16 in /builds/slave/test 10:18:16 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 10:18:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:18:16 INFO - {'append_to_log': False, 10:18:16 INFO - 'base_work_dir': '/builds/slave/test', 10:18:16 INFO - 'blob_upload_branch': 'Firefox', 10:18:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:18:16 INFO - 'branch': 'Firefox', 10:18:16 INFO - 'buildbot_json_path': 'buildprops.json', 10:18:16 INFO - 'config_files': ('talos/mac_config.py',), 10:18:16 INFO - 'default_actions': ('clobber', 10:18:16 INFO - 'read-buildbot-config', 10:18:16 INFO - 'download-and-extract', 10:18:16 INFO - 'populate-webroot', 10:18:16 INFO - 'create-virtualenv', 10:18:16 INFO - 'install', 10:18:16 INFO - 'setup-mitmproxy', 10:18:16 INFO - 'run-tests'), 10:18:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:18:16 INFO - 'download_minidump_stackwalk': True, 10:18:16 INFO - 'download_symbols': 'ondemand', 10:18:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:18:16 INFO - 'tooltool.py': '/tools/tooltool.py', 10:18:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:18:16 INFO - '/tools/misc-python/virtualenv.py')}, 10:18:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:18:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:18:16 INFO - 'gecko_profile': False, 10:18:16 INFO - 'gecko_profile_interval': 0, 10:18:16 INFO - 'installer_path': 'installer.exe', 10:18:16 INFO - 'log_level': 'info', 10:18:16 INFO - 'log_name': 'talos', 10:18:16 INFO - 'log_to_console': True, 10:18:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:18:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:18:16 INFO - 'opt_config_files': (), 10:18:16 INFO - 'pip_index': False, 10:18:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:18:16 INFO - 'cmd': ('bash', 10:18:16 INFO - '-c', 10:18:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:18:16 INFO - 'enabled': True, 10:18:16 INFO - 'halt_on_failure': False, 10:18:16 INFO - 'name': 'check_screen_resolution'},), 10:18:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:18:16 INFO - 'cmd': ('bash', 10:18:16 INFO - '-c', 10:18:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:18:16 INFO - 'enabled': True, 10:18:16 INFO - 'halt_on_failure': False, 10:18:16 INFO - 'name': 'check_screen_resolution'},), 10:18:16 INFO - 'run_cmd_checks_enabled': True, 10:18:16 INFO - 'suite': 'dromaeojs-e10s', 10:18:16 INFO - 'system_bits': '32', 10:18:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:18:16 INFO - 'title': 't-yosemite-r7-0247', 10:18:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:18:16 INFO - 'use_talos_json': True, 10:18:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:18:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:18:16 INFO - 'work_dir': 'build'} 10:18:16 INFO - [mozharness: 2017-06-09 17:18:16.221550Z] Running clobber step. 10:18:16 INFO - Running pre-action listener: _resource_record_pre_action 10:18:16 INFO - Running main action method: clobber 10:18:16 INFO - rmtree: /builds/slave/test/build 10:18:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:18:18 INFO - Running post-action listener: _resource_record_post_action 10:18:18 INFO - [mozharness: 2017-06-09 17:18:18.548214Z] Finished clobber step (success) 10:18:18 INFO - [mozharness: 2017-06-09 17:18:18.548379Z] Running read-buildbot-config step. 10:18:18 INFO - Running pre-action listener: _resource_record_pre_action 10:18:18 INFO - Running main action method: read_buildbot_config 10:18:18 INFO - Using buildbot properties: 10:18:18 INFO - { 10:18:18 INFO - "project": "", 10:18:18 INFO - "product": "firefox", 10:18:18 INFO - "who": "ffxbld@noreply.mozilla.org", 10:18:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:18:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:18:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:18:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:18:18 INFO - "stage_platform": "macosx64", 10:18:18 INFO - "basedir": "/builds/slave/test", 10:18:18 INFO - "buildnumber": 84, 10:18:18 INFO - "platform": "yosemite_r7", 10:18:18 INFO - "parent_task_id": "PLMnlAH9Q_Sk8ZzwUksYsw", 10:18:18 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:18:18 INFO - "slavebuilddir": "test", 10:18:18 INFO - "taskId": "NO5wuWW5SN6a0R9EOlnWLg", 10:18:18 INFO - "branch": "mozilla-central", 10:18:18 INFO - "script_repo_revision": "production", 10:18:18 INFO - "revision": "2d20b365eee19434657f6b365d310e8b70904d2b", 10:18:18 INFO - "slavename": "t-yosemite-r7-0247", 10:18:18 INFO - "repo_path": "mozilla-central" 10:18:18 INFO - } 10:18:18 INFO - Finding installer, test and symbols from parent task. 10:18:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NO5wuWW5SN6a0R9EOlnWLg'}, attempt #1 10:18:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NO5wuWW5SN6a0R9EOlnWLg'}, attempt #1 10:18:19 INFO - Task dependencies: HQr4lL23TcOL_2Qw5_Bn-Q PLMnlAH9Q_Sk8ZzwUksYsw 10:18:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw'}, attempt #1 10:18:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:18:20 INFO - Set test_url: https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/test_packages.json 10:18:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/target.crashreporter-symbols.zip 10:18:20 INFO - Running post-action listener: _resource_record_post_action 10:18:20 INFO - [mozharness: 2017-06-09 17:18:20.261353Z] Finished read-buildbot-config step (success) 10:18:20 INFO - [mozharness: 2017-06-09 17:18:20.261474Z] Running download-and-extract step. 10:18:20 INFO - Running pre-action listener: _resource_record_pre_action 10:18:20 INFO - Running main action method: download_and_extract 10:18:20 INFO - mkdir: /builds/slave/test/build/tests 10:18:20 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')]} 10:18:20 INFO - trying https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:18:20 INFO - Downloading https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/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 10:18:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/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 10:18:20 INFO - Downloaded 1328 bytes. 10:18:20 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:18:20 INFO - Using the following test package requirements: 10:18:20 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:18:20 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:18:20 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:18:20 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:18:20 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'jsshell-mac.zip'], 10:18:20 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:18:20 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:18:20 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:18:20 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:18:20 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 10:18:20 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:18:20 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:18:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:18:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:18:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:18:20 INFO - Fetch https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:18:22 INFO - Content-Length response header: 59541605 10:18:22 INFO - Bytes received: 59541605 10:18:40 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:18:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:18:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:18:40 INFO - Fetch https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:18:42 INFO - Content-Length response header: 59541605 10:18:42 INFO - Bytes received: 59541605 10:19:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:19:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:19:00 INFO - Fetch https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:19:01 INFO - Content-Length response header: 14449999 10:19:01 INFO - Bytes received: 14449999 10:19:01 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')]} 10:19:01 INFO - trying https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:19:01 INFO - Downloading https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:19:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/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 10:19:03 INFO - Downloaded 69302742 bytes. 10:19:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:19:03 INFO - mkdir: /builds/slave/test/properties 10:19:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:19:03 INFO - Writing to file /builds/slave/test/properties/build_url 10:19:03 INFO - Contents: 10:19:03 INFO - build_url:https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:19:03 INFO - Running post-action listener: _resource_record_post_action 10:19:03 INFO - Running post-action listener: set_extra_try_arguments 10:19:03 INFO - [mozharness: 2017-06-09 17:19:03.579637Z] Finished download-and-extract step (success) 10:19:03 INFO - [mozharness: 2017-06-09 17:19:03.579796Z] Running populate-webroot step. 10:19:03 INFO - Running pre-action listener: _resource_record_pre_action 10:19:03 INFO - Running main action method: populate_webroot 10:19:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:19:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:19:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:19:03 INFO - '--tppagecycles', 10:19:03 INFO - '7'], 10:19:03 INFO - 'tests': ['tsvgm']}}, 10:19:03 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:19:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:19:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:19:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:19:03 INFO - 'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:19:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:19:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:19:03 INFO - 'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['damp', 'tps']}, 10:19:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:19:03 INFO - 'tests': ['damp', 'tps']}, 10:19:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['dromaeo_dom']}, 10:19:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:19:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:19:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:19:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:19:03 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:19:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['a11yr', 10:19:03 INFO - 'ts_paint', 10:19:03 INFO - 'tpaint', 10:19:03 INFO - 'sessionrestore', 10:19:03 INFO - 'sessionrestore_no_auto_restore', 10:19:03 INFO - 'tabpaint']}, 10:19:03 INFO - 'other-e10s': {'tests': ['a11yr', 10:19:03 INFO - 'ts_paint', 10:19:03 INFO - 'tpaint', 10:19:03 INFO - 'sessionrestore', 10:19:03 INFO - 'sessionrestore_no_auto_restore', 10:19:03 INFO - 'tabpaint']}, 10:19:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:19:03 INFO - 'ts_paint', 10:19:03 INFO - 'tpaint', 10:19:03 INFO - 'sessionrestore', 10:19:03 INFO - 'sessionrestore_no_auto_restore', 10:19:03 INFO - 'tabpaint']}, 10:19:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:19:03 INFO - 'ts_paint', 10:19:03 INFO - 'tpaint', 10:19:03 INFO - 'sessionrestore', 10:19:03 INFO - 'sessionrestore_no_auto_restore', 10:19:03 INFO - 'tabpaint']}, 10:19:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['a11yr', 10:19:03 INFO - 'ts_paint', 10:19:03 INFO - 'tpaint', 10:19:03 INFO - 'sessionrestore', 10:19:03 INFO - 'sessionrestore_no_auto_restore', 10:19:03 INFO - 'tabpaint']}, 10:19:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['a11yr', 10:19:03 INFO - 'ts_paint', 10:19:03 INFO - 'tpaint', 10:19:03 INFO - 'sessionrestore', 10:19:03 INFO - 'sessionrestore_no_auto_restore', 10:19:03 INFO - 'tabpaint']}, 10:19:03 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:19:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:19:03 INFO - 'bloom_basic_ref']}, 10:19:03 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 10:19:03 INFO - 'talos_options': ['--mitmproxy', 10:19:03 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 10:19:03 INFO - '--firstNonBlankPaint'], 10:19:03 INFO - 'tests': ['quantum_pageload_google', 10:19:03 INFO - 'quantum_pageload_youtube', 10:19:03 INFO - 'quantum_pageload_amazon', 10:19:03 INFO - 'quantum_pageload_facebook']}, 10:19:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['tsvgx', 10:19:03 INFO - 'tsvgr_opacity', 10:19:03 INFO - 'tart', 10:19:03 INFO - 'tscrollx', 10:19:03 INFO - 'cart', 10:19:03 INFO - 'tsvg_static']}, 10:19:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:19:03 INFO - 'tsvgr_opacity', 10:19:03 INFO - 'tart', 10:19:03 INFO - 'tscrollx', 10:19:03 INFO - 'cart', 10:19:03 INFO - 'tsvg_static']}, 10:19:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:19:03 INFO - 'talos_options': ['--disable-e10s'], 10:19:03 INFO - 'tests': ['tp5o']}, 10:19:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:19:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:19:03 INFO - 'talos_options': ['--disable-e10s', 10:19:03 INFO - '--xperf_path', 10:19:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:03 INFO - 'tests': ['tp5n']}, 10:19:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:19:03 INFO - 'talos_options': ['--xperf_path', 10:19:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:19:03 INFO - 'tests': ['tp5n']}}, 10:19:03 INFO - 'talos.zip': {'path': '', 10:19:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:19:03 INFO - Running post-action listener: _resource_record_post_action 10:19:03 INFO - [mozharness: 2017-06-09 17:19:03.602975Z] Finished populate-webroot step (success) 10:19:03 INFO - [mozharness: 2017-06-09 17:19:03.603100Z] Running create-virtualenv step. 10:19:03 INFO - Running pre-action listener: _resource_record_pre_action 10:19:03 INFO - Running main action method: create_virtualenv 10:19:03 INFO - Creating virtualenv /builds/slave/test/build/venv 10:19:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:19:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:19:03 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:19:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:19:03 INFO - Using real prefix '/tools/python27' 10:19:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:19:04 INFO - Installing distribute.............................................................................................................................................................................................done. 10:19:07 INFO - Installing pip.................done. 10:19:07 INFO - Return code: 0 10:19:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:19:07 INFO - Reading from file tmpfile_stdout 10:19:07 INFO - Output received: 10:19:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:19:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:07 INFO - 'HOME': '/Users/cltbld', 10:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:07 INFO - 'LOGNAME': 'cltbld', 10:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:07 INFO - 'MOZ_NO_REMOTE': '1', 10:19:07 INFO - 'NO_EM_RESTART': '1', 10:19:07 INFO - 'PAGER': '/bin/cat', 10:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:07 INFO - 'PWD': '/builds/slave/test', 10:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:07 INFO - 'SHELL': '/bin/bash', 10:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:07 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:07 INFO - 'USER': 'cltbld', 10:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:07 INFO - 'XPC_FLAGS': '0x0', 10:19:07 INFO - 'XPC_SERVICE_NAME': '0', 10:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:19:07 INFO - Cleaning up... 10:19:07 INFO - Return code: 0 10:19:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:19:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:19:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:07 INFO - 'HOME': '/Users/cltbld', 10:19:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:07 INFO - 'LOGNAME': 'cltbld', 10:19:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:07 INFO - 'MOZ_NO_REMOTE': '1', 10:19:07 INFO - 'NO_EM_RESTART': '1', 10:19:07 INFO - 'PAGER': '/bin/cat', 10:19:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:07 INFO - 'PWD': '/builds/slave/test', 10:19:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:07 INFO - 'SHELL': '/bin/bash', 10:19:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:07 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:07 INFO - 'USER': 'cltbld', 10:19:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:07 INFO - 'XPC_FLAGS': '0x0', 10:19:07 INFO - 'XPC_SERVICE_NAME': '0', 10:19:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:08 INFO - Downloading/unpacking psutil>=3.1.1 10:19:08 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 10:19:08 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 10:19:08 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 10:19:08 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 10:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:19:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:10 INFO - Installing collected packages: psutil 10:19:10 INFO - Running setup.py install for psutil 10:19:10 INFO - building 'psutil._psutil_osx' extension 10:19:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:19:11 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 10:19:11 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 10:19:11 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 10:19:11 INFO - building 'psutil._psutil_posix' extension 10:19:11 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 10:19:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:19:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:19:11 INFO - ^ 10:19:11 INFO - 1 warning generated. 10:19:11 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 10:19:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:19:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:19:11 INFO - Successfully installed psutil 10:19:11 INFO - Cleaning up... 10:19:11 INFO - Return code: 0 10:19:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:19:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:11 INFO - 'HOME': '/Users/cltbld', 10:19:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:11 INFO - 'LOGNAME': 'cltbld', 10:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:11 INFO - 'MOZ_NO_REMOTE': '1', 10:19:11 INFO - 'NO_EM_RESTART': '1', 10:19:11 INFO - 'PAGER': '/bin/cat', 10:19:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:11 INFO - 'PWD': '/builds/slave/test', 10:19:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:11 INFO - 'SHELL': '/bin/bash', 10:19:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:11 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:11 INFO - 'USER': 'cltbld', 10:19:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:11 INFO - 'XPC_FLAGS': '0x0', 10:19:11 INFO - 'XPC_SERVICE_NAME': '0', 10:19:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:19:13 INFO - Installing collected packages: mozsystemmonitor 10:19:13 INFO - Running setup.py install for mozsystemmonitor 10:19:13 INFO - Successfully installed mozsystemmonitor 10:19:13 INFO - Cleaning up... 10:19:13 INFO - Return code: 0 10:19:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:19:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:13 INFO - 'HOME': '/Users/cltbld', 10:19:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:13 INFO - 'LOGNAME': 'cltbld', 10:19:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:13 INFO - 'MOZ_NO_REMOTE': '1', 10:19:13 INFO - 'NO_EM_RESTART': '1', 10:19:13 INFO - 'PAGER': '/bin/cat', 10:19:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:13 INFO - 'PWD': '/builds/slave/test', 10:19:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:13 INFO - 'SHELL': '/bin/bash', 10:19:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:13 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:13 INFO - 'USER': 'cltbld', 10:19:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:13 INFO - 'XPC_FLAGS': '0x0', 10:19:13 INFO - 'XPC_SERVICE_NAME': '0', 10:19:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:13 INFO - Downloading/unpacking jsonschema==2.5.1 10:19:13 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 10:19:13 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 10:19:13 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 10:19:13 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 10:19:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:19:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:15 INFO - Downloading functools32-3.2.3-2.tar.gz 10:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:19:15 INFO - warning: no files found matching '*.txt' 10:19:15 INFO - no previously-included directories found matching 'build' 10:19:15 INFO - no previously-included directories found matching 'dist' 10:19:15 INFO - no previously-included directories found matching '.git*' 10:19:15 INFO - Installing collected packages: jsonschema, functools32 10:19:15 INFO - Running setup.py install for functools32 10:19:15 INFO - warning: no files found matching '*.txt' 10:19:15 INFO - no previously-included directories found matching 'build' 10:19:15 INFO - no previously-included directories found matching 'dist' 10:19:15 INFO - no previously-included directories found matching '.git*' 10:19:15 INFO - Successfully installed jsonschema functools32 10:19:15 INFO - Cleaning up... 10:19:15 INFO - Return code: 0 10:19:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:19:15 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')]} 10:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:15 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')]} 10:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:15 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 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:15 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 10:19:15 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 10:19:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:15 INFO - 'HOME': '/Users/cltbld', 10:19:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:15 INFO - 'LOGNAME': 'cltbld', 10:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:15 INFO - 'MOZ_NO_REMOTE': '1', 10:19:15 INFO - 'NO_EM_RESTART': '1', 10:19:15 INFO - 'PAGER': '/bin/cat', 10:19:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:15 INFO - 'PWD': '/builds/slave/test', 10:19:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:15 INFO - 'SHELL': '/bin/bash', 10:19:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:15 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:15 INFO - 'USER': 'cltbld', 10:19:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:15 INFO - 'XPC_FLAGS': '0x0', 10:19:15 INFO - 'XPC_SERVICE_NAME': '0', 10:19:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:19:16 INFO - Cleaning up... 10:19:16 INFO - Return code: 0 10:19:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:19: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')]} 10:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19: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')]} 10:19:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:16 INFO - 'HOME': '/Users/cltbld', 10:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:16 INFO - 'LOGNAME': 'cltbld', 10:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:16 INFO - 'MOZ_NO_REMOTE': '1', 10:19:16 INFO - 'NO_EM_RESTART': '1', 10:19:16 INFO - 'PAGER': '/bin/cat', 10:19:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:16 INFO - 'PWD': '/builds/slave/test', 10:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:16 INFO - 'SHELL': '/bin/bash', 10:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:16 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:16 INFO - 'USER': 'cltbld', 10:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:16 INFO - 'XPC_FLAGS': '0x0', 10:19:16 INFO - 'XPC_SERVICE_NAME': '0', 10:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:16 INFO - Downloading/unpacking blobuploader==1.2.4 10:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:17 INFO - Downloading blobuploader-1.2.4.tar.gz 10:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:19:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:19:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:18 INFO - Downloading docopt-0.6.1.tar.gz 10:19:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:19:18 INFO - Installing collected packages: blobuploader, requests, docopt 10:19:18 INFO - Running setup.py install for blobuploader 10:19:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:19:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:19:18 INFO - Running setup.py install for requests 10:19:19 INFO - Running setup.py install for docopt 10:19:19 INFO - Successfully installed blobuploader requests docopt 10:19:19 INFO - Cleaning up... 10:19:19 INFO - Return code: 0 10:19:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:19:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:19 INFO - 'HOME': '/Users/cltbld', 10:19:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:19 INFO - 'LOGNAME': 'cltbld', 10:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:19 INFO - 'MOZ_NO_REMOTE': '1', 10:19:19 INFO - 'NO_EM_RESTART': '1', 10:19:19 INFO - 'PAGER': '/bin/cat', 10:19:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:19 INFO - 'PWD': '/builds/slave/test', 10:19:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:19 INFO - 'SHELL': '/bin/bash', 10:19:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:19 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:19 INFO - 'USER': 'cltbld', 10:19:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:19 INFO - 'XPC_FLAGS': '0x0', 10:19:19 INFO - 'XPC_SERVICE_NAME': '0', 10:19:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:19 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-kETu7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:19 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-1vDSKY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-0FmziH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-KDrJif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-IfB3Mz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-dPSYuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-I7JXA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-LuJJLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-7XcmdV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-oFluHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-MA64OA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:19:20 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-m55ZNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:19:21 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-p_Y_E1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:19:21 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-GCdezL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:21 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-YdmcmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:19:21 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-Bd688o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:19:21 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-fm2BkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:19:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:19:21 INFO - Running setup.py install for manifestparser 10:19:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:19:21 INFO - Running setup.py install for mozcrash 10:19:21 INFO - Running setup.py install for mozdebug 10:19:21 INFO - Running setup.py install for mozdevice 10:19:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:19:22 INFO - Running setup.py install for mozfile 10:19:22 INFO - Running setup.py install for mozhttpd 10:19:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:19:22 INFO - Running setup.py install for mozinfo 10:19:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:19:22 INFO - Running setup.py install for mozInstall 10:19:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:19:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:19:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:19:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:19:22 INFO - Running setup.py install for mozleak 10:19:22 INFO - Running setup.py install for mozlog 10:19:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:19:23 INFO - Running setup.py install for moznetwork 10:19:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:19:23 INFO - Running setup.py install for mozprocess 10:19:23 INFO - Running setup.py install for mozprofile 10:19:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:19:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:19:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:19:23 INFO - Running setup.py install for mozrunner 10:19:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:19:23 INFO - Running setup.py install for mozscreenshot 10:19:23 INFO - Running setup.py install for moztest 10:19:23 INFO - Running setup.py install for mozversion 10:19:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:19:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:19:24 INFO - Cleaning up... 10:19:24 INFO - Return code: 0 10:19:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:19:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:24 INFO - 'HOME': '/Users/cltbld', 10:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:24 INFO - 'LOGNAME': 'cltbld', 10:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:24 INFO - 'MOZ_NO_REMOTE': '1', 10:19:24 INFO - 'NO_EM_RESTART': '1', 10:19:24 INFO - 'PAGER': '/bin/cat', 10:19:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:24 INFO - 'PWD': '/builds/slave/test', 10:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:24 INFO - 'SHELL': '/bin/bash', 10:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:24 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:24 INFO - 'USER': 'cltbld', 10:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:24 INFO - 'XPC_FLAGS': '0x0', 10:19:24 INFO - 'XPC_SERVICE_NAME': '0', 10:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:19:24 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-TpSQx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:19:24 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-buMgDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:19:24 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-OXLeaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:19:24 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)) 10:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:19:24 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-1QSeZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:19:24 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)) 10:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:19:24 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-nrm4aZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:19:24 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)) 10:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:19:24 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-3mRAtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-91r7mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-53NhXl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-ghwrlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-1Xw2Ww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-1pIsDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-Za52mc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:19:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-BqgRZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:19:25 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)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-ygQDEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:19:25 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)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-1ZuY0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:19:25 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)) 10:19:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:19:25 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-AL8Czq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:19:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:19:26 INFO - Running setup.py (path:/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/pip-qIse6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:19:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:27 INFO - Downloading blessings-1.6.tar.gz 10:19:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:19:27 INFO - Installing collected packages: blessings 10:19:27 INFO - Running setup.py install for blessings 10:19:27 INFO - Successfully installed blessings 10:19:27 INFO - Cleaning up... 10:19:27 INFO - Return code: 0 10:19:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:19:28 INFO - Reading from file tmpfile_stdout 10:19:28 INFO - Current package versions: 10:19:28 INFO - blessings == 1.6 10:19:28 INFO - blobuploader == 1.2.4 10:19:28 INFO - docopt == 0.6.1 10:19:28 INFO - functools32 == 3.2.3-2 10:19:28 INFO - jsonschema == 2.5.1 10:19:28 INFO - manifestparser == 1.1 10:19:28 INFO - mozInstall == 1.12 10:19:28 INFO - mozcrash == 1.0 10:19:28 INFO - mozdebug == 0.1 10:19:28 INFO - mozdevice == 0.51 10:19:28 INFO - mozfile == 1.2 10:19:28 INFO - mozhttpd == 0.7 10:19:28 INFO - mozinfo == 0.10 10:19:28 INFO - mozleak == 0.1 10:19:28 INFO - mozlog == 3.4 10:19:28 INFO - moznetwork == 0.27 10:19:28 INFO - mozprocess == 0.25 10:19:28 INFO - mozprofile == 0.28 10:19:28 INFO - mozrunner == 6.13 10:19:28 INFO - mozscreenshot == 0.1 10:19:28 INFO - mozsystemmonitor == 0.3 10:19:28 INFO - moztest == 0.8 10:19:28 INFO - mozversion == 1.4 10:19:28 INFO - psutil == 3.1.1 10:19:28 INFO - requests == 1.2.3 10:19:28 INFO - wsgiref == 0.1.2 10:19:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:28 INFO - 'HOME': '/Users/cltbld', 10:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:28 INFO - 'LOGNAME': 'cltbld', 10:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:28 INFO - 'MOZ_NO_REMOTE': '1', 10:19:28 INFO - 'NO_EM_RESTART': '1', 10:19:28 INFO - 'PAGER': '/bin/cat', 10:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:28 INFO - 'PWD': '/builds/slave/test', 10:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:28 INFO - 'SHELL': '/bin/bash', 10:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:28 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:28 INFO - 'USER': 'cltbld', 10:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:28 INFO - 'XPC_FLAGS': '0x0', 10:19:28 INFO - 'XPC_SERVICE_NAME': '0', 10:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:19:28 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:19:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:19:29 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:19:29 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)) 10:19:29 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)) 10:19:29 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)) 10:19:29 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)) 10:19:29 INFO - Installing collected packages: marionette-driver 10:19:29 INFO - Running setup.py install for marionette-driver 10:19:30 INFO - Successfully installed marionette-driver 10:19:30 INFO - Cleaning up... 10:19:30 INFO - Return code: 0 10:19:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:19:30 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')]} 10:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:19:30 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')]} 10:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:19:30 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 0x10d584c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d588768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb719d28670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, '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.FMtETx8hEC/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.vj0lsSXx32/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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 10:19:30 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 10:19:30 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 10:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:30 INFO - 'HOME': '/Users/cltbld', 10:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:30 INFO - 'LOGNAME': 'cltbld', 10:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:30 INFO - 'MOZ_NO_REMOTE': '1', 10:19:30 INFO - 'NO_EM_RESTART': '1', 10:19:30 INFO - 'PAGER': '/bin/cat', 10:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:30 INFO - 'PWD': '/builds/slave/test', 10:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:30 INFO - 'SHELL': '/bin/bash', 10:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:30 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:30 INFO - 'USER': 'cltbld', 10:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:30 INFO - 'XPC_FLAGS': '0x0', 10:19:30 INFO - 'XPC_SERVICE_NAME': '0', 10:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:19:30 INFO - Cleaning up... 10:19:30 INFO - Return code: 0 10:19:30 INFO - Running post-action listener: _resource_record_post_action 10:19:30 INFO - Running post-action listener: _start_resource_monitoring 10:19:30 INFO - Starting resource monitoring. 10:19:30 INFO - [mozharness: 2017-06-09 17:19:30.424262Z] Finished create-virtualenv step (success) 10:19:30 INFO - [mozharness: 2017-06-09 17:19:30.425189Z] Running install step. 10:19:30 INFO - Running pre-action listener: _resource_record_pre_action 10:19:30 INFO - Running main action method: install 10:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:19:30 INFO - Reading from file tmpfile_stdout 10:19:30 INFO - Detecting whether we're running mozinstall >=1.0... 10:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:19:30 INFO - Reading from file tmpfile_stdout 10:19:30 INFO - Output received: 10:19:30 INFO - Usage: mozinstall [options] installer 10:19:30 INFO - Options: 10:19:30 INFO - -h, --help show this help message and exit 10:19:30 INFO - -d DEST, --destination=DEST 10:19:30 INFO - Directory to install application into. [default: 10:19:30 INFO - "/builds/slave/test"] 10:19:30 INFO - --app=APP Application being installed. [default: firefox] 10:19:30 INFO - mkdir: /builds/slave/test/build/application 10:19:30 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'] 10:19:30 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 10:19:52 INFO - Reading from file tmpfile_stdout 10:19:52 INFO - Output received: 10:19:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:19:52 INFO - Running post-action listener: _resource_record_post_action 10:19:52 INFO - [mozharness: 2017-06-09 17:19:52.531294Z] Finished install step (success) 10:19:52 INFO - [mozharness: 2017-06-09 17:19:52.531394Z] Running setup-mitmproxy step. 10:19:52 INFO - Running pre-action listener: _resource_record_pre_action 10:19:52 INFO - Running main action method: setup_mitmproxy 10:19:52 INFO - Skipping: mitmproxy is not required 10:19:52 INFO - Running post-action listener: _resource_record_post_action 10:19:52 INFO - [mozharness: 2017-06-09 17:19:52.531989Z] Finished setup-mitmproxy step (success) 10:19:52 INFO - [mozharness: 2017-06-09 17:19:52.532085Z] Running run-tests step. 10:19:52 INFO - Running pre-action listener: _resource_record_pre_action 10:19:52 INFO - Running main action method: run_tests 10:19:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:19:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:19:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:19:52 INFO - 2017-06-09 10:19:52.546 screenresolution[1631:7873] starting screenresolution argv=screenresolution get 10:19:52 INFO - 2017-06-09 10:19:52.549 screenresolution[1631:7873] Display 0: 1600x1200x32@60 10:19:52 INFO - 2017-06-09 10:19:52.555 screenresolution[1632:7875] starting screenresolution argv=screenresolution list 10:19:52 INFO - Available Modes on Display 0 10:19:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:19:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:19:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:19:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:19:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:19:52 INFO - Intel Iris: 10:19:52 INFO - Chipset Model: Intel Iris 10:19:52 INFO - Type: GPU 10:19:52 INFO - Bus: Built-In 10:19:52 INFO - VRAM (Dynamic, Max): 1536 MB 10:19:52 INFO - Vendor: Intel (0x8086) 10:19:52 INFO - Device ID: 0x0a2e 10:19:52 INFO - Revision ID: 0x0009 10:19:52 INFO - Displays: 10:19:52 INFO - Display: 10:19:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:19:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:19:52 INFO - Display Serial Number: 1600x1200 60 10:19:52 INFO - Main Display: Yes 10:19:52 INFO - Mirror: Off 10:19:52 INFO - Online: Yes 10:19:52 INFO - Rotation: Supported 10:19:52 INFO - Return code: 0 10:19:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:19:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:19:52 INFO - Python 2.7.3 10:19:52 INFO - Return code: 0 10:19:52 INFO - grabbing minidump binary from tooltool 10:19:52 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')]} 10:19:52 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 0x10d2dad30>, '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 0x10d2a4cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:19:52 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 10:19:52 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 10:19:52 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 10:19:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:19:52 INFO - Return code: 0 10:19:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:19:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:19:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:19:52 INFO - ENV: RUST_BACKTRACE is now 1 10:19:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:19:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:19:52 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/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--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 10:19:52 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/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0247 --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 10:19:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FMtETx8hEC/Render', 10:19:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:19:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:19:52 INFO - 'HOME': '/Users/cltbld', 10:19:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:19:52 INFO - 'LOGNAME': 'cltbld', 10:19:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:19:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:19:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:19:52 INFO - 'MOZ_NO_REMOTE': '1', 10:19:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:19:52 INFO - 'NO_EM_RESTART': '1', 10:19:52 INFO - 'PAGER': '/bin/cat', 10:19:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:19:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:19:52 INFO - 'PWD': '/builds/slave/test', 10:19:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:19:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:19:52 INFO - 'RUST_BACKTRACE': '1', 10:19:52 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:19:52 INFO - 'SHELL': '/bin/bash', 10:19:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vj0lsSXx32/Listeners', 10:19:52 INFO - 'TMPDIR': '/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/', 10:19:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:19:52 INFO - 'USER': 'cltbld', 10:19:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:19:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:19:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:19:52 INFO - 'XPC_FLAGS': '0x0', 10:19:52 INFO - 'XPC_SERVICE_NAME': '0', 10:19:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:19:52 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/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0247', '--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 10:19:53 INFO - mozversion application_buildid: 20170609083226 10:19:53 INFO - mozversion application_changeset: 2d20b365eee19434657f6b365d310e8b70904d2b 10:19:53 INFO - mozversion application_display_name: Nightly 10:19:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:19:53 INFO - mozversion application_name: Firefox 10:19:53 INFO - mozversion application_remotingname: firefox 10:19:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:19:53 INFO - mozversion application_vendor: Mozilla 10:19:53 INFO - mozversion application_version: 55.0a1 10:19:53 INFO - mozversion platform_buildid: 20170609083226 10:19:53 INFO - mozversion platform_changeset: 2d20b365eee19434657f6b365d310e8b70904d2b 10:19:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:19:53 INFO - mozversion platform_version: 55.0a1 10:19:53 INFO - using testdate: 1497028793 10:19:53 INFO - actual date: 1497028793 10:19:53 INFO - starting webserver on 'localhost:49229' 10:19:53 INFO - SUITE-START | Running 2 tests 10:19:53 INFO - TEST-START | dromaeo_css 10:19:53 INFO - operating with platform_type : mac_ 10:19:53 INFO - Initialising browser for dromaeo_css test... 10:19:53 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpp3OQNJ/profile 10:19:53 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 10:20:27 INFO - TEST-INFO | 1642: killed by SIGTERM 10:20:27 INFO - Browser initialized. 10:20:27 INFO - Running cycle 1/1 for dromaeo_css test... 10:20:27 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpp3OQNJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:20:29 INFO - PID 1650 | 2017-06-09 10:20:29.186 plugin-container[1651:8371] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 10:20:29 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:20:29 INFO - PID 1650 | 2017-06-09 10:20:29.187 plugin-container[1651:8371] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 10:20:29 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:20:29 INFO - PID 1650 | 2017-06-09 10:20:29.187 plugin-container[1651:8371] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:20:29 INFO - PID 1650 | 2017-06-09 10:20:29.710 plugin-container[1652:8418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 10:20:29 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:20:29 INFO - PID 1650 | 2017-06-09 10:20:29.711 plugin-container[1652:8418] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 10:20:29 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:20:29 INFO - PID 1650 | 2017-06-09 10:20:29.711 plugin-container[1652:8418] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:20:39 INFO - PID 1650 | RSS: Main: 254119936 10:20:39 INFO - PID 1650 | 10:23:41 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 10:23:42 INFO - PID 1650 | RSS: Main: 262856704 10:23:42 INFO - PID 1650 | 10:26:44 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 10:26:45 INFO - PID 1650 | RSS: Main: 275922944 10:26:45 INFO - PID 1650 | 10:29:47 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 10:29:47 INFO - PID 1650 | RSS: Main: 275542016 10:29:47 INFO - PID 1650 | 10:32:49 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 10:32:50 INFO - PID 1650 | RSS: Main: 280104960 10:32:50 INFO - PID 1650 | 10:35:53 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 10:35:53 INFO - PID 1650 | RSS: Main: 279183360 10:35:53 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 10:38:55 INFO - PID 1650 | RSS: Main: 278102016 10:38:55 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | __start_tp_report 10:38:55 INFO - PID 1650 | _x_x_mozilla_page_load 10:38:55 INFO - PID 1650 | _x_x_mozilla_page_load_details 10:38:55 INFO - PID 1650 | |i|pagename|runs| 10:38:55 INFO - PID 1650 | |0;dojo.html;3232.272738633808;3486.936484372266;3601;3621.782693038418;3624.383854744693;6029.005214139662;6071.7571297148115;6170.321264660889;6174.320824709278;6187.845303867405;6096.390360963901;6098.725557349919;6127.953177891112;6129.4789942854795;6196.070589411579;5340.519353258196;5390.9191362129595;5407.783688652449;5437.34751829781;5513.42109078547;4499.527549607294;5308.150695888658;5456.781728730846;5494.697791621459;5511.834644960638;4384.693071484998;5404.32445944257;5480.588955828309;5486.807961721341;5522.5581953443825;5262.605304602132;5328.600354973392;5379.488948549882;5396.1905714143195;5435.239066530688;5351.598630102719;5386.003589335985;5387.084195686703;5408.188771684239;5415.187721841716;6123.050927106306;6152.753889844401;6174.5060395168275;6190.288116866573;6205.286392064925;6310.6213627182515;6395.104685344056;6417.294097649255;6501.674916254168;6519.315471875432;5590.273264475592;6660.966695166541;6669.599824010526;6672.466202703772;6707.329267073303;5355.0896347621;6197.81406557804;6235.345288744707;6253.249610046823;6258.968705156491;6539.411452969255;6654.401103900672;6676.53264271506;6826.283240259801;6860;6180.690965451709;6368.235811702625;6391.648459334689;6438;6454.19322584677;6433.774817881352;6462.353764623594;6608.3391660834495;6623.403893649498;6679.933200668028;6501.47988160946;6515.185601799775;6517.511186660925;6544.738210471528;6560.376764207488;6310.4005119514495;6345.650989195638;6406.071119687619;6484.740610375627;6543;5204.947950520446;5234.18870075139;5249.580033597303;5263.7104959226845;5284.497972692588;4656.4877863434995;4699.671023028423;4721.244600863914;4729.881752956135;4731.834385796481;5112.923306150411;6121.632702037892;6251.156093927261;6259.937400625936;6260.154879091265;6448.065030570635;6556.0493728410065;6612.8346791330605;6614.404703576702;6642.402183803384;6241.594296370721;6243.625382477066;6248.625082495065;6276.780312689034;6281.96859015702;5755;5831.970840145772;5839.211706419579;5850.47345738877;5901.704914754245;5804.216430781959;6346.984482482781;6561.245456772533;6587.473002159912;6644.501662375438;6497.220333560003;6583.059580034279;6611.768588099393;6641.837678406357;6668.533202675961;6760.661966901537;6776.593404395752;6776.93223067763;6781.152355955505;6786.287439818748;5494.670319780826;6282.591631543935;6286.811395658137;6378.425941665087;6425.646589437626;6568.441682457044;6601.042848786803;6626.536142470173;6632.535722499571;6635.867282654223;6234.1583886174785;6368.554201205871;6412.551121421456;6417.197850268716;6418.679066046772;6214.627122372672;6260.966940454774;6327.3988971047675;6360.427561519486;6370.3629637037775;6164.815055548162;6225.657588832658;6259.405356491126;6261.217347831521;6292.2134733158355;6398.232212134573;6403.96798016007;6433;6450.516211284266;6456.515761317826;5280.234366016983;6157.53818463626;6197.1324014638885;6236.906446403217;6266.467350275278 10:38:55 INFO - PID 1650 | |1;ext.html;788.479603461715;814.7881550796793;833.579042583495;1242.6147894152807;1298.234041915271;31605.683943160562;39169;39829;40007.79996100023;40115.79942100289;40562.79718601403;40718.79640601793;40919;41022.79488602553;41320.38019429711;7815.413843961712;8169.795755106111;8397;8398.03422606399;8400.369972252105;8287.378446616498;8425.620847061897;8433.915660843375;8444.408891377607;8461.957690211571;8289.378296627747;8345;8370;8414.158584141567;8454.957725211365;8380.916190838105;8411.957940210259;8426.325893928471;8444.240018398312;8467.915320846774;8257.95871020647;8285.875711864326;8352.33181345497;8372.28835548979;8384.203500667427;38023.80988095069;38192;38198.23603527914;38201.617983820084;38329.80835095807;3548.4854696068924;3551.289742051574;3586.067622418164;3594.478800573902;3606.278744251134;30966;31233.219169520715;31237.687623123933;31291.217719556964;31301.843490782627;3037.51399776037;3053.6946305369283;3073.139520934142;3093.8298393588343;3109.409212249688;197945;204736.92894606592;242611;297708;300517;39842.800785996464;49220.50779492159;49769;49981.75009124931;50026.74986625044;50758;51126;51260;53767;54906.17640735392;12396.75206495865;14353.354099065567;14429.567112986628;14545.78181327281;14578.052426592238;11415.37215453158;11516.30902145874;11546.960773530254;11629.418529073513;11666;10751.516181771683;13937.163770173822;14153.716925661429;14250.71743543086;14300.928495357664;5421.83734487966;5917.520619845039;6201.937980620226;6279.372062793684;6289.465395441347;985.7979114281699;990.2127808392345;997.2769741937183;1024.7130803375067;1050.6217761605815;23288.4177895554;28533.42933141367;30733.846330768203;31853;32108;1057.8201705709894;1087.2226358153957;1103.5309993252836;1127.7067962329854;1133.3766428464312;11023.06303964172;12114.527596023381;12933.353332333534;13094.803577946339;13096.083274170907;329791;333883;334901;338215;343347;52911.94176116456;53624;53962;54330.18504722353;54818.451815481334;1441.7549016667192;1446.16845313947;1489.5680252726588;1500.9774853377207;1521.3078049487679;22045.559088818456;22127.565967743856;22533.21133760376;23234.767652323262;24048;44618.884527887065;52279.477205227464;52626.47373526216;53541.46458535365;53869.730651346494;291492;292560;292680;297291;300431;36730.8163459181;36748;36942.076448089014;37711.81144094262;38320;36977.815110924275;44571;46971;47338.05323893434;48323.516764831904;29416;29582.40835183241;29890.551641725826;30573.54139687951;31129.377412451173;25492.362690932132;29609;29972.85013574918;31460.842695786374;31992.840035799672;32260;36813.07967300839;37349.81325093357;38755.41866871943;38857.6114238854;27950;31666.366672666878;32394;32646;33209.66790332066;18072;24457.633135502627;24573.648054956724;24686.382840429134;25288.74711252864 10:38:55 INFO - PID 1650 | |2;jquery.html;410.1612203044774;413.4728221517565;466.1841776890442;487.49300727243667;496.2580941492469;21223.68164477534;21911.452213694647;22419.215327463542;23231.07075716969;24245.39386515338;24188.879055604742;24896.75103248967;24929.002839886427;25051.498970020635;25599;6039.1243269725865;6188.381161883821;6190.78332258371;6366.331535188808;6378.043293505965;5799.507041901444;6134.631922084667;6208.255009398878;6352.968235158818;6365.681715914209;6109.541784366168;6118.021116621341;6121.938780612204;6283.214598175228;6289.245290565139;6098.237720284965;6110.786122485736;6123.142760013601;6257.624542527463;6261.561690681631;5623.437656234385;5636.351819540764;5684.857878553028;5695.40198279183;5814.186013958049;29248.415031699245;29628.407431851458;30084.8495757522;30221.848890755624;30681.693183068106;1819.927202911882;2090.2684060578827;2132.3176583493287;2153.8276937844935;2176.064292354287;18746.343877964206;19263.940483273414;19448.22207111714;19820.60358792816;19923.601527969506;2122.734658167729;2186.202036256755;2188.485705859122;2236.709227800392;2256.638937769965;131815;158616;179086.10456947633;182942;199417;14339.659462360467;15217.771733424008;15344.616384590252;15388.999715018488;15425.537233883002;19730.40808775739;19847.900760496104;19864.00679965996;19889.701654475193;19915.203391864452;9610.90389096114;9652.324337296463;9709.271804614682;9715.319927605;9965.950170249102;8920.286377089817;8933.553322333857;8970.955145224232;9063.096749682509;9235.492047937294;8921.15249051339;9211.078892110736;9288.489133097744;9306.67426640078;9489.715308540755;2545.263841370534;2560.8975640974154;2560.9372110574277;2619.1095027690676;2682.9000109954823;718.683779137178;852.2883392367319;918.4076270805293;929.6095639831152;949.838527450335;16459.930104543408;20184.495387615134;20938.162473501194;22024.678519288893;22150;912.2337236721054;962.5235508423375;975.5561219645044;997.0727223682045;997.7605374710163;10143.884172740993;11955.760884782256;14406.639834004234;14509.782353264709;14767.556973290819;582786;583462;585910;590029;590766;165468;167571.1621441885;167902.16048919677;171475.1426242861;172510.13744931194;998.4523987818852;1048.5229220704562;1145.3013661666378;1178.8762179971152;1242.776300265979;21241.256556021082;21956;25025.249242522754;25411.618825717258;26076.217713468628;70192.29807701858;70553;71201.64399177971;71567.28432715815;72911;91329.6300555479;92752;94396;94620;95364.52317738367;11597.76804463923;11598.768024639628;11607;11631.76736465283;11886.762264754829;18085.09574521295;18500.444986650422;18555.90722046381;18810.529736756143;18976.810231897503;12358.320292383645;14622.780658290347;14944.252787360805;15067.472638458039;15088.924555377593;13220.008499362393;13420.93289533585;13498.460061597427;13622.93188534051;13638.590842274747;14845.628859278604;14949.402023919356;15250.237488125327;15343.309551070006;15485.535433937;9102.544872756203;11460.713482163013;11500.194986351209;11518.712032199262;11540.653780386601;12408.62774116778;14871.47949821751;14882.851171488146;14940.775888361466;15034.774478382615 10:38:55 INFO - PID 1650 | |3;mootools.html;1415.313572917135;1426.9785953210696;1464.816897887764;1492.7237212183584;1509.4113295814632;11400.657980260605;11459.59891403801;12801.295928723917;13391.866081339183;13403.865961340383;12312.445939932702;13072;13178.143420677674;13193.010524210675;13208.603741887759;2605.6612640356725;2654.86725663717;2660.5610074337806;2663.2942270298386;2668.146193218171;2201.933941981743;2517.8363406378617;2625.12058460416;2659.7074321824584;2699.4601079784024;2263.2644390573046;2568.4734629401023;2652.2971412575685;2673.719259477756;2680.383511792289;2611.2557920992426;2642.484715480482;2649.6952850422254;2653.5621622432277;2666.706662267149;2146;2596.2860213441268;2613.9346516337246;2615.4246065865477;2642.4318771464227;12806.487740490462;12822.102452828305;13004.544840930523;13159.736805263938;13350.46598136083;7032.824179395505;7308.561486310785;7319.377852882511;7349.191588925214;7462.85074298511;4435.68950173488;4519.412476378083;4547.886302842422;4769.451513075971;4807.975960120177;1166.5567084507918;1342.8254326937533;1369.8150749648769;1372.060138804912;1375.7523645743763;19272;19409.93245371504;19411.708824367506;19418.70871936922;19431.125599348063;6521.445677117498;6606.8017959461295;6754.020667003355;6778.8305292367095;7011.333923277382;15859.920700396424;15883;15911.840881591268;15914.840851591567;15982.680346393006;7284.745033923788;7320.597367144752;7378.704851805975;7380;7592.544447333178;6414.358564143642;6568.376004279586;6608.140941677551;6623.172103487064;6643.272749085273;5219.634625576249;5276.498732620395;5280.128778751463;5290.6296559241255;5434.728263586884;1344.327836081959;1456.1117718191895;1507.5402002389158;1616.474645740139;1656.8094669112966;685.5920727167328;794.9483283586549;823.3495538524498;835.7910522369408;836.3685417509741;7206.56760594366;7364.153122390887;7620.657070431844;7703.807404814925;7929.643166057541;774.6978678315461;782.2998416417274;942.1002942190192;961.3895176562933;1018.0938964321759;4603.562661547147;4824.131656301899;4840.83057093;5025.296458495814;5085.466026067284;15493.1408231013;16879.662406751795;17283;17415.390461334046;17525.7371139433;17105.91447042782;17111.14444277781;17284.395046173322;17425.12874356277;17469.563260918578;9106;9171.715959765637;9181.632734690671;9287.58205880737;9565.139137477661;6611.471082313499;7793.922060779547;7884.290413862551;8092.190780921861;8275.255227029596;15805.762913556537;15841;15933.840661593236;16006.359745610045;16699;15636.609084772972;15898.205089745697;16378.672426551164;16709.582260443585;16830.242639081516;8897.2882169425;9300.302477314093;10626.512288279802;10782.514786834454;10841.241113122005;11865.347405892759;11929.105317101079;12019.63941081769;12019.75960480803;12042;9772.9022709772;9938.652147174815;9957.452340121363;9959.751006224613;10153.847692284475;9703.223742100756;9788.923218446107;9828.803423931624;9835.754106147117;9839.557219925264;8615.741527754177;8878.556072196236;8893.555322233733;8937.73186804397;9023.774405639911;6427.014714636466;6612.305707900601;6655.667216639052;6805.65971701403;6903.792886213421;6633.535652504278;6740.513553484269;6742.190937087385;6843.178818541608;7060.6469676517 10:38:55 INFO - PID 1650 | |4;prototype.html;415.7734034950951;425.4575416344161;438.35342869267834;460.33021953058295;460.5279588421867;6078.118672792442;6212.534059945499;6265.279492858323;6268.498520118385;6294.653794041325;5633.746481408336;5802.92645860515;6061.727222274997;6069.453749162574;6102.694865256731;4561.635069194473;4581.1753884300815;4621.445426548818;4651.627869770418;4661.347411362395;4596.03483268514;4603.930941035887;4611;4626.976865115686;4730.172219861511;3718.3864662330684;4559.703619264754;4561.977190114045;4569.588737013668;4695.3426520287185;3911.7677931451785;4570.337301091323;4573.908521829578;4583.289590113521;4616.522712731927;3937.1731936293413;4018.578049304839;4036.777977211252;4054.351303791379;4123.525794533637;4960.933399319128;5756.762296106315;5922;6125.5099592032975;6202.255729312466;4448.5551444855255;5422.322209723785;5444.428335024805;5450.255918106208;5580.86047848803;5701.204120701966;6585.176852893388;6635.4500342317115;6663.866722665568;6751.729930802762;4185.518665353462;4552.135094332067;4558.407407037064;4581.381513495702;4680.4149481314835;6285.340039295854;6503.772367967145;6643.966780166068;6793;7188.993540904277;4593.081383723269;4645.39609850724;4653.022865198346;4654.743989080565;4808.759562021885;4753.096911586787;4775.355327030876;4775.880602984953;4780.139574876486;4915.90168196634;5281.947180528223;5389.784408623611;5414.864628384243;5414.917016596696;5430.266913966643;5449.6730196188355;5458.7990642058685;5720.256366672306;6785.355391237825;6826.419754320938;1405.8261351771384;1607.4615003973565;1644.2354305247877;1647.2998975435391;1684.941027064047;1076.364944682641;1078.4823284823328;1111.0445615716396;1119.9384033878052;1143.9199256052163;5417.185242943658;6243.875122497525;6251.343608921089;6252.374762523711;6364.109024736541;6848.3494068063455;6850.725970961105;6853.348931851466;6856.588604683635;6881.621510816954;5412.83761487156;6227.408396202353;6279.937200628026;6282;6352.237731472253;3539.663731945461;3586.6951309139017;3600.3159399714496;3612.7651702639246;3677.7609455385314;5493.615446918758;7040.612766297801;7058.929410705828;7072.287049299056;7130.643467826588;5325;5446.782128714886;7062.435005199572;7085.787426377217;7231.927680723231;6885.51801373899;7085.999999999897;7090.361867431956;7093.467989900675;7095.303784561834;6850.554713943578;6858.862822743617;6861.039454476278;6865.485088618473;6883;5708.714564271853;6202.658853762906;6724.361185687352;6732.798016059526;6742.561733487308;6089.634621922699;6695.598264104169;6854.760083397057;6981.301869812978;7080.716771329089;4660.510646382081;4673.532646735299;4702.905941881075;4787.67797618148;4795.664303498722;5603.971980140236;6602.834929126618;6824.669189507998;6840.213375461686;6866.725330986705;6538.901916471162;6543.803685889431;6586.176727909012;6625.469962403093;6762.734483947933;6552.803415897531;6556.246031706224;6578.76974305897;6587.242467116343;6735.663216839041;6372.490200784019;6378.425941665272;6408.455281301141;6441.259255185526;6471.449926756279;5556.444355564411;6081.6655083969035;6653.767118150841;6656.567323123982;6695;5124;5991.951408503407;6403.295637479967;6431.646259455775;6495.74017039332 10:38:55 INFO - PID 1650 | |5;yui.html;784.7371130671224;798.3054742374131;814.3403842887261;833.7202394324709;848.2450618949134;13915.791263131036;14512.419503219859;16072.678546429064;16535.917320413395;16586.33654653814;15543.689126217498;17080.914595427035;17103.059331736782;17143.742843857322;17524.123793810322;943.9905600943971;951.8857737071527;954.6038394066454;962.2638681408728;968.5205823117567;964.2719746591314;987.1559816357019;989.9307048506571;993.538004827755;995;921.3642586615214;1036.0313107244713;1038.91688664907;1047.4657924458504;1047.7171163785774;1017.6031347774374;1028.28534168752;1036.7874585709912;1040.9427481488515;1041.1306559023187;735.5733674468795;741.4661443760538;753.3822265742093;753.6721526136148;753.7324249891265;16237.675246495004;16551.08969006704;16694.749578756328;16862.578435539086;17231.31074757008;16874.325026998904;16942.37393204043;17077;17318.913405432893;17373.3050677974;14353.856461435356;14391.856081439157;14490.058146220568;14504.56486305402;14554.053986490844;3475.5481787367735;3555.4311310190506;3567.0725611340977;3579.3020361029608;3582.0507565495163;158361;160569;160631;161718;163349;13347.332633368293;13474.19154850712;13484.460621575223;13621.727565448635;13732.65668358279;24812.627810582857;25275.862586183666;26202;26292;26696.733032669814;6433.292337842833;7789.80525486856;7834.1774113718375;7842.921570784333;7843.37253019757;3160.920976975548;3296.0606227225126;3709.573399059089;3828.9617103829164;3830.597787232356;4399.318105693623;4496.505384407713;4498.032922921588;4525.773711314421;4591.885202869888;934.3879758758025;959.8320293948531;960.6781728121018;970.7476056225291;972.5477652891298;571.3001573072952;591.9940800592025;605.6578033411108;622.5393209025289;628.3433811666819;10802.243842931077;11569.6529104127;11738.413079345999;12302.01583873288;12335.383230838423;536.8201652446475;584.7076461769116;620.5066971757464;648.7307767276621;660.5904339309659;5322.2548843161985;5603.411641777636;5684.6589204647025;5774.82675519735;5896.823095307148;97022;107015;120569.58860822735;121170.78829211595;124321;93236.06763932273;108559;112488;112489;112753;62656;74182.62908685423;96808;98731.01268987362;100977;10216.489175541343;10472.895271047191;11030.779384412106;11121.443927803415;11157.10743140563;41832;44432.66701998945;45026.77486612546;45611;45756.542434575225;64904.35095649172;83346.58326708328;86986.82532936728;88722;89355;17367.565810854325;17386.13069346553;17396.391126311024;17434.825651743828;17578.296868125133;18027.819721802614;18264.45206643803;18802.965836879102;19048.142833572245;19048.904755476135;13719.862801371859;16935.23791429364;17298;17397.695172861833;17418.47744567665;16250;16628.16859157013;17312;17660.02869842171;17730.645387092445;16710.24803883804;16797.496075117768;16802.327906883587;16988.320467181173;17139.628829693844;10850.186236032487;11278.605248816251;11288.774224515299;11456.427178641201;11590.478428470571;12566.685832854251;12617.621471355507;13096.803547946598;13119.27843968591;13393.799093013418 10:38:55 INFO - PID 1650 | __end_tp_report 10:38:55 INFO - PID 1650 | __start_cc_report 10:38:55 INFO - PID 1650 | _x_x_mozilla_cycle_collect,459 10:38:55 INFO - PID 1650 | __end_cc_report 10:38:55 INFO - PID 1650 | __startTimestamp1497029935823__endTimestamp 10:38:55 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | ------- Summary: start ------- 10:38:55 INFO - PID 1650 | Number of tests: 6 10:38:55 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | [#0] dojo.html Cycles:165 Average:6021.29 Median:6258.97 stddev:703.58 (11.2%) stddev-sans-first:670.86 10:38:55 INFO - PID 1650 | Values: 3232.3 3486.9 3601.0 3621.8 3624.4 6029.0 6071.8 6170.3 6174.3 6187.8 6096.4 6098.7 6128.0 6129.5 6196.1 5340.5 5390.9 5407.8 5437.3 5513.4 4499.5 5308.2 5456.8 5494.7 5511.8 4384.7 5404.3 5480.6 5486.8 5522.6 5262.6 5328.6 5379.5 5396.2 5435.2 5351.6 5386.0 5387.1 5408.2 5415.2 6123.1 6152.8 6174.5 6190.3 6205.3 6310.6 6395.1 6417.3 6501.7 6519.3 5590.3 6661.0 6669.6 6672.5 6707.3 5355.1 6197.8 6235.3 6253.2 6259.0 6539.4 6654.4 6676.5 6826.3 6860.0 6180.7 6368.2 6391.6 6438.0 6454.2 6433.8 6462.4 6608.3 6623.4 6679.9 6501.5 6515.2 6517.5 6544.7 6560.4 6310.4 6345.7 6406.1 6484.7 6543.0 5204.9 5234.2 5249.6 5263.7 5284.5 4656.5 4699.7 4721.2 4729.9 4731.8 5112.9 6121.6 6251.2 6259.9 6260.2 6448.1 6556.0 6612.8 6614.4 6642.4 6241.6 6243.6 6248.6 6276.8 6282.0 5755.0 5832.0 5839.2 5850.5 5901.7 5804.2 6347.0 6561.2 6587.5 6644.5 6497.2 6583.1 6611.8 6641.8 6668.5 6760.7 6776.6 6776.9 6781.2 6786.3 5494.7 6282.6 6286.8 6378.4 6425.6 6568.4 6601.0 6626.5 6632.5 6635.9 6234.2 6368.6 6412.6 6417.2 6418.7 6214.6 6261.0 6327.4 6360.4 6370.4 6164.8 6225.7 6259.4 6261.2 6292.2 6398.2 6404.0 6433.0 6450.5 6456.5 5280.2 6157.5 6197.1 6236.9 6266.5 10:38:55 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | [#1] ext.html Cycles:180 Average:45276.45 Median:173447.74 stddev:77795.75 (44.9%) stddev-sans-first:77942.28 10:38:55 INFO - PID 1650 | Values: 788.5 814.8 833.6 1242.6 1298.2 31605.7 39169.0 39829.0 40007.8 40115.8 40562.8 40718.8 40919.0 41022.8 41320.4 7815.4 8169.8 8397.0 8398.0 8400.4 8287.4 8425.6 8433.9 8444.4 8462.0 8289.4 8345.0 8370.0 8414.2 8455.0 8380.9 8412.0 8426.3 8444.2 8467.9 8258.0 8285.9 8352.3 8372.3 8384.2 38023.8 38192.0 38198.2 38201.6 38329.8 3548.5 3551.3 3586.1 3594.5 3606.3 30966.0 31233.2 31237.7 31291.2 31301.8 3037.5 3053.7 3073.1 3093.8 3109.4 197945.0 204736.9 242611.0 297708.0 300517.0 39842.8 49220.5 49769.0 49981.8 50026.7 50758.0 51126.0 51260.0 53767.0 54906.2 12396.8 14353.4 14429.6 14545.8 14578.1 11415.4 11516.3 11547.0 11629.4 11666.0 10751.5 13937.2 14153.7 14250.7 14300.9 5421.8 5917.5 6201.9 6279.4 6289.5 985.8 990.2 997.3 1024.7 1050.6 23288.4 28533.4 30733.8 31853.0 32108.0 1057.8 1087.2 1103.5 1127.7 1133.4 11023.1 12114.5 12933.4 13094.8 13096.1 329791.0 333883.0 334901.0 338215.0 343347.0 52911.9 53624.0 53962.0 54330.2 54818.5 1441.8 1446.2 1489.6 1501.0 1521.3 22045.6 22127.6 22533.2 23234.8 24048.0 44618.9 52279.5 52626.5 53541.5 53869.7 291492.0 292560.0 292680.0 297291.0 300431.0 36730.8 36748.0 36942.1 37711.8 38320.0 36977.8 44571.0 46971.0 47338.1 48323.5 29416.0 29582.4 29890.6 30573.5 31129.4 25492.4 29609.0 29972.9 31460.8 31992.8 32260.0 36813.1 37349.8 38755.4 38857.6 27950.0 31666.4 32394.0 32646.0 33209.7 18072.0 24457.6 24573.6 24686.4 25288.7 10:38:55 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | [#2] jquery.html Cycles:180 Average:40217.61 Median:24571.07 stddev:100830.58 (410.4%) stddev-sans-first:101069.14 10:38:55 INFO - PID 1650 | Values: 410.2 413.5 466.2 487.5 496.3 21223.7 21911.5 22419.2 23231.1 24245.4 24188.9 24896.8 24929.0 25051.5 25599.0 6039.1 6188.4 6190.8 6366.3 6378.0 5799.5 6134.6 6208.3 6353.0 6365.7 6109.5 6118.0 6121.9 6283.2 6289.2 6098.2 6110.8 6123.1 6257.6 6261.6 5623.4 5636.4 5684.9 5695.4 5814.2 29248.4 29628.4 30084.8 30221.8 30681.7 1819.9 2090.3 2132.3 2153.8 2176.1 18746.3 19263.9 19448.2 19820.6 19923.6 2122.7 2186.2 2188.5 2236.7 2256.6 131815.0 158616.0 179086.1 182942.0 199417.0 14339.7 15217.8 15344.6 15389.0 15425.5 19730.4 19847.9 19864.0 19889.7 19915.2 9610.9 9652.3 9709.3 9715.3 9966.0 8920.3 8933.6 8971.0 9063.1 9235.5 8921.2 9211.1 9288.5 9306.7 9489.7 2545.3 2560.9 2560.9 2619.1 2682.9 718.7 852.3 918.4 929.6 949.8 16459.9 20184.5 20938.2 22024.7 22150.0 912.2 962.5 975.6 997.1 997.8 10143.9 11955.8 14406.6 14509.8 14767.6 582786.0 583462.0 585910.0 590029.0 590766.0 165468.0 167571.2 167902.2 171475.1 172510.1 998.5 1048.5 1145.3 1178.9 1242.8 21241.3 21956.0 25025.2 25411.6 26076.2 70192.3 70553.0 71201.6 71567.3 72911.0 91329.6 92752.0 94396.0 94620.0 95364.5 11597.8 11598.8 11607.0 11631.8 11886.8 18085.1 18500.4 18555.9 18810.5 18976.8 12358.3 14622.8 14944.3 15067.5 15088.9 13220.0 13420.9 13498.5 13622.9 13638.6 14845.6 14949.4 15250.2 15343.3 15485.5 9102.5 11460.7 11500.2 11518.7 11540.7 12408.6 14871.5 14882.9 14940.8 15034.8 10:38:55 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | [#3] mootools.html Cycles:180 Average:8077.71 Median:2667.43 stddev:5310.00 (199.1%) stddev-sans-first:5301.30 10:38:55 INFO - PID 1650 | Values: 1415.3 1427.0 1464.8 1492.7 1509.4 11400.7 11459.6 12801.3 13391.9 13403.9 12312.4 13072.0 13178.1 13193.0 13208.6 2605.7 2654.9 2660.6 2663.3 2668.1 2201.9 2517.8 2625.1 2659.7 2699.5 2263.3 2568.5 2652.3 2673.7 2680.4 2611.3 2642.5 2649.7 2653.6 2666.7 2146.0 2596.3 2613.9 2615.4 2642.4 12806.5 12822.1 13004.5 13159.7 13350.5 7032.8 7308.6 7319.4 7349.2 7462.9 4435.7 4519.4 4547.9 4769.5 4808.0 1166.6 1342.8 1369.8 1372.1 1375.8 19272.0 19409.9 19411.7 19418.7 19431.1 6521.4 6606.8 6754.0 6778.8 7011.3 15859.9 15883.0 15911.8 15914.8 15982.7 7284.7 7320.6 7378.7 7380.0 7592.5 6414.4 6568.4 6608.1 6623.2 6643.3 5219.6 5276.5 5280.1 5290.6 5434.7 1344.3 1456.1 1507.5 1616.5 1656.8 685.6 794.9 823.3 835.8 836.4 7206.6 7364.2 7620.7 7703.8 7929.6 774.7 782.3 942.1 961.4 1018.1 4603.6 4824.1 4840.8 5025.3 5085.5 15493.1 16879.7 17283.0 17415.4 17525.7 17105.9 17111.1 17284.4 17425.1 17469.6 9106.0 9171.7 9181.6 9287.6 9565.1 6611.5 7793.9 7884.3 8092.2 8275.3 15805.8 15841.0 15933.8 16006.4 16699.0 15636.6 15898.2 16378.7 16709.6 16830.2 8897.3 9300.3 10626.5 10782.5 10841.2 11865.3 11929.1 12019.6 12019.8 12042.0 9772.9 9938.7 9957.5 9959.8 10153.8 9703.2 9788.9 9828.8 9835.8 9839.6 8615.7 8878.6 8893.6 8937.7 9023.8 6427.0 6612.3 6655.7 6805.7 6903.8 6633.5 6740.5 6742.2 6843.2 7060.6 10:38:55 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | [#4] prototype.html Cycles:180 Average:5370.83 Median:5779.84 stddev:1638.69 (28.4%) stddev-sans-first:1600.53 10:38:55 INFO - PID 1650 | Values: 415.8 425.5 438.4 460.3 460.5 6078.1 6212.5 6265.3 6268.5 6294.7 5633.7 5802.9 6061.7 6069.5 6102.7 4561.6 4581.2 4621.4 4651.6 4661.3 4596.0 4603.9 4611.0 4627.0 4730.2 3718.4 4559.7 4562.0 4569.6 4695.3 3911.8 4570.3 4573.9 4583.3 4616.5 3937.2 4018.6 4036.8 4054.4 4123.5 4960.9 5756.8 5922.0 6125.5 6202.3 4448.6 5422.3 5444.4 5450.3 5580.9 5701.2 6585.2 6635.5 6663.9 6751.7 4185.5 4552.1 4558.4 4581.4 4680.4 6285.3 6503.8 6644.0 6793.0 7189.0 4593.1 4645.4 4653.0 4654.7 4808.8 4753.1 4775.4 4775.9 4780.1 4915.9 5281.9 5389.8 5414.9 5414.9 5430.3 5449.7 5458.8 5720.3 6785.4 6826.4 1405.8 1607.5 1644.2 1647.3 1684.9 1076.4 1078.5 1111.0 1119.9 1143.9 5417.2 6243.9 6251.3 6252.4 6364.1 6848.3 6850.7 6853.3 6856.6 6881.6 5412.8 6227.4 6279.9 6282.0 6352.2 3539.7 3586.7 3600.3 3612.8 3677.8 5493.6 7040.6 7058.9 7072.3 7130.6 5325.0 5446.8 7062.4 7085.8 7231.9 6885.5 7086.0 7090.4 7093.5 7095.3 6850.6 6858.9 6861.0 6865.5 6883.0 5708.7 6202.7 6724.4 6732.8 6742.6 6089.6 6695.6 6854.8 6981.3 7080.7 4660.5 4673.5 4702.9 4787.7 4795.7 5604.0 6602.8 6824.7 6840.2 6866.7 6538.9 6543.8 6586.2 6625.5 6762.7 6552.8 6556.2 6578.8 6587.2 6735.7 6372.5 6378.4 6408.5 6441.3 6471.4 5556.4 6081.7 6653.8 6656.6 6695.0 5124.0 5992.0 6403.3 6431.6 6495.7 10:38:55 INFO - PID 1650 | 10:38:55 INFO - PID 1650 | [#5] yui.html Cycles:180 Average:24521.78 Median:17619.16 stddev:37398.14 (212.3%) stddev-sans-first:37460.58 10:38:55 INFO - PID 1650 | Values: 784.7 798.3 814.3 833.7 848.2 13915.8 14512.4 16072.7 16535.9 16586.3 15543.7 17080.9 17103.1 17143.7 17524.1 944.0 951.9 954.6 962.3 968.5 964.3 987.2 989.9 993.5 995.0 921.4 1036.0 1038.9 1047.5 1047.7 1017.6 1028.3 1036.8 1040.9 1041.1 735.6 741.5 753.4 753.7 753.7 16237.7 16551.1 16694.7 16862.6 17231.3 16874.3 16942.4 17077.0 17318.9 17373.3 14353.9 14391.9 14490.1 14504.6 14554.1 3475.5 3555.4 3567.1 3579.3 3582.1 158361.0 160569.0 160631.0 161718.0 163349.0 13347.3 13474.2 13484.5 13621.7 13732.7 24812.6 25275.9 26202.0 26292.0 26696.7 6433.3 7789.8 7834.2 7842.9 7843.4 3160.9 3296.1 3709.6 3829.0 3830.6 4399.3 4496.5 4498.0 4525.8 4591.9 934.4 959.8 960.7 970.7 972.5 571.3 592.0 605.7 622.5 628.3 10802.2 11569.7 11738.4 12302.0 12335.4 536.8 584.7 620.5 648.7 660.6 5322.3 5603.4 5684.7 5774.8 5896.8 97022.0 107015.0 120569.6 121170.8 124321.0 93236.1 108559.0 112488.0 112489.0 112753.0 62656.0 74182.6 96808.0 98731.0 100977.0 10216.5 10472.9 11030.8 11121.4 11157.1 41832.0 44432.7 45026.8 45611.0 45756.5 64904.4 83346.6 86986.8 88722.0 89355.0 17367.6 17386.1 17396.4 17434.8 17578.3 18027.8 18264.5 18803.0 19048.1 19048.9 13719.9 16935.2 17298.0 17397.7 17418.5 16250.0 16628.2 17312.0 17660.0 17730.6 16710.2 16797.5 16802.3 16988.3 17139.6 10850.2 11278.6 11288.8 11456.4 11590.5 12566.7 12617.6 13096.8 13119.3 13393.8 10:38:55 INFO - PID 1650 | -------- Summary: end -------- 10:38:55 INFO - PID 1650 | 10:38:56 INFO - TEST-INFO | 1650: exit 0 10:38:56 INFO - TEST-OK | dromaeo_css | took 1143245ms 10:38:56 INFO - TEST-START | kraken 10:38:56 INFO - operating with platform_type : mac_ 10:38:56 INFO - Initialising browser for kraken test... 10:38:56 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpDM2zTv/profile 10:38:56 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 10:39:30 INFO - TEST-INFO | 1659: killed by SIGTERM 10:39:30 INFO - Browser initialized. 10:39:30 INFO - Running cycle 1/1 for kraken test... 10:39:30 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/hkb07m1j4sq5c29hg511636400000w/T/tmpDM2zTv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:39:32 INFO - PID 1663 | 2017-06-09 10:39:32.497 plugin-container[1664:10930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a43, name = 'com.apple.tsm.portname' 10:39:32 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:39:32 INFO - PID 1663 | 2017-06-09 10:39:32.498 plugin-container[1664:10930] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0f, name = 'com.apple.CFPasteboardClient' 10:39:32 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:39:32 INFO - PID 1663 | 2017-06-09 10:39:32.498 plugin-container[1664:10930] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:39:33 INFO - PID 1663 | 2017-06-09 10:39:33.256 plugin-container[1665:10977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 10:39:33 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:39:33 INFO - PID 1663 | 2017-06-09 10:39:33.257 plugin-container[1665:10977] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 10:39:33 INFO - PID 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:39:33 INFO - PID 1663 | 2017-06-09 10:39:33.257 plugin-container[1665:10977] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:39:42 INFO - PID 1663 | RSS: Main: 254312448 10:39:42 INFO - PID 1663 | 10:39:45 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 10:39:46 INFO - PID 1663 | RSS: Main: 267366400 10:39:46 INFO - PID 1663 | 10:39:48 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 10:39:49 INFO - PID 1663 | RSS: Main: 277184512 10:39:49 INFO - PID 1663 | 10:39:52 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 10:39:53 INFO - PID 1663 | RSS: Main: 279146496 10:39:53 INFO - PID 1663 | 10:39:55 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 10:39:56 INFO - PID 1663 | RSS: Main: 280961024 10:39:56 INFO - PID 1663 | 10:39:58 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:39:58 INFO - PID 1663 | RSS: Main: 281980928 10:39:58 INFO - PID 1663 | 10:40:01 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 10:40:02 INFO - PID 1663 | RSS: Main: 286203904 10:40:02 INFO - PID 1663 | 10:40:05 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 10:40:06 INFO - PID 1663 | RSS: Main: 288272384 10:40:06 INFO - PID 1663 | 10:40:09 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 10:40:09 INFO - PID 1663 | RSS: Main: 287055872 10:40:09 INFO - PID 1663 | 10:40:11 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:40:12 INFO - PID 1663 | RSS: Main: 287346688 10:40:12 INFO - PID 1663 | 10:40:13 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:40:14 INFO - PID 1663 | RSS: Main: 287191040 10:40:14 INFO - PID 1663 | 10:40:16 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:40:17 INFO - PID 1663 | RSS: Main: 287162368 10:40:17 INFO - PID 1663 | 10:40:20 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:40:20 INFO - PID 1663 | RSS: Main: 286818304 10:40:20 INFO - PID 1663 | 10:40:23 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:40:24 INFO - PID 1663 | RSS: Main: 286711808 10:40:24 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 10:40:26 INFO - PID 1663 | RSS: Main: 288616448 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | __start_tp_report 10:40:26 INFO - PID 1663 | _x_x_mozilla_page_load 10:40:26 INFO - PID 1663 | _x_x_mozilla_page_load_details 10:40:26 INFO - PID 1663 | |i|pagename|runs| 10:40:26 INFO - PID 1663 | |0;ai-astar;162;161;106;113;116;116;114;116;115;115 10:40:26 INFO - PID 1663 | |1;audio-beat-detection;142;111;129;155;112;128;126;111;154;110 10:40:26 INFO - PID 1663 | |2;audio-dft;200;202;202;207;209;234;280;264;193;200 10:40:26 INFO - PID 1663 | |3;audio-fft;73;84;77;145;58;76;88;89;128;60 10:40:26 INFO - PID 1663 | |4;audio-oscillator;73;70;110;70;70;74;110;70;73;109 10:40:26 INFO - PID 1663 | |5;imaging-gaussian-blur;89;89;96;89;91;90;89;108;135;135 10:40:26 INFO - PID 1663 | |6;imaging-darkroom;98;97;96;96;97;96;96;97;97;98 10:40:26 INFO - PID 1663 | |7;imaging-desaturate;91;94;115;138;92;93;94;125;134;92 10:40:26 INFO - PID 1663 | |8;json-parse-financial;51;50;52;50;52;50;55;52;53;53 10:40:26 INFO - PID 1663 | |9;json-stringify-tinderbox;56;59;55;59;66;63;58;58;56;55 10:40:26 INFO - PID 1663 | |10;stanford-crypto-aes;84;82;85;85;82;86;86;92;112;89 10:40:26 INFO - PID 1663 | |11;stanford-crypto-ccm;138;141;136;137;134;138;135;146;150;164 10:40:26 INFO - PID 1663 | |12;stanford-crypto-pbkdf2;177;183;184;171;186;184;185;180;186;186 10:40:26 INFO - PID 1663 | |13;stanford-crypto-sha256-iterative;68;73;90;64;78;88;87;91;91;93 10:40:26 INFO - PID 1663 | __end_tp_report 10:40:26 INFO - PID 1663 | __start_cc_report 10:40:26 INFO - PID 1663 | _x_x_mozilla_cycle_collect,1186 10:40:26 INFO - PID 1663 | __end_cc_report 10:40:26 INFO - PID 1663 | __startTimestamp1497030026541__endTimestamp 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | ------- Summary: start ------- 10:40:26 INFO - PID 1663 | Number of tests: 14 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#0] ai-astar Cycles:10 Average:123.40 Median:116.00 stddev:20.30 (17.5%) stddev-sans-first:16.02 10:40:26 INFO - PID 1663 | Values: 162.0 161.0 106.0 113.0 116.0 116.0 114.0 116.0 115.0 115.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#1] audio-beat-detection Cycles:10 Average:127.80 Median:128.50 stddev:17.52 (13.6%) stddev-sans-first:17.82 10:40:26 INFO - PID 1663 | Values: 142.0 111.0 129.0 155.0 112.0 128.0 126.0 111.0 154.0 110.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#2] audio-dft Cycles:10 Average:219.10 Median:208.00 stddev:30.17 (14.5%) stddev-sans-first:31.20 10:40:26 INFO - PID 1663 | Values: 200.0 202.0 202.0 207.0 209.0 234.0 280.0 264.0 193.0 200.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#3] audio-fft Cycles:10 Average:87.80 Median:76.50 stddev:27.97 (36.6%) stddev-sans-first:29.15 10:40:26 INFO - PID 1663 | Values: 73.0 84.0 77.0 145.0 58.0 76.0 88.0 89.0 128.0 60.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#4] audio-oscillator Cycles:10 Average:82.90 Median:70.00 stddev:18.53 (26.5%) stddev-sans-first:19.31 10:40:26 INFO - PID 1663 | Values: 73.0 70.0 110.0 70.0 70.0 74.0 110.0 70.0 73.0 109.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:101.10 Median:89.00 stddev:18.81 (21.1%) stddev-sans-first:19.43 10:40:26 INFO - PID 1663 | Values: 89.0 89.0 96.0 89.0 91.0 90.0 89.0 108.0 135.0 135.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:97.00 stddev:0.79 (0.8%) stddev-sans-first:0.71 10:40:26 INFO - PID 1663 | Values: 98.0 97.0 96.0 96.0 97.0 96.0 96.0 97.0 97.0 98.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#7] imaging-desaturate Cycles:10 Average:106.80 Median:92.00 stddev:19.20 (20.9%) stddev-sans-first:19.49 10:40:26 INFO - PID 1663 | Values: 91.0 94.0 115.0 138.0 92.0 93.0 94.0 125.0 134.0 92.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.62 (3.1%) stddev-sans-first:1.69 10:40:26 INFO - PID 1663 | Values: 51.0 50.0 52.0 50.0 52.0 50.0 55.0 52.0 53.0 53.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.50 stddev:3.57 (6.1%) stddev-sans-first:3.67 10:40:26 INFO - PID 1663 | Values: 56.0 59.0 55.0 59.0 66.0 63.0 58.0 58.0 56.0 55.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.50 stddev:8.86 (10.4%) stddev-sans-first:9.26 10:40:26 INFO - PID 1663 | Values: 84.0 82.0 85.0 85.0 82.0 86.0 86.0 92.0 112.0 89.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:141.90 Median:139.50 stddev:9.26 (6.6%) stddev-sans-first:9.71 10:40:26 INFO - PID 1663 | Values: 138.0 141.0 136.0 137.0 134.0 138.0 135.0 146.0 150.0 164.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.20 Median:184.50 stddev:4.89 (2.7%) stddev-sans-first:4.82 10:40:26 INFO - PID 1663 | Values: 177.0 183.0 184.0 171.0 186.0 184.0 185.0 180.0 186.0 186.0 10:40:26 INFO - PID 1663 | 10:40:26 INFO - PID 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.30 Median:89.00 stddev:10.67 (12.0%) stddev-sans-first:9.98 10:40:26 INFO - PID 1663 | Values: 68.0 73.0 90.0 64.0 78.0 88.0 87.0 91.0 91.0 93.0 10:40:26 INFO - PID 1663 | -------- Summary: end -------- 10:40:26 INFO - PID 1663 | 10:40:27 INFO - TEST-INFO | 1663: exit 0 10:40:27 INFO - TEST-OK | kraken | took 90949ms 10:40:27 INFO - SUITE-END | took 1234s 10:40:27 INFO - Completed test suite (00:20:34) 10:40:27 INFO - javascript benchmark 10:40:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8224.048622630728, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.272738633808, 3486.936484372266, 3601.0, 3621.782693038418, 3624.383854744693, 6029.005214139662, 6071.7571297148115, 6170.321264660889, 6174.320824709278, 6187.845303867405, 6096.390360963901, 6098.725557349919, 6127.953177891112, 6129.4789942854795, 6196.070589411579, 5340.519353258196, 5390.9191362129595, 5407.783688652449, 5437.34751829781, 5513.42109078547, 4499.527549607294, 5308.150695888658, 5456.781728730846, 5494.697791621459, 5511.834644960638, 4384.693071484998, 5404.32445944257, 5480.588955828309, 5486.807961721341, 5522.5581953443825, 5262.605304602132, 5328.600354973392, 5379.488948549882, 5396.1905714143195, 5435.239066530688, 5351.598630102719, 5386.003589335985, 5387.084195686703, 5408.188771684239, 5415.187721841716, 6123.050927106306, 6152.753889844401, 6174.5060395168275, 6190.288116866573, 6205.286392064925, 6310.6213627182515, 6395.104685344056, 6417.294097649255, 6501.674916254168, 6519.315471875432, 5590.273264475592, 6660.966695166541, 6669.599824010526, 6672.466202703772, 6707.329267073303, 5355.0896347621, 6197.81406557804, 6235.345288744707, 6253.249610046823, 6258.968705156491, 6539.411452969255, 6654.401103900672, 6676.53264271506, 6826.283240259801, 6860.0, 6180.690965451709, 6368.235811702625, 6391.648459334689, 6438.0, 6454.19322584677, 6433.774817881352, 6462.353764623594, 6608.3391660834495, 6623.403893649498, 6679.933200668028, 6501.47988160946, 6515.185601799775, 6517.511186660925, 6544.738210471528, 6560.376764207488, 6310.4005119514495, 6345.650989195638, 6406.071119687619, 6484.740610375627, 6543.0, 5204.947950520446, 5234.18870075139, 5249.580033597303, 5263.7104959226845, 5284.497972692588, 4656.4877863434995, 4699.671023028423, 4721.244600863914, 4729.881752956135, 4731.834385796481, 5112.923306150411, 6121.632702037892, 6251.156093927261, 6259.937400625936, 6260.154879091265, 6448.065030570635, 6556.0493728410065, 6612.8346791330605, 6614.404703576702, 6642.402183803384, 6241.594296370721, 6243.625382477066, 6248.625082495065, 6276.780312689034, 6281.96859015702, 5755.0, 5831.970840145772, 5839.211706419579, 5850.47345738877, 5901.704914754245, 5804.216430781959, 6346.984482482781, 6561.245456772533, 6587.473002159912, 6644.501662375438, 6497.220333560003, 6583.059580034279, 6611.768588099393, 6641.837678406357, 6668.533202675961, 6760.661966901537, 6776.593404395752, 6776.93223067763, 6781.152355955505, 6786.287439818748, 5494.670319780826, 6282.591631543935, 6286.811395658137, 6378.425941665087, 6425.646589437626, 6568.441682457044, 6601.042848786803, 6626.536142470173, 6632.535722499571, 6635.867282654223, 6234.1583886174785, 6368.554201205871, 6412.551121421456, 6417.197850268716, 6418.679066046772, 6214.627122372672, 6260.966940454774, 6327.3988971047675, 6360.427561519486, 6370.3629637037775, 6164.815055548162, 6225.657588832658, 6259.405356491126, 6261.217347831521, 6292.2134733158355, 6398.232212134573, 6403.96798016007, 6433.0, 6450.516211284266, 6456.515761317826, 5280.234366016983, 6157.53818463626, 6197.1324014638885, 6236.906446403217, 6266.467350275278], "value": 5976.5705120356115, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.479603461715, 814.7881550796793, 833.579042583495, 1242.6147894152807, 1298.234041915271, 31605.683943160562, 39169.0, 39829.0, 40007.79996100023, 40115.79942100289, 40562.79718601403, 40718.79640601793, 40919.0, 41022.79488602553, 41320.38019429711, 7815.413843961712, 8169.795755106111, 8397.0, 8398.03422606399, 8400.369972252105, 8287.378446616498, 8425.620847061897, 8433.915660843375, 8444.408891377607, 8461.957690211571, 8289.378296627747, 8345.0, 8370.0, 8414.158584141567, 8454.957725211365, 8380.916190838105, 8411.957940210259, 8426.325893928471, 8444.240018398312, 8467.915320846774, 8257.95871020647, 8285.875711864326, 8352.33181345497, 8372.28835548979, 8384.203500667427, 38023.80988095069, 38192.0, 38198.23603527914, 38201.617983820084, 38329.80835095807, 3548.4854696068924, 3551.289742051574, 3586.067622418164, 3594.478800573902, 3606.278744251134, 30966.0, 31233.219169520715, 31237.687623123933, 31291.217719556964, 31301.843490782627, 3037.51399776037, 3053.6946305369283, 3073.139520934142, 3093.8298393588343, 3109.409212249688, 197945.0, 204736.92894606592, 242611.0, 297708.0, 300517.0, 39842.800785996464, 49220.50779492159, 49769.0, 49981.75009124931, 50026.74986625044, 50758.0, 51126.0, 51260.0, 53767.0, 54906.17640735392, 12396.75206495865, 14353.354099065567, 14429.567112986628, 14545.78181327281, 14578.052426592238, 11415.37215453158, 11516.30902145874, 11546.960773530254, 11629.418529073513, 11666.0, 10751.516181771683, 13937.163770173822, 14153.716925661429, 14250.71743543086, 14300.928495357664, 5421.83734487966, 5917.520619845039, 6201.937980620226, 6279.372062793684, 6289.465395441347, 985.7979114281699, 990.2127808392345, 997.2769741937183, 1024.7130803375067, 1050.6217761605815, 23288.4177895554, 28533.42933141367, 30733.846330768203, 31853.0, 32108.0, 1057.8201705709894, 1087.2226358153957, 1103.5309993252836, 1127.7067962329854, 1133.3766428464312, 11023.06303964172, 12114.527596023381, 12933.353332333534, 13094.803577946339, 13096.083274170907, 329791.0, 333883.0, 334901.0, 338215.0, 343347.0, 52911.94176116456, 53624.0, 53962.0, 54330.18504722353, 54818.451815481334, 1441.7549016667192, 1446.16845313947, 1489.5680252726588, 1500.9774853377207, 1521.3078049487679, 22045.559088818456, 22127.565967743856, 22533.21133760376, 23234.767652323262, 24048.0, 44618.884527887065, 52279.477205227464, 52626.47373526216, 53541.46458535365, 53869.730651346494, 291492.0, 292560.0, 292680.0, 297291.0, 300431.0, 36730.8163459181, 36748.0, 36942.076448089014, 37711.81144094262, 38320.0, 36977.815110924275, 44571.0, 46971.0, 47338.05323893434, 48323.516764831904, 29416.0, 29582.40835183241, 29890.551641725826, 30573.54139687951, 31129.377412451173, 25492.362690932132, 29609.0, 29972.85013574918, 31460.842695786374, 31992.840035799672, 32260.0, 36813.07967300839, 37349.81325093357, 38755.41866871943, 38857.6114238854, 27950.0, 31666.366672666878, 32394.0, 32646.0, 33209.66790332066, 18072.0, 24457.633135502627, 24573.648054956724, 24686.382840429134, 25288.74711252864], "value": 17804.634870050413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.1612203044774, 413.4728221517565, 466.1841776890442, 487.49300727243667, 496.2580941492469, 21223.68164477534, 21911.452213694647, 22419.215327463542, 23231.07075716969, 24245.39386515338, 24188.879055604742, 24896.75103248967, 24929.002839886427, 25051.498970020635, 25599.0, 6039.1243269725865, 6188.381161883821, 6190.78332258371, 6366.331535188808, 6378.043293505965, 5799.507041901444, 6134.631922084667, 6208.255009398878, 6352.968235158818, 6365.681715914209, 6109.541784366168, 6118.021116621341, 6121.938780612204, 6283.214598175228, 6289.245290565139, 6098.237720284965, 6110.786122485736, 6123.142760013601, 6257.624542527463, 6261.561690681631, 5623.437656234385, 5636.351819540764, 5684.857878553028, 5695.40198279183, 5814.186013958049, 29248.415031699245, 29628.407431851458, 30084.8495757522, 30221.848890755624, 30681.693183068106, 1819.927202911882, 2090.2684060578827, 2132.3176583493287, 2153.8276937844935, 2176.064292354287, 18746.343877964206, 19263.940483273414, 19448.22207111714, 19820.60358792816, 19923.601527969506, 2122.734658167729, 2186.202036256755, 2188.485705859122, 2236.709227800392, 2256.638937769965, 131815.0, 158616.0, 179086.10456947633, 182942.0, 199417.0, 14339.659462360467, 15217.771733424008, 15344.616384590252, 15388.999715018488, 15425.537233883002, 19730.40808775739, 19847.900760496104, 19864.00679965996, 19889.701654475193, 19915.203391864452, 9610.90389096114, 9652.324337296463, 9709.271804614682, 9715.319927605, 9965.950170249102, 8920.286377089817, 8933.553322333857, 8970.955145224232, 9063.096749682509, 9235.492047937294, 8921.15249051339, 9211.078892110736, 9288.489133097744, 9306.67426640078, 9489.715308540755, 2545.263841370534, 2560.8975640974154, 2560.9372110574277, 2619.1095027690676, 2682.9000109954823, 718.683779137178, 852.2883392367319, 918.4076270805293, 929.6095639831152, 949.838527450335, 16459.930104543408, 20184.495387615134, 20938.162473501194, 22024.678519288893, 22150.0, 912.2337236721054, 962.5235508423375, 975.5561219645044, 997.0727223682045, 997.7605374710163, 10143.884172740993, 11955.760884782256, 14406.639834004234, 14509.782353264709, 14767.556973290819, 582786.0, 583462.0, 585910.0, 590029.0, 590766.0, 165468.0, 167571.1621441885, 167902.16048919677, 171475.1426242861, 172510.13744931194, 998.4523987818852, 1048.5229220704562, 1145.3013661666378, 1178.8762179971152, 1242.776300265979, 21241.256556021082, 21956.0, 25025.249242522754, 25411.618825717258, 26076.217713468628, 70192.29807701858, 70553.0, 71201.64399177971, 71567.28432715815, 72911.0, 91329.6300555479, 92752.0, 94396.0, 94620.0, 95364.52317738367, 11597.76804463923, 11598.768024639628, 11607.0, 11631.76736465283, 11886.762264754829, 18085.09574521295, 18500.444986650422, 18555.90722046381, 18810.529736756143, 18976.810231897503, 12358.320292383645, 14622.780658290347, 14944.252787360805, 15067.472638458039, 15088.924555377593, 13220.008499362393, 13420.93289533585, 13498.460061597427, 13622.93188534051, 13638.590842274747, 14845.628859278604, 14949.402023919356, 15250.237488125327, 15343.309551070006, 15485.535433937, 9102.544872756203, 11460.713482163013, 11500.194986351209, 11518.712032199262, 11540.653780386601, 12408.62774116778, 14871.47949821751, 14882.851171488146, 14940.775888361466, 15034.774478382615], "value": 11661.608158344472, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.313572917135, 1426.9785953210696, 1464.816897887764, 1492.7237212183584, 1509.4113295814632, 11400.657980260605, 11459.59891403801, 12801.295928723917, 13391.866081339183, 13403.865961340383, 12312.445939932702, 13072.0, 13178.143420677674, 13193.010524210675, 13208.603741887759, 2605.6612640356725, 2654.86725663717, 2660.5610074337806, 2663.2942270298386, 2668.146193218171, 2201.933941981743, 2517.8363406378617, 2625.12058460416, 2659.7074321824584, 2699.4601079784024, 2263.2644390573046, 2568.4734629401023, 2652.2971412575685, 2673.719259477756, 2680.383511792289, 2611.2557920992426, 2642.484715480482, 2649.6952850422254, 2653.5621622432277, 2666.706662267149, 2146.0, 2596.2860213441268, 2613.9346516337246, 2615.4246065865477, 2642.4318771464227, 12806.487740490462, 12822.102452828305, 13004.544840930523, 13159.736805263938, 13350.46598136083, 7032.824179395505, 7308.561486310785, 7319.377852882511, 7349.191588925214, 7462.85074298511, 4435.68950173488, 4519.412476378083, 4547.886302842422, 4769.451513075971, 4807.975960120177, 1166.5567084507918, 1342.8254326937533, 1369.8150749648769, 1372.060138804912, 1375.7523645743763, 19272.0, 19409.93245371504, 19411.708824367506, 19418.70871936922, 19431.125599348063, 6521.445677117498, 6606.8017959461295, 6754.020667003355, 6778.8305292367095, 7011.333923277382, 15859.920700396424, 15883.0, 15911.840881591268, 15914.840851591567, 15982.680346393006, 7284.745033923788, 7320.597367144752, 7378.704851805975, 7380.0, 7592.544447333178, 6414.358564143642, 6568.376004279586, 6608.140941677551, 6623.172103487064, 6643.272749085273, 5219.634625576249, 5276.498732620395, 5280.128778751463, 5290.6296559241255, 5434.728263586884, 1344.327836081959, 1456.1117718191895, 1507.5402002389158, 1616.474645740139, 1656.8094669112966, 685.5920727167328, 794.9483283586549, 823.3495538524498, 835.7910522369408, 836.3685417509741, 7206.56760594366, 7364.153122390887, 7620.657070431844, 7703.807404814925, 7929.643166057541, 774.6978678315461, 782.2998416417274, 942.1002942190192, 961.3895176562933, 1018.0938964321759, 4603.562661547147, 4824.131656301899, 4840.83057093, 5025.296458495814, 5085.466026067284, 15493.1408231013, 16879.662406751795, 17283.0, 17415.390461334046, 17525.7371139433, 17105.91447042782, 17111.14444277781, 17284.395046173322, 17425.12874356277, 17469.563260918578, 9106.0, 9171.715959765637, 9181.632734690671, 9287.58205880737, 9565.139137477661, 6611.471082313499, 7793.922060779547, 7884.290413862551, 8092.190780921861, 8275.255227029596, 15805.762913556537, 15841.0, 15933.840661593236, 16006.359745610045, 16699.0, 15636.609084772972, 15898.205089745697, 16378.672426551164, 16709.582260443585, 16830.242639081516, 8897.2882169425, 9300.302477314093, 10626.512288279802, 10782.514786834454, 10841.241113122005, 11865.347405892759, 11929.105317101079, 12019.63941081769, 12019.75960480803, 12042.0, 9772.9022709772, 9938.652147174815, 9957.452340121363, 9959.751006224613, 10153.847692284475, 9703.223742100756, 9788.923218446107, 9828.803423931624, 9835.754106147117, 9839.557219925264, 8615.741527754177, 8878.556072196236, 8893.555322233733, 8937.73186804397, 9023.774405639911, 6427.014714636466, 6612.305707900601, 6655.667216639052, 6805.65971701403, 6903.792886213421, 6633.535652504278, 6740.513553484269, 6742.190937087385, 6843.178818541608, 7060.6469676517], "value": 5974.653736746841, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.7734034950951, 425.4575416344161, 438.35342869267834, 460.33021953058295, 460.5279588421867, 6078.118672792442, 6212.534059945499, 6265.279492858323, 6268.498520118385, 6294.653794041325, 5633.746481408336, 5802.92645860515, 6061.727222274997, 6069.453749162574, 6102.694865256731, 4561.635069194473, 4581.1753884300815, 4621.445426548818, 4651.627869770418, 4661.347411362395, 4596.03483268514, 4603.930941035887, 4611.0, 4626.976865115686, 4730.172219861511, 3718.3864662330684, 4559.703619264754, 4561.977190114045, 4569.588737013668, 4695.3426520287185, 3911.7677931451785, 4570.337301091323, 4573.908521829578, 4583.289590113521, 4616.522712731927, 3937.1731936293413, 4018.578049304839, 4036.777977211252, 4054.351303791379, 4123.525794533637, 4960.933399319128, 5756.762296106315, 5922.0, 6125.5099592032975, 6202.255729312466, 4448.5551444855255, 5422.322209723785, 5444.428335024805, 5450.255918106208, 5580.86047848803, 5701.204120701966, 6585.176852893388, 6635.4500342317115, 6663.866722665568, 6751.729930802762, 4185.518665353462, 4552.135094332067, 4558.407407037064, 4581.381513495702, 4680.4149481314835, 6285.340039295854, 6503.772367967145, 6643.966780166068, 6793.0, 7188.993540904277, 4593.081383723269, 4645.39609850724, 4653.022865198346, 4654.743989080565, 4808.759562021885, 4753.096911586787, 4775.355327030876, 4775.880602984953, 4780.139574876486, 4915.90168196634, 5281.947180528223, 5389.784408623611, 5414.864628384243, 5414.917016596696, 5430.266913966643, 5449.6730196188355, 5458.7990642058685, 5720.256366672306, 6785.355391237825, 6826.419754320938, 1405.8261351771384, 1607.4615003973565, 1644.2354305247877, 1647.2998975435391, 1684.941027064047, 1076.364944682641, 1078.4823284823328, 1111.0445615716396, 1119.9384033878052, 1143.9199256052163, 5417.185242943658, 6243.875122497525, 6251.343608921089, 6252.374762523711, 6364.109024736541, 6848.3494068063455, 6850.725970961105, 6853.348931851466, 6856.588604683635, 6881.621510816954, 5412.83761487156, 6227.408396202353, 6279.937200628026, 6282.0, 6352.237731472253, 3539.663731945461, 3586.6951309139017, 3600.3159399714496, 3612.7651702639246, 3677.7609455385314, 5493.615446918758, 7040.612766297801, 7058.929410705828, 7072.287049299056, 7130.643467826588, 5325.0, 5446.782128714886, 7062.435005199572, 7085.787426377217, 7231.927680723231, 6885.51801373899, 7085.999999999897, 7090.361867431956, 7093.467989900675, 7095.303784561834, 6850.554713943578, 6858.862822743617, 6861.039454476278, 6865.485088618473, 6883.0, 5708.714564271853, 6202.658853762906, 6724.361185687352, 6732.798016059526, 6742.561733487308, 6089.634621922699, 6695.598264104169, 6854.760083397057, 6981.301869812978, 7080.716771329089, 4660.510646382081, 4673.532646735299, 4702.905941881075, 4787.67797618148, 4795.664303498722, 5603.971980140236, 6602.834929126618, 6824.669189507998, 6840.213375461686, 6866.725330986705, 6538.901916471162, 6543.803685889431, 6586.176727909012, 6625.469962403093, 6762.734483947933, 6552.803415897531, 6556.246031706224, 6578.76974305897, 6587.242467116343, 6735.663216839041, 6372.490200784019, 6378.425941665272, 6408.455281301141, 6441.259255185526, 6471.449926756279, 5556.444355564411, 6081.6655083969035, 6653.767118150841, 6656.567323123982, 6695.0, 5124.0, 5991.951408503407, 6403.295637479967, 6431.646259455775, 6495.74017039332], "value": 4877.434945381335, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.7371130671224, 798.3054742374131, 814.3403842887261, 833.7202394324709, 848.2450618949134, 13915.791263131036, 14512.419503219859, 16072.678546429064, 16535.917320413395, 16586.33654653814, 15543.689126217498, 17080.914595427035, 17103.059331736782, 17143.742843857322, 17524.123793810322, 943.9905600943971, 951.8857737071527, 954.6038394066454, 962.2638681408728, 968.5205823117567, 964.2719746591314, 987.1559816357019, 989.9307048506571, 993.538004827755, 995.0, 921.3642586615214, 1036.0313107244713, 1038.91688664907, 1047.4657924458504, 1047.7171163785774, 1017.6031347774374, 1028.28534168752, 1036.7874585709912, 1040.9427481488515, 1041.1306559023187, 735.5733674468795, 741.4661443760538, 753.3822265742093, 753.6721526136148, 753.7324249891265, 16237.675246495004, 16551.08969006704, 16694.749578756328, 16862.578435539086, 17231.31074757008, 16874.325026998904, 16942.37393204043, 17077.0, 17318.913405432893, 17373.3050677974, 14353.856461435356, 14391.856081439157, 14490.058146220568, 14504.56486305402, 14554.053986490844, 3475.5481787367735, 3555.4311310190506, 3567.0725611340977, 3579.3020361029608, 3582.0507565495163, 158361.0, 160569.0, 160631.0, 161718.0, 163349.0, 13347.332633368293, 13474.19154850712, 13484.460621575223, 13621.727565448635, 13732.65668358279, 24812.627810582857, 25275.862586183666, 26202.0, 26292.0, 26696.733032669814, 6433.292337842833, 7789.80525486856, 7834.1774113718375, 7842.921570784333, 7843.37253019757, 3160.920976975548, 3296.0606227225126, 3709.573399059089, 3828.9617103829164, 3830.597787232356, 4399.318105693623, 4496.505384407713, 4498.032922921588, 4525.773711314421, 4591.885202869888, 934.3879758758025, 959.8320293948531, 960.6781728121018, 970.7476056225291, 972.5477652891298, 571.3001573072952, 591.9940800592025, 605.6578033411108, 622.5393209025289, 628.3433811666819, 10802.243842931077, 11569.6529104127, 11738.413079345999, 12302.01583873288, 12335.383230838423, 536.8201652446475, 584.7076461769116, 620.5066971757464, 648.7307767276621, 660.5904339309659, 5322.2548843161985, 5603.411641777636, 5684.6589204647025, 5774.82675519735, 5896.823095307148, 97022.0, 107015.0, 120569.58860822735, 121170.78829211595, 124321.0, 93236.06763932273, 108559.0, 112488.0, 112489.0, 112753.0, 62656.0, 74182.62908685423, 96808.0, 98731.01268987362, 100977.0, 10216.489175541343, 10472.895271047191, 11030.779384412106, 11121.443927803415, 11157.10743140563, 41832.0, 44432.66701998945, 45026.77486612546, 45611.0, 45756.542434575225, 64904.35095649172, 83346.58326708328, 86986.82532936728, 88722.0, 89355.0, 17367.565810854325, 17386.13069346553, 17396.391126311024, 17434.825651743828, 17578.296868125133, 18027.819721802614, 18264.45206643803, 18802.965836879102, 19048.142833572245, 19048.904755476135, 13719.862801371859, 16935.23791429364, 17298.0, 17397.695172861833, 17418.47744567665, 16250.0, 16628.16859157013, 17312.0, 17660.02869842171, 17730.645387092445, 16710.24803883804, 16797.496075117768, 16802.327906883587, 16988.320467181173, 17139.628829693844, 10850.186236032487, 11278.605248816251, 11288.774224515299, 11456.427178641201, 11590.478428470571, 12566.685832854251, 12617.621471355507, 13096.803547946598, 13119.27843968591, 13393.799093013418], "value": 8555.320548079226, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1550.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 106.0, 113.0, 116.0, 116.0, 114.0, 116.0, 115.0, 115.0], "value": 123.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 129.0, 155.0, 112.0, 128.0, 126.0, 111.0, 154.0, 110.0], "value": 127.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 202.0, 202.0, 207.0, 209.0, 234.0, 280.0, 264.0, 193.0, 200.0], "value": 219.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 84.0, 77.0, 145.0, 58.0, 76.0, 88.0, 89.0, 128.0, 60.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 70.0, 110.0, 70.0, 70.0, 74.0, 110.0, 70.0, 73.0, 109.0], "value": 82.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 96.0, 89.0, 91.0, 90.0, 89.0, 108.0, 135.0, 135.0], "value": 101.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 97.0, 98.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 115.0, 138.0, 92.0, 93.0, 94.0, 125.0, 134.0, 92.0], "value": 106.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 52.0, 50.0, 52.0, 50.0, 55.0, 52.0, 53.0, 53.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 55.0, 59.0, 66.0, 63.0, 58.0, 58.0, 56.0, 55.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 85.0, 85.0, 82.0, 86.0, 86.0, 92.0, 112.0, 89.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 136.0, 137.0, 134.0, 138.0, 135.0, 146.0, 150.0, 164.0], "value": 141.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 183.0, 184.0, 171.0, 186.0, 184.0, 185.0, 180.0, 186.0, 186.0], "value": 182.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 90.0, 64.0, 78.0, 88.0, 87.0, 91.0, 91.0, 93.0], "value": 82.3, "unit": "score"}]}]} 10:40:27 INFO - Return code: 0 10:40:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:40:27 INFO - # TBPL SUCCESS # 10:40:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:40:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:40:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:40:27 INFO - 2017-06-09 10:40:27.968 screenresolution[1671:11369] starting screenresolution argv=screenresolution get 10:40:27 INFO - 2017-06-09 10:40:27.971 screenresolution[1671:11369] Display 0: 1600x1200x32@60 10:40:27 INFO - 2017-06-09 10:40:27.976 screenresolution[1672:11372] starting screenresolution argv=screenresolution list 10:40:27 INFO - Available Modes on Display 0 10:40:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:40:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:40:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:40:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:40:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:40:28 INFO - Intel Iris: 10:40:28 INFO - Chipset Model: Intel Iris 10:40:28 INFO - Type: GPU 10:40:28 INFO - Bus: Built-In 10:40:28 INFO - VRAM (Dynamic, Max): 1536 MB 10:40:28 INFO - Vendor: Intel (0x8086) 10:40:28 INFO - Device ID: 0x0a2e 10:40:28 INFO - Revision ID: 0x0009 10:40:28 INFO - Displays: 10:40:28 INFO - Display: 10:40:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:40:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:40:28 INFO - Display Serial Number: 1600x1200 60 10:40:28 INFO - Main Display: Yes 10:40:28 INFO - Mirror: Off 10:40:28 INFO - Online: Yes 10:40:28 INFO - Rotation: Supported 10:40:28 INFO - Return code: 0 10:40:28 INFO - Running post-action listener: _resource_record_post_action 10:40:28 INFO - [mozharness: 2017-06-09 17:40:28.212711Z] Finished run-tests step (success) 10:40:28 INFO - Running post-run listener: _resource_record_post_run 10:40:28 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 33989632; Write bytes: 578710528; Read time: 226; Write time: 1789 10:40:28 INFO - TinderboxPrint: CPU usage
53.3% 10:40:28 INFO - TinderboxPrint: I/O read bytes / time
33,989,632 / 226 10:40:28 INFO - TinderboxPrint: I/O write bytes / time
578,710,528 / 1,789 10:40:28 INFO - TinderboxPrint: CPU idle
2,347.6 (46.7%) 10:40:28 INFO - TinderboxPrint: CPU system
1,034.6 (20.6%) 10:40:28 INFO - TinderboxPrint: CPU user
1,647.6 (32.8%) 10:40:28 INFO - TinderboxPrint: Swap in / out
281,698,304 / 0 10:40:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 253143552; Write bytes: 256739328; Read time: 18234; Write time: 554 10:40:28 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:40:28 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 30397440; Write bytes: 320521216; Read time: 197; Write time: 1229 10:40:28 INFO - Running post-run listener: _upload_blobber_files 10:40:28 INFO - Blob upload gear active. 10:40:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:40:28 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 10:40:28 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'] 10:40:28 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 10:40:29 INFO - (blobuploader) - INFO - Open directory for files ... 10:40:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:40:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:40:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:30 INFO - (blobuploader) - INFO - Done attempting. 10:40:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:40:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:40:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:31 INFO - (blobuploader) - INFO - Done attempting. 10:40:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:40:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:40:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:32 INFO - (blobuploader) - INFO - Done attempting. 10:40:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:40:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:40:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:40:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:40:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:40:34 INFO - (blobuploader) - INFO - Done attempting. 10:40:34 INFO - (blobuploader) - INFO - Iteration through files over. 10:40:34 INFO - Return code: 0 10:40:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:40:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:40:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f4ac8faa467aa6f32d64f0fdc6fb4576241c98e2459d37c0f48bb40d48e156db3c6ec8bf668db730a86ce44dbc222fb3539b95e592fffce0ef5c35c98dc304b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f4b7dbcda321dadfa59583a8cad4cdc5ebdcfb929b8a834a7b0fdf8300d4dbf9d724477899f4883d5b8ba28e19845a4e3b4e468d29aef3570da8dfceaab29b2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6014fc1fc1856681568cf5e20cc17f5ecc18f49c567196f97b7ef6bab340f3b7154c4f86a9266fdb6bc1d6953f3157dad8fe86e1d0fd870bf0b56e4b735d73c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fb2383466cbd60f630df69b0e0a876eca0e511a369ca1a7e058504dd0b0c8cd4f5dae6d2de7fc293d27f115d52d6aa28826c206eab48cf2996a07172c2287d2"} 10:40:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:40:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:40:34 INFO - Contents: 10:40:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2f4ac8faa467aa6f32d64f0fdc6fb4576241c98e2459d37c0f48bb40d48e156db3c6ec8bf668db730a86ce44dbc222fb3539b95e592fffce0ef5c35c98dc304b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f4b7dbcda321dadfa59583a8cad4cdc5ebdcfb929b8a834a7b0fdf8300d4dbf9d724477899f4883d5b8ba28e19845a4e3b4e468d29aef3570da8dfceaab29b2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6014fc1fc1856681568cf5e20cc17f5ecc18f49c567196f97b7ef6bab340f3b7154c4f86a9266fdb6bc1d6953f3157dad8fe86e1d0fd870bf0b56e4b735d73c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fb2383466cbd60f630df69b0e0a876eca0e511a369ca1a7e058504dd0b0c8cd4f5dae6d2de7fc293d27f115d52d6aa28826c206eab48cf2996a07172c2287d2"} 10:40:34 INFO - Running post-run listener: copy_logs_to_upload_dir 10:40:34 INFO - Copying logs to upload dir... 10:40:34 INFO - mkdir: /builds/slave/test/build/upload/logs 10:40:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.047299 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-09 10:40:34.112214) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-09 10:40:34.116692) ========= 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.FMtETx8hEC/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.vj0lsSXx32/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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/2f4ac8faa467aa6f32d64f0fdc6fb4576241c98e2459d37c0f48bb40d48e156db3c6ec8bf668db730a86ce44dbc222fb3539b95e592fffce0ef5c35c98dc304b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f4b7dbcda321dadfa59583a8cad4cdc5ebdcfb929b8a834a7b0fdf8300d4dbf9d724477899f4883d5b8ba28e19845a4e3b4e468d29aef3570da8dfceaab29b2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6014fc1fc1856681568cf5e20cc17f5ecc18f49c567196f97b7ef6bab340f3b7154c4f86a9266fdb6bc1d6953f3157dad8fe86e1d0fd870bf0b56e4b735d73c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fb2383466cbd60f630df69b0e0a876eca0e511a369ca1a7e058504dd0b0c8cd4f5dae6d2de7fc293d27f115d52d6aa28826c206eab48cf2996a07172c2287d2"} build_url:https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011342 build_url: 'https://queue.taskcluster.net/v1/task/PLMnlAH9Q_Sk8ZzwUksYsw/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/2f4ac8faa467aa6f32d64f0fdc6fb4576241c98e2459d37c0f48bb40d48e156db3c6ec8bf668db730a86ce44dbc222fb3539b95e592fffce0ef5c35c98dc304b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8f4b7dbcda321dadfa59583a8cad4cdc5ebdcfb929b8a834a7b0fdf8300d4dbf9d724477899f4883d5b8ba28e19845a4e3b4e468d29aef3570da8dfceaab29b2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6014fc1fc1856681568cf5e20cc17f5ecc18f49c567196f97b7ef6bab340f3b7154c4f86a9266fdb6bc1d6953f3157dad8fe86e1d0fd870bf0b56e4b735d73c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6fb2383466cbd60f630df69b0e0a876eca0e511a369ca1a7e058504dd0b0c8cd4f5dae6d2de7fc293d27f115d52d6aa28826c206eab48cf2996a07172c2287d2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-09 10:40:34.151419) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 10:40:34.151812) ========= 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.FMtETx8hEC/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.vj0lsSXx32/Listeners TMPDIR=/var/folders/hr/hkb07m1j4sq5c29hg511636400000w/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.005167 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 10:40:34.204348) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-09 10:40:34.205172) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-09 10:40:34.205893) ========= ========= Total master_lag: 0.19 =========