builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0022 starttime: 1497556326.57 results: success (0) revision: 00f228ced57d2e66110d090721ab38f495392d9a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:06.573378) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:06.573895) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:06.574185) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:06.597870) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:06.598239) ========= 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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-15 12:52:06-- 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% 155M=0s 2017-06-15 12:52:06 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119289 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:06.740598) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:06.740969) ========= 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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.021595 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:06.789192) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-15 12:52:06.792757) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 00f228ced57d2e66110d090721ab38f495392d9a --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 00f228ced57d2e66110d090721ab38f495392d9a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wzd0R9yQwa/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.9rDcbLjg0h/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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-15 12:52:06,876 truncating revision to first 12 chars 2017-06-15 12:52:06,876 Setting DEBUG logging. 2017-06-15 12:52:06,876 attempt 1/10 2017-06-15 12:52:06,876 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/00f228ced57d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-15 12:52:08,259 unpacking tar archive at: mozilla-central-00f228ced57d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.655728 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-15 12:52:08.469318) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:08.472680) ========= script_repo_revision: 00f228ced57d2e66110d090721ab38f495392d9a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:08.473069) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:08.473331) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 12:52:08.491085) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-15 12:52:08.491508) ========= /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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:52:08 INFO - MultiFileLogger online at 20170615 12:52:08 in /builds/slave/test 12:52:08 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 12:52:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:52:08 INFO - {'append_to_log': False, 12:52:08 INFO - 'base_work_dir': '/builds/slave/test', 12:52:08 INFO - 'blob_upload_branch': 'Firefox', 12:52:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:52:08 INFO - 'branch': 'Firefox', 12:52:08 INFO - 'buildbot_json_path': 'buildprops.json', 12:52:08 INFO - 'config_files': ('talos/mac_config.py',), 12:52:08 INFO - 'default_actions': ('clobber', 12:52:08 INFO - 'read-buildbot-config', 12:52:08 INFO - 'download-and-extract', 12:52:08 INFO - 'populate-webroot', 12:52:08 INFO - 'create-virtualenv', 12:52:08 INFO - 'install', 12:52:08 INFO - 'setup-mitmproxy', 12:52:08 INFO - 'run-tests'), 12:52:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:52:08 INFO - 'download_minidump_stackwalk': True, 12:52:08 INFO - 'download_symbols': 'ondemand', 12:52:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:52:08 INFO - 'tooltool.py': '/tools/tooltool.py', 12:52:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:52:08 INFO - '/tools/misc-python/virtualenv.py')}, 12:52:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:52:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:52:08 INFO - 'gecko_profile': False, 12:52:08 INFO - 'gecko_profile_interval': 0, 12:52:08 INFO - 'installer_path': 'installer.exe', 12:52:08 INFO - 'log_level': 'info', 12:52:08 INFO - 'log_name': 'talos', 12:52:08 INFO - 'log_to_console': True, 12:52:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:52:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:52:08 INFO - 'opt_config_files': (), 12:52:08 INFO - 'pip_index': False, 12:52:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:52:08 INFO - 'cmd': ('bash', 12:52:08 INFO - '-c', 12:52:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:52:08 INFO - 'enabled': True, 12:52:08 INFO - 'halt_on_failure': False, 12:52:08 INFO - 'name': 'check_screen_resolution'},), 12:52:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:52:08 INFO - 'cmd': ('bash', 12:52:08 INFO - '-c', 12:52:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:52:08 INFO - 'enabled': True, 12:52:08 INFO - 'halt_on_failure': False, 12:52:08 INFO - 'name': 'check_screen_resolution'},), 12:52:08 INFO - 'run_cmd_checks_enabled': True, 12:52:08 INFO - 'suite': 'dromaeojs-e10s', 12:52:08 INFO - 'system_bits': '32', 12:52:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:52:08 INFO - 'title': 't-yosemite-r7-0022', 12:52:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:52:08 INFO - 'use_talos_json': True, 12:52:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:52:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:52:08 INFO - 'work_dir': 'build'} 12:52:08 INFO - [mozharness: 2017-06-15 19:52:08.696610Z] Running clobber step. 12:52:08 INFO - Running pre-action listener: _resource_record_pre_action 12:52:08 INFO - Running main action method: clobber 12:52:08 INFO - rmtree: /builds/slave/test/build 12:52:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:52:11 INFO - Running post-action listener: _resource_record_post_action 12:52:11 INFO - [mozharness: 2017-06-15 19:52:11.064749Z] Finished clobber step (success) 12:52:11 INFO - [mozharness: 2017-06-15 19:52:11.065006Z] Running read-buildbot-config step. 12:52:11 INFO - Running pre-action listener: _resource_record_pre_action 12:52:11 INFO - Running main action method: read_buildbot_config 12:52:11 INFO - Using buildbot properties: 12:52:11 INFO - { 12:52:11 INFO - "project": "", 12:52:11 INFO - "product": "firefox", 12:52:11 INFO - "who": "archaeopteryx@coole-files.de", 12:52:11 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 12:52:11 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:52:11 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:52:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:52:11 INFO - "stage_platform": "macosx64", 12:52:11 INFO - "basedir": "/builds/slave/test", 12:52:11 INFO - "buildnumber": 26, 12:52:11 INFO - "platform": "yosemite_r7", 12:52:11 INFO - "parent_task_id": "FTThGpRES7qYQsJx2jc4pA", 12:52:11 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:52:11 INFO - "slavebuilddir": "test", 12:52:11 INFO - "taskId": "TJuO3KRwTu2MmguvLYM4ow", 12:52:11 INFO - "branch": "mozilla-central", 12:52:11 INFO - "script_repo_revision": "production", 12:52:11 INFO - "revision": "00f228ced57d2e66110d090721ab38f495392d9a", 12:52:11 INFO - "slavename": "t-yosemite-r7-0022", 12:52:11 INFO - "repo_path": "mozilla-central" 12:52:11 INFO - } 12:52:11 INFO - Finding installer, test and symbols from parent task. 12:52:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TJuO3KRwTu2MmguvLYM4ow'}, attempt #1 12:52:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TJuO3KRwTu2MmguvLYM4ow'}, attempt #1 12:52:12 INFO - Task dependencies: YDUo6z3DTK-34OOfSvGk4w FTThGpRES7qYQsJx2jc4pA 12:52:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA'}, attempt #1 12:52:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 12:52:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/test_packages.json 12:52:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/target.crashreporter-symbols.zip 12:52:13 INFO - Running post-action listener: _resource_record_post_action 12:52:13 INFO - [mozharness: 2017-06-15 19:52:13.012741Z] Finished read-buildbot-config step (success) 12:52:13 INFO - [mozharness: 2017-06-15 19:52:13.012870Z] Running download-and-extract step. 12:52:13 INFO - Running pre-action listener: _resource_record_pre_action 12:52:13 INFO - Running main action method: download_and_extract 12:52:13 INFO - mkdir: /builds/slave/test/build/tests 12:52: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')]} 12:52:13 INFO - trying https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 12:52:13 INFO - Downloading https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:52:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 12:52:14 INFO - Downloaded 1328 bytes. 12:52:14 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 12:52:14 INFO - Using the following test package requirements: 12:52:14 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 12:52:14 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:52:14 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 12:52:14 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 12:52:14 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'jsshell-mac.zip'], 12:52:14 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 12:52:14 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 12:52:14 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 12:52:14 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 12:52:14 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 12:52:14 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 12:52:14 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 12:52:14 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:52:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 12:52:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:52:14 INFO - Fetch https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 12:52:15 INFO - Content-Length response header: 59590176 12:52:15 INFO - Bytes received: 59590176 12:52:34 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:52:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 12:52:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:52:34 INFO - Fetch https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 12:52:36 INFO - Content-Length response header: 59590176 12:52:36 INFO - Bytes received: 59590176 12:52:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 12:52:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:52:54 INFO - Fetch https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 12:52:55 INFO - Content-Length response header: 14454477 12:52:55 INFO - Bytes received: 14454477 12:52:55 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')]} 12:52:55 INFO - trying https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 12:52:55 INFO - Downloading https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 12:52:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 12:52:57 INFO - Downloaded 69576582 bytes. 12:52:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 12:52:57 INFO - mkdir: /builds/slave/test/properties 12:52:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:52:57 INFO - Writing to file /builds/slave/test/properties/build_url 12:52:57 INFO - Contents: 12:52:57 INFO - build_url:https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 12:52:57 INFO - Running post-action listener: _resource_record_post_action 12:52:57 INFO - Running post-action listener: set_extra_try_arguments 12:52:57 INFO - [mozharness: 2017-06-15 19:52:57.602779Z] Finished download-and-extract step (success) 12:52:57 INFO - [mozharness: 2017-06-15 19:52:57.602943Z] Running populate-webroot step. 12:52:57 INFO - Running pre-action listener: _resource_record_pre_action 12:52:57 INFO - Running main action method: populate_webroot 12:52:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:52:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:52:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:52:57 INFO - '--tppagecycles', 12:52:57 INFO - '7'], 12:52:57 INFO - 'tests': ['tsvgm']}}, 12:52:57 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:52:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:52:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:52:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:52:57 INFO - 'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:52:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:52:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:52:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:52:57 INFO - 'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['damp', 'tps']}, 12:52:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:52:57 INFO - 'tests': ['damp', 'tps']}, 12:52:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['dromaeo_dom']}, 12:52:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:52:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:52:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:52:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:52:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:52:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['a11yr', 12:52:57 INFO - 'ts_paint', 12:52:57 INFO - 'tpaint', 12:52:57 INFO - 'sessionrestore', 12:52:57 INFO - 'sessionrestore_no_auto_restore', 12:52:57 INFO - 'tabpaint']}, 12:52:57 INFO - 'other-e10s': {'tests': ['a11yr', 12:52:57 INFO - 'ts_paint', 12:52:57 INFO - 'tpaint', 12:52:57 INFO - 'sessionrestore', 12:52:57 INFO - 'sessionrestore_no_auto_restore', 12:52:57 INFO - 'tabpaint']}, 12:52:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:52:57 INFO - 'ts_paint', 12:52:57 INFO - 'tpaint', 12:52:57 INFO - 'sessionrestore', 12:52:57 INFO - 'sessionrestore_no_auto_restore', 12:52:57 INFO - 'tabpaint']}, 12:52:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:52:57 INFO - 'ts_paint', 12:52:57 INFO - 'tpaint', 12:52:57 INFO - 'sessionrestore', 12:52:57 INFO - 'sessionrestore_no_auto_restore', 12:52:57 INFO - 'tabpaint']}, 12:52:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['a11yr', 12:52:57 INFO - 'ts_paint', 12:52:57 INFO - 'tpaint', 12:52:57 INFO - 'sessionrestore', 12:52:57 INFO - 'sessionrestore_no_auto_restore', 12:52:57 INFO - 'tabpaint']}, 12:52:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['a11yr', 12:52:57 INFO - 'ts_paint', 12:52:57 INFO - 'tpaint', 12:52:57 INFO - 'sessionrestore', 12:52:57 INFO - 'sessionrestore_no_auto_restore', 12:52:57 INFO - 'tabpaint']}, 12:52:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:52:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:52:57 INFO - 'bloom_basic_ref']}, 12:52:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 12:52:57 INFO - 'talos_options': ['--mitmproxy', 12:52:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 12:52:57 INFO - '--firstNonBlankPaint'], 12:52:57 INFO - 'tests': ['quantum_pageload_google', 12:52:57 INFO - 'quantum_pageload_youtube', 12:52:57 INFO - 'quantum_pageload_amazon', 12:52:57 INFO - 'quantum_pageload_facebook']}, 12:52:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['tsvgx', 12:52:57 INFO - 'tsvgr_opacity', 12:52:57 INFO - 'tart', 12:52:57 INFO - 'tscrollx', 12:52:57 INFO - 'cart', 12:52:57 INFO - 'tsvg_static']}, 12:52:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:52:57 INFO - 'tsvgr_opacity', 12:52:57 INFO - 'tart', 12:52:57 INFO - 'tscrollx', 12:52:57 INFO - 'cart', 12:52:57 INFO - 'tsvg_static']}, 12:52:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:52:57 INFO - 'talos_options': ['--disable-e10s'], 12:52:57 INFO - 'tests': ['tp5o']}, 12:52:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:52:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:52:57 INFO - 'talos_options': ['--disable-e10s', 12:52:57 INFO - '--xperf_path', 12:52:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:52:57 INFO - 'tests': ['tp5n']}, 12:52:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:52:57 INFO - 'talos_options': ['--xperf_path', 12:52:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:52:57 INFO - 'tests': ['tp5n']}}, 12:52:57 INFO - 'talos.zip': {'path': '', 12:52:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:52:57 INFO - Running post-action listener: _resource_record_post_action 12:52:57 INFO - [mozharness: 2017-06-15 19:52:57.625410Z] Finished populate-webroot step (success) 12:52:57 INFO - [mozharness: 2017-06-15 19:52:57.625538Z] Running create-virtualenv step. 12:52:57 INFO - Running pre-action listener: _resource_record_pre_action 12:52:57 INFO - Running main action method: create_virtualenv 12:52:57 INFO - Creating virtualenv /builds/slave/test/build/venv 12:52:57 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 12:52:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:52:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:52:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:52:57 INFO - Using real prefix '/tools/python27' 12:52:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:52:58 INFO - Installing distribute.............................................................................................................................................................................................done. 12:53:01 INFO - Installing pip.................done. 12:53:01 INFO - Return code: 0 12:53:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:53:01 INFO - Reading from file tmpfile_stdout 12:53:01 INFO - Output received: 12:53:01 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) 12:53:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:53: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')]} 12:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53: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')]} 12:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:01 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:01 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 12:53:01 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 12:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:01 INFO - 'HOME': '/Users/cltbld', 12:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:01 INFO - 'LOGNAME': 'cltbld', 12:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:01 INFO - 'MOZ_NO_REMOTE': '1', 12:53:01 INFO - 'NO_EM_RESTART': '1', 12:53:01 INFO - 'PAGER': '/bin/cat', 12:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:01 INFO - 'PWD': '/builds/slave/test', 12:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:01 INFO - 'SHELL': '/bin/bash', 12:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:01 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:01 INFO - 'USER': 'cltbld', 12:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:01 INFO - 'XPC_FLAGS': '0x0', 12:53:01 INFO - 'XPC_SERVICE_NAME': '0', 12:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:01 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 12:53:01 INFO - Cleaning up... 12:53:01 INFO - Return code: 0 12:53:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:53: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')]} 12:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53: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')]} 12:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:01 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:01 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 12:53:01 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 12:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:01 INFO - 'HOME': '/Users/cltbld', 12:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:01 INFO - 'LOGNAME': 'cltbld', 12:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:01 INFO - 'MOZ_NO_REMOTE': '1', 12:53:01 INFO - 'NO_EM_RESTART': '1', 12:53:01 INFO - 'PAGER': '/bin/cat', 12:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:01 INFO - 'PWD': '/builds/slave/test', 12:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:01 INFO - 'SHELL': '/bin/bash', 12:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:01 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:01 INFO - 'USER': 'cltbld', 12:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:01 INFO - 'XPC_FLAGS': '0x0', 12:53:01 INFO - 'XPC_SERVICE_NAME': '0', 12:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:02 INFO - Downloading/unpacking psutil>=3.1.1 12:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:53:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:53:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:53:03 INFO - Installing collected packages: psutil 12:53:03 INFO - Running setup.py install for psutil 12:53:04 INFO - building 'psutil._psutil_osx' extension 12:53:04 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 12:53:04 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 12:53:04 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 12:53:04 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 12:53:04 INFO - building 'psutil._psutil_posix' extension 12:53:04 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 12:53:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:53:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:53:04 INFO - ^ 12:53:04 INFO - 1 warning generated. 12:53:04 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 12:53:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:53:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:53:04 INFO - Successfully installed psutil 12:53:04 INFO - Cleaning up... 12:53:04 INFO - Return code: 0 12:53:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:53:04 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')]} 12:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:05 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')]} 12:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:05 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:05 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 12:53:05 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 12:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:05 INFO - 'HOME': '/Users/cltbld', 12:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:05 INFO - 'LOGNAME': 'cltbld', 12:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:05 INFO - 'MOZ_NO_REMOTE': '1', 12:53:05 INFO - 'NO_EM_RESTART': '1', 12:53:05 INFO - 'PAGER': '/bin/cat', 12:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:05 INFO - 'PWD': '/builds/slave/test', 12:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:05 INFO - 'SHELL': '/bin/bash', 12:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:05 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:05 INFO - 'USER': 'cltbld', 12:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:05 INFO - 'XPC_FLAGS': '0x0', 12:53:05 INFO - 'XPC_SERVICE_NAME': '0', 12:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:53:06 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 12:53:06 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 12:53:06 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 12:53:06 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 12:53:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:53:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:53:07 INFO - Installing collected packages: mozsystemmonitor 12:53:07 INFO - Running setup.py install for mozsystemmonitor 12:53:08 INFO - Successfully installed mozsystemmonitor 12:53:08 INFO - Cleaning up... 12:53:08 INFO - Return code: 0 12:53:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:08 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:08 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 12:53:08 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 12:53:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:08 INFO - 'HOME': '/Users/cltbld', 12:53:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:08 INFO - 'LOGNAME': 'cltbld', 12:53:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:08 INFO - 'MOZ_NO_REMOTE': '1', 12:53:08 INFO - 'NO_EM_RESTART': '1', 12:53:08 INFO - 'PAGER': '/bin/cat', 12:53:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:08 INFO - 'PWD': '/builds/slave/test', 12:53:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:08 INFO - 'SHELL': '/bin/bash', 12:53:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:08 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:08 INFO - 'USER': 'cltbld', 12:53:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:08 INFO - 'XPC_FLAGS': '0x0', 12:53:08 INFO - 'XPC_SERVICE_NAME': '0', 12:53:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:08 INFO - Downloading/unpacking jsonschema==2.5.1 12:53: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 12:53: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 12:53: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 12:53: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 12:53:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:53:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:53:09 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 12:53:09 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 12:53:09 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 12:53:09 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 12:53:10 INFO - Downloading functools32-3.2.3-2.tar.gz 12:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:53:10 INFO - warning: no files found matching '*.txt' 12:53:10 INFO - no previously-included directories found matching 'build' 12:53:10 INFO - no previously-included directories found matching 'dist' 12:53:10 INFO - no previously-included directories found matching '.git*' 12:53:10 INFO - Installing collected packages: jsonschema, functools32 12:53:10 INFO - Running setup.py install for functools32 12:53:10 INFO - warning: no files found matching '*.txt' 12:53:10 INFO - no previously-included directories found matching 'build' 12:53:10 INFO - no previously-included directories found matching 'dist' 12:53:10 INFO - no previously-included directories found matching '.git*' 12:53:10 INFO - Successfully installed jsonschema functools32 12:53:10 INFO - Cleaning up... 12:53:10 INFO - Return code: 0 12:53:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:10 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:10 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 12:53:10 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 12:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:10 INFO - 'HOME': '/Users/cltbld', 12:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:10 INFO - 'LOGNAME': 'cltbld', 12:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:10 INFO - 'MOZ_NO_REMOTE': '1', 12:53:10 INFO - 'NO_EM_RESTART': '1', 12:53:10 INFO - 'PAGER': '/bin/cat', 12:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:10 INFO - 'PWD': '/builds/slave/test', 12:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:10 INFO - 'SHELL': '/bin/bash', 12:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:10 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:10 INFO - 'USER': 'cltbld', 12:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:10 INFO - 'XPC_FLAGS': '0x0', 12:53:10 INFO - 'XPC_SERVICE_NAME': '0', 12:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:53:10 INFO - Cleaning up... 12:53:10 INFO - Return code: 0 12:53:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:10 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:10 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 12:53:10 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 12:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:10 INFO - 'HOME': '/Users/cltbld', 12:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:10 INFO - 'LOGNAME': 'cltbld', 12:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:10 INFO - 'MOZ_NO_REMOTE': '1', 12:53:10 INFO - 'NO_EM_RESTART': '1', 12:53:10 INFO - 'PAGER': '/bin/cat', 12:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:10 INFO - 'PWD': '/builds/slave/test', 12:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:10 INFO - 'SHELL': '/bin/bash', 12:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:10 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:10 INFO - 'USER': 'cltbld', 12:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:10 INFO - 'XPC_FLAGS': '0x0', 12:53:10 INFO - 'XPC_SERVICE_NAME': '0', 12:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:10 INFO - Downloading/unpacking blobuploader==1.2.4 12:53:10 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 12:53:10 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 12:53:10 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 12:53:10 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 12:53:12 INFO - Downloading blobuploader-1.2.4.tar.gz 12:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:53:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:53:12 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 12:53:12 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 12:53:12 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 12:53:12 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 12:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:53:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:53: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 12:53: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 12:53: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 12:53: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 12:53:13 INFO - Downloading docopt-0.6.1.tar.gz 12:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:53:13 INFO - Installing collected packages: blobuploader, requests, docopt 12:53:13 INFO - Running setup.py install for blobuploader 12:53:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:53:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:53:13 INFO - Running setup.py install for requests 12:53:14 INFO - Running setup.py install for docopt 12:53:14 INFO - Successfully installed blobuploader requests docopt 12:53:14 INFO - Cleaning up... 12:53:14 INFO - Return code: 0 12:53:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:53:14 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')]} 12:53:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:14 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')]} 12:53:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:14 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:14 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 12:53:14 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 12:53:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:14 INFO - 'HOME': '/Users/cltbld', 12:53:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:14 INFO - 'LOGNAME': 'cltbld', 12:53:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:14 INFO - 'MOZ_NO_REMOTE': '1', 12:53:14 INFO - 'NO_EM_RESTART': '1', 12:53:14 INFO - 'PAGER': '/bin/cat', 12:53:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:14 INFO - 'PWD': '/builds/slave/test', 12:53:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:14 INFO - 'SHELL': '/bin/bash', 12:53:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:14 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:14 INFO - 'USER': 'cltbld', 12:53:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:14 INFO - 'XPC_FLAGS': '0x0', 12:53:14 INFO - 'XPC_SERVICE_NAME': '0', 12:53:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:53:14 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-C8mSHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:53:14 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-MkyGSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:53:14 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-aGgvpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:53:14 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-OeK1cY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:53:14 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-tBVV1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-CSPT2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Xf94yr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-MWGxiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-qp1oY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-EJvgbd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-5aQbgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-9y_GEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Cm_9u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-NrPDES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:15 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-5M5bec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:53:16 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-7lDqjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:53:16 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-GhNnrT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:53:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:53:16 INFO - Running setup.py install for manifestparser 12:53:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:53:16 INFO - Running setup.py install for mozcrash 12:53:16 INFO - Running setup.py install for mozdebug 12:53:16 INFO - Running setup.py install for mozdevice 12:53:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:53:16 INFO - Running setup.py install for mozfile 12:53:17 INFO - Running setup.py install for mozhttpd 12:53:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:53:17 INFO - Running setup.py install for mozinfo 12:53:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:53:17 INFO - Running setup.py install for mozInstall 12:53:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:53:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:53:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:53:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:53:17 INFO - Running setup.py install for mozleak 12:53:17 INFO - Running setup.py install for mozlog 12:53:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:53:17 INFO - Running setup.py install for moznetwork 12:53:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:53:17 INFO - Running setup.py install for mozprocess 12:53:18 INFO - Running setup.py install for mozprofile 12:53:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:53:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:53:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:53:18 INFO - Running setup.py install for mozrunner 12:53:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:53:18 INFO - Running setup.py install for mozscreenshot 12:53:18 INFO - Running setup.py install for moztest 12:53:18 INFO - Running setup.py install for mozversion 12:53:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:53:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:53:18 INFO - Cleaning up... 12:53:18 INFO - Return code: 0 12:53:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:53:18 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')]} 12:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:18 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')]} 12:53:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:18 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:18 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 12:53:18 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 12:53:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:18 INFO - 'HOME': '/Users/cltbld', 12:53:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:18 INFO - 'LOGNAME': 'cltbld', 12:53:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:18 INFO - 'MOZ_NO_REMOTE': '1', 12:53:18 INFO - 'NO_EM_RESTART': '1', 12:53:18 INFO - 'PAGER': '/bin/cat', 12:53:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:18 INFO - 'PWD': '/builds/slave/test', 12:53:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:18 INFO - 'SHELL': '/bin/bash', 12:53:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:18 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:18 INFO - 'USER': 'cltbld', 12:53:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:18 INFO - 'XPC_FLAGS': '0x0', 12:53:18 INFO - 'XPC_SERVICE_NAME': '0', 12:53:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-DgQTU0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:53:19 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)) 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-CgEzG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:53:19 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)) 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-pqgEtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:53:19 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)) 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-87voMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:53:19 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)) 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-XBTbzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:53:19 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)) 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-A6Ir04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:53:19 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)) 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-kYn_EM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:53:19 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)) 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-tpq93m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 12:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:53:19 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-NEt3l5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:53:20 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)) 12:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:53:20 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-mBrLmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:53:20 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)) 12:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:53:20 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-C3PeYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:53:20 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)) 12:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:53:20 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-h6RnMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:53:20 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)) 12:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:53:20 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-a7HTY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:53:20 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)) 12:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:53:20 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-TgiTyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:53:20 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)) 12:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:20 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-FgXaHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:53:20 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)) 12:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:53:20 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-8c9P9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:53:20 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)) 12:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:53:20 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-I8CYsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:53:20 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)) 12:53:20 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)) 12:53:20 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)) 12:53:20 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)) 12:53:20 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)) 12:53:20 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)) 12:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:53:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:53:20 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 12:53:20 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 12:53:20 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 12:53:20 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 12:53:22 INFO - Downloading blessings-1.6.tar.gz 12:53:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:53:22 INFO - Installing collected packages: blessings 12:53:22 INFO - Running setup.py install for blessings 12:53:22 INFO - Successfully installed blessings 12:53:22 INFO - Cleaning up... 12:53:22 INFO - Return code: 0 12:53:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:53:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:53:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:53:22 INFO - Reading from file tmpfile_stdout 12:53:22 INFO - Current package versions: 12:53:22 INFO - blessings == 1.6 12:53:22 INFO - blobuploader == 1.2.4 12:53:22 INFO - docopt == 0.6.1 12:53:22 INFO - functools32 == 3.2.3-2 12:53:22 INFO - jsonschema == 2.5.1 12:53:22 INFO - manifestparser == 1.1 12:53:22 INFO - mozInstall == 1.13 12:53:22 INFO - mozcrash == 1.0 12:53:22 INFO - mozdebug == 0.1 12:53:22 INFO - mozdevice == 0.51 12:53:22 INFO - mozfile == 1.2 12:53:22 INFO - mozhttpd == 0.7 12:53:22 INFO - mozinfo == 0.10 12:53:22 INFO - mozleak == 0.1 12:53:22 INFO - mozlog == 3.4 12:53:22 INFO - moznetwork == 0.27 12:53:22 INFO - mozprocess == 0.25 12:53:22 INFO - mozprofile == 0.28 12:53:22 INFO - mozrunner == 6.13 12:53:22 INFO - mozscreenshot == 0.1 12:53:22 INFO - mozsystemmonitor == 0.3 12:53:22 INFO - moztest == 0.8 12:53:22 INFO - mozversion == 1.4 12:53:22 INFO - psutil == 3.1.1 12:53:22 INFO - requests == 1.2.3 12:53:22 INFO - wsgiref == 0.1.2 12:53:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:53:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:22 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:22 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 12:53:22 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 12:53:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:22 INFO - 'HOME': '/Users/cltbld', 12:53:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:22 INFO - 'LOGNAME': 'cltbld', 12:53:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:22 INFO - 'MOZ_NO_REMOTE': '1', 12:53:22 INFO - 'NO_EM_RESTART': '1', 12:53:22 INFO - 'PAGER': '/bin/cat', 12:53:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:22 INFO - 'PWD': '/builds/slave/test', 12:53:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:22 INFO - 'SHELL': '/bin/bash', 12:53:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:22 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:22 INFO - 'USER': 'cltbld', 12:53:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:22 INFO - 'XPC_FLAGS': '0x0', 12:53:22 INFO - 'XPC_SERVICE_NAME': '0', 12:53:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:23 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)) 12:53:23 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)) 12:53:23 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)) 12:53:23 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)) 12:53:23 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)) 12:53:23 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)) 12:53:23 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)) 12:53:23 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)) 12:53:23 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)) 12:53:23 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:53:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:53:24 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:53:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:53:24 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)) 12:53:24 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)) 12:53:24 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)) 12:53:24 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)) 12:53:24 INFO - Installing collected packages: marionette-driver 12:53:24 INFO - Running setup.py install for marionette-driver 12:53:24 INFO - Successfully installed marionette-driver 12:53:24 INFO - Cleaning up... 12:53:24 INFO - Return code: 0 12:53:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:53: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')]} 12:53:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:53: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')]} 12:53:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:53:24 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 0x108776c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10877a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd8ab475330>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, '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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 12:53:24 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 12:53:24 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 12:53:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:24 INFO - 'HOME': '/Users/cltbld', 12:53:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:24 INFO - 'LOGNAME': 'cltbld', 12:53:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:24 INFO - 'MOZ_NO_REMOTE': '1', 12:53:24 INFO - 'NO_EM_RESTART': '1', 12:53:24 INFO - 'PAGER': '/bin/cat', 12:53:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:24 INFO - 'PWD': '/builds/slave/test', 12:53:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:24 INFO - 'SHELL': '/bin/bash', 12:53:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:24 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:24 INFO - 'USER': 'cltbld', 12:53:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:24 INFO - 'XPC_FLAGS': '0x0', 12:53:24 INFO - 'XPC_SERVICE_NAME': '0', 12:53:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:53:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:53:25 INFO - Cleaning up... 12:53:25 INFO - Return code: 0 12:53:25 INFO - Running post-action listener: _resource_record_post_action 12:53:25 INFO - Running post-action listener: _start_resource_monitoring 12:53:25 INFO - Starting resource monitoring. 12:53:25 INFO - [mozharness: 2017-06-15 19:53:25.258886Z] Finished create-virtualenv step (success) 12:53:25 INFO - [mozharness: 2017-06-15 19:53:25.260029Z] Running install step. 12:53:25 INFO - Running pre-action listener: _resource_record_pre_action 12:53:25 INFO - Running main action method: install 12:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:53:25 INFO - Reading from file tmpfile_stdout 12:53:25 INFO - Detecting whether we're running mozinstall >=1.0... 12:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:53:25 INFO - Reading from file tmpfile_stdout 12:53:25 INFO - Output received: 12:53:25 INFO - Usage: mozinstall [options] installer 12:53:25 INFO - Options: 12:53:25 INFO - -h, --help show this help message and exit 12:53:25 INFO - -d DEST, --destination=DEST 12:53:25 INFO - Directory to install application into. [default: 12:53:25 INFO - "/builds/slave/test"] 12:53:25 INFO - --app=APP Application being installed. [default: firefox] 12:53:25 INFO - mkdir: /builds/slave/test/build/application 12:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:53:47 INFO - Reading from file tmpfile_stdout 12:53:47 INFO - Output received: 12:53:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:53:47 INFO - Running post-action listener: _resource_record_post_action 12:53:47 INFO - [mozharness: 2017-06-15 19:53:47.700909Z] Finished install step (success) 12:53:47 INFO - [mozharness: 2017-06-15 19:53:47.701083Z] Running setup-mitmproxy step. 12:53:47 INFO - Running pre-action listener: _resource_record_pre_action 12:53:47 INFO - Running main action method: setup_mitmproxy 12:53:47 INFO - Skipping: mitmproxy is not required 12:53:47 INFO - Running post-action listener: _resource_record_post_action 12:53:47 INFO - [mozharness: 2017-06-15 19:53:47.701808Z] Finished setup-mitmproxy step (success) 12:53:47 INFO - [mozharness: 2017-06-15 19:53:47.701920Z] Running run-tests step. 12:53:47 INFO - Running pre-action listener: _resource_record_pre_action 12:53:47 INFO - Running main action method: run_tests 12:53:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:53:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:53:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:53:47 INFO - 2017-06-15 12:53:47.715 screenresolution[1992:14962] starting screenresolution argv=screenresolution get 12:53:47 INFO - 2017-06-15 12:53:47.717 screenresolution[1992:14962] Display 0: 1600x1200x32@60 12:53:47 INFO - 2017-06-15 12:53:47.722 screenresolution[1993:14964] starting screenresolution argv=screenresolution list 12:53:47 INFO - Available Modes on Display 0 12:53:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:53:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:53:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:53:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:53:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:53:47 INFO - Intel Iris: 12:53:47 INFO - Chipset Model: Intel Iris 12:53:47 INFO - Type: GPU 12:53:47 INFO - Bus: Built-In 12:53:47 INFO - VRAM (Dynamic, Max): 1536 MB 12:53:47 INFO - Vendor: Intel (0x8086) 12:53:47 INFO - Device ID: 0x0a2e 12:53:47 INFO - Revision ID: 0x0009 12:53:47 INFO - Displays: 12:53:47 INFO - Display: 12:53:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:53:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:53:47 INFO - Display Serial Number: 1600x1200 60 12:53:47 INFO - Main Display: Yes 12:53:47 INFO - Mirror: Off 12:53:47 INFO - Online: Yes 12:53:47 INFO - Rotation: Supported 12:53:47 INFO - Return code: 0 12:53:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:53:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:53:47 INFO - Python 2.7.3 12:53:47 INFO - Return code: 0 12:53:47 INFO - grabbing minidump binary from tooltool 12:53:47 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')]} 12:53:47 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 0x1084ccd30>, '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 0x108496cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1084bdbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:53:47 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 12:53:47 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 12:53:47 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 12:53:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:53:48 INFO - Return code: 0 12:53:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:53:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:53:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:53:48 INFO - ENV: RUST_BACKTRACE is now 1 12:53:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:53:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:53:48 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/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--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 12:53:48 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/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0022 --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 12:53:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wzd0R9yQwa/Render', 12:53:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:53:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:53:48 INFO - 'HOME': '/Users/cltbld', 12:53:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:53:48 INFO - 'LOGNAME': 'cltbld', 12:53:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:53:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:53:48 INFO - 'MOZ_NO_REMOTE': '1', 12:53:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:53:48 INFO - 'NO_EM_RESTART': '1', 12:53:48 INFO - 'PAGER': '/bin/cat', 12:53:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:53:48 INFO - 'PWD': '/builds/slave/test', 12:53:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:53:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:53:48 INFO - 'RUST_BACKTRACE': '1', 12:53:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:53:48 INFO - 'SHELL': '/bin/bash', 12:53:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9rDcbLjg0h/Listeners', 12:53:48 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 12:53:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:53:48 INFO - 'USER': 'cltbld', 12:53:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:53:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:53:48 INFO - 'XPC_FLAGS': '0x0', 12:53:48 INFO - 'XPC_SERVICE_NAME': '0', 12:53:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:53:48 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/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--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 12:53:48 INFO - mozversion application_buildid: 20170615110815 12:53:48 INFO - mozversion application_changeset: 00f228ced57d2e66110d090721ab38f495392d9a 12:53:48 INFO - mozversion application_display_name: Nightly 12:53:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:53:48 INFO - mozversion application_name: Firefox 12:53:48 INFO - mozversion application_remotingname: firefox 12:53:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:53:48 INFO - mozversion application_vendor: Mozilla 12:53:48 INFO - mozversion application_version: 56.0a1 12:53:48 INFO - mozversion platform_buildid: 20170615110815 12:53:48 INFO - mozversion platform_changeset: 00f228ced57d2e66110d090721ab38f495392d9a 12:53:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:53:48 INFO - mozversion platform_version: 56.0a1 12:53:48 INFO - using testdate: 1497556428 12:53:48 INFO - actual date: 1497556428 12:53:48 INFO - starting webserver on 'localhost:49494' 12:53:48 INFO - SUITE-START | Running 2 tests 12:53:48 INFO - TEST-START | dromaeo_css 12:53:48 INFO - operating with platform_type : mac_ 12:53:48 INFO - Initialising browser for dromaeo_css test... 12:53:48 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49494/getInfo.html -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpjCWhCP/profile 12:53:48 INFO - TEST-INFO | started process 2003 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49494/getInfo.html -foreground) 12:54:21 INFO - TEST-INFO | 2003: killed by SIGTERM 12:54:21 INFO - Browser initialized. 12:54:21 INFO - Running cycle 1/1 for dromaeo_css test... 12:54:21 INFO - TEST-INFO | started process 2007 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpjCWhCP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:54:23 INFO - PID 2007 | 2017-06-15 12:54:23.840 plugin-container[2008:15423] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 12:54:23 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:54:23 INFO - PID 2007 | 2017-06-15 12:54:23.840 plugin-container[2008:15423] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 12:54:23 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:54:23 INFO - PID 2007 | 2017-06-15 12:54:23.841 plugin-container[2008:15423] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:54:24 INFO - PID 2007 | 2017-06-15 12:54:24.672 plugin-container[2009:15468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 12:54:24 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:54:24 INFO - PID 2007 | 2017-06-15 12:54:24.672 plugin-container[2009:15468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 12:54:24 INFO - PID 2007 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:54:24 INFO - PID 2007 | 2017-06-15 12:54:24.673 plugin-container[2009:15468] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:54:34 INFO - PID 2007 | RSS: Main: 254537728 12:54:34 INFO - PID 2007 | 12:57:38 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49494/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49494/tests/dromaeo/cssquery-ext.html) 12:57:39 INFO - PID 2007 | RSS: Main: 268877824 12:57:39 INFO - PID 2007 | 13:00:41 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49494/tests/dromaeo/cssquery-ext.html (next: http://localhost:49494/tests/dromaeo/cssquery-jquery.html) 13:00:42 INFO - PID 2007 | RSS: Main: 275849216 13:00:42 INFO - PID 2007 | 13:03:43 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49494/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49494/tests/dromaeo/cssquery-mootools.html) 13:03:44 INFO - PID 2007 | RSS: Main: 278306816 13:03:44 INFO - PID 2007 | 13:06:45 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49494/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49494/tests/dromaeo/cssquery-prototype.html) 13:06:46 INFO - PID 2007 | RSS: Main: 278646784 13:06:46 INFO - PID 2007 | 13:09:49 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49494/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49494/tests/dromaeo/cssquery-yui.html) 13:09:50 INFO - PID 2007 | RSS: Main: 280776704 13:09:50 INFO - PID 2007 | 13:12:51 INFO - PID 2007 | Cycle 1(1): loaded http://localhost:49494/tests/dromaeo/cssquery-yui.html (next: http://localhost:49494/tests/dromaeo/cssquery-dojo.html) 13:12:51 INFO - PID 2007 | RSS: Main: 278892544 13:12:51 INFO - PID 2007 | 13:12:51 INFO - PID 2007 | __start_tp_report 13:12:51 INFO - PID 2007 | _x_x_mozilla_page_load 13:12:51 INFO - PID 2007 | _x_x_mozilla_page_load_details 13:12:51 INFO - PID 2007 | |i|pagename|runs| 13:12:51 INFO - PID 2007 | |0;dojo.html;3485.5991560970497;3547.5742910850704;3599.208174201675;3616.150204701896;3625.474306225598;5228.5555727763185;6103.969480152598;6150.09347102398;6234.0648902664625;6238.4385405313515;6187.783427580036;6197.5041996640275;6248.37516248375;6272.184615999925;6286.874262514747;5521;5526.530244929186;5532.5573954083675;5574.386817450078;5654.038813401711;5632.971835140818;5640.323161220639;5648.717564121798;5651.8304450866535;5662.37713851476;5380.569554435636;5539.556835453154;5542.39033706292;5552.972700050504;5595.160725891149;5430.83707488776;5473.644213126174;5496.477834605706;5513.173024046425;5515.531179849718;5429.104197807357;5482.095454250044;5504.2018907258625;5561.221428999943;5563.248961390201;6158.6612736299485;6273.130921640842;6282.183316168915;6290.087937249074;6298.307186209513;6825.453963682893;6837.726490940406;6845.247022827485;6854.657267136673;6857.759978400782;6680.331966803281;6789.321067893221;6793.286704896013;6933.68798404068;6954.40887524561;5370.301860758076;6325.746970121236;6360.363963603649;6383.138276332728;6396;6874.2782007889455;7044.084269044992;7050.647467626598;7052.435805135577;7060.117485314336;6509.967450162719;6568.638724870178;6660.400563949268;6701.296363881722;6722.798316050526;6733.3939945405145;6743.15710536183;6745.460363170934;6754.966225168941;6779.0509328694025;6096.542759293071;6663.800085997428;6733.562318449285;6790.253072162058;6806.625635589991;5301.655392399482;5756.9061878243;6754.864902701918;6762.83092922671;6783.830404239933;4522.977385113053;4652.813887444532;5264.6314757966575;5340.95851308991;5440.537554307928;4795.256735206049;4802.447718512416;4850.733209673502;4913.992631510532;4933.136701077369;5032.917922646649;5766.538676905838;6318.399752023551;6354.745810167634;6395.904061439082;6789.558678685869;6890.793276201722;6892.827679308058;6924.030635711005;6944.861102778017;6198.783042593487;6297.685115744194;6329.3354197809485;6351.809445716636;6393.9360606394275;5823.068309070529;6080.969595152084;6084.087386892002;6085.9695701521205;6093.786717464867;6813.080234168324;6878.931210687929;6895.75864844728;7009.614471204132;7044.718211271492;6640.800775976535;6727.730890764315;6764.458843292623;6773.221079575912;6802.217744959393;5579.888402231852;6917.27368626287;6922.7923162305215;6950.096487456598;6961.61711105894;6637.037629543785;6644.368784965421;6647.036179754005;6661.067450557023;6667.2666006738245;6663.400293973566;6779.35596118368;6787.694553744995;6788.981652752127;6825.1127353443735;6513.804585862432;6586.703598338182;6588.868222635616;6598.868022639616;6603.108580341785;6586.769463068769;6595.967020164869;6597.208334999831;6597.868042639217;6603.966980165068;6163.630182189083;6443.291237963726;6466.935330646634;6479.967600161969;6484.870302593828;6428.742850285937;6512.120863683342;6514.315996820265;6539.346065393423;6574.967125164344;6510.283868774526;6516.869662606816;6533.803985880431;6548.8362790930605;6577.2436169841085 13:12:51 INFO - PID 2007 | |1;ext.html;714.832014476598;765.9578723170226;820.2453742556847;838.8657814749641;1217.567763443977;32590.674093259062;36735;36744;36779.63220367795;36870.078248043814;37250;37539.62460375395;37540.62459375405;37590.43614345787;37695;6711.228208756007;7797.844043119134;7799.922000779976;7801.141874393812;7805.141434442206;7274.890876636855;7751.0311211098615;7766.728164514243;7936.920630793676;8138.104808471064;8146.144654811248;8222.301104406133;8224.300934420582;8224.917750822475;8228.83542329153;7261.164966028895;8082.515049097073;8139.145389734082;8140;8204.917950820534;7938.960305198437;7949.165337639581;7954.801129971739;7989;7996.24035716606;34005.82997084999;34054.659453405395;34091.6590834091;34116.48825267623;34202;3505.5968563615256;3510.5787305523245;3542.787432754017;3547.6452354764574;3549.8935031948827;28379.858100709363;28549.000784972635;28677.713222867715;28693.713062869312;28731.425371492664;3055.7555395568525;3062.846857657108;3072.646645635758;3089.165925200206;3105.192649911017;247655.52344476504;253550;253643;264022;305524;44671.32993005108;45057.64827055194;45175.322370164475;45205;45254;39390.80304598516;46963.29555056677;46975.5302446978;47099.29351059737;47162;12992.090553661154;13424.865751342557;14283.92858035724;14284;14288.285585720672;11665.416729163508;11709;11714.004309633776;11722.355270460035;11744.412779360997;11467.369294688706;12084.939575302244;12520.624381268577;12846.486140554272;12970.416331265116;5790.247267855152;5791.710414479344;5793.768249269982;5793.971030144822;5864.530837532987;995.6266400099962;1000.8648832407678;1006.1598565198142;1059.6556119261118;1061.867266591676;19646.70529942052;22014.44963875916;22185.778142218693;22506.32481025572;22520.986555605035;1085.1535802074395;1099.543689368903;1145.2498613408065;1159.4202898550725;1161.285809227336;11624.6512604622;11665.833416658266;11682.299062056303;12044.313796068558;15288.006279591791;349592;350596;351050;351399;352557;52169;52535;52608.47391526112;52692;52764.73617631964;1466.2961778346453;1468.2438544149772;1480.5558332500207;1481.2519677562761;1487.4422091715608;26949.59575606328;27552.586711198946;28219.858900705363;28658.85670571634;28672.85663571669;35607.75372861938;35715.10712232215;35787.28425431529;35861.64138358687;35906;281514;281525;281553;282143;282431;27731.168064958085;28140.87436502599;28187.859060704566;32924.34151317008;33373.16567085842;32776.836115820224;33743;37981;38247;40059.7982060527;27114.915403383642;27518.312042199104;27909.44181116407;28941.10588941089;28994.420111598072;27382.726172738814;28444.146675599764;28808.567871481526;28906.566401504417;29239.83040678349;26044;32188.839055804572;32908.67091329152;33848;33962.490562641084;25046.74953250444;27084.458310833277;27469.31326716848;29283.85358073281;30634.693653064074;19346.4196074118;24126.87936560306;24169.63745543783;24303;24481 13:12:51 INFO - PID 2007 | |2;jquery.html;498.499008496461;499.48054023815234;500.37703059690676;509.58723434018435;525.3800515391836;21364.931753412326;21943.451413714665;21949.122035118577;22109.557808843812;22132.446688832788;24286.392840179004;24297.39256518588;24320.513589728194;24520.896559654815;24582.385440364;6344.651044192556;6521.119648847416;6558.639274839882;6581.80254592363;6660.633665148415;6331.398517140888;6432.292447830735;6520.054592084145;6550.639714815683;6554.836129096763;6012.819615411545;6386.042093685938;6404.839879003016;6406.4554512866225;6420.839479013039;6388.744450221986;6397.264314603834;6404.4236018758065;6409.647469389181;6417.005364168562;5866.354700982888;5879.265091863517;5882.941170588282;5893.528517718572;5896.351401345849;28139.29651758702;28514.71485285162;29227;29514.409711805645;29536.409271814442;1928.1323404467905;1976.7529058867642;2260.559190957764;2279.4073540879326;2304.0207911637485;18549.258029678796;19119.80880191208;19138.23447062116;19163.94598297093;19187.52031199217;2162.4593851537115;2167.902444390006;2216.213244298267;2233.352327824929;2234.36320648616;169283.15358423252;172881;173417;180934;181306.18693813158;12458.31479268649;13580.45678172862;14171.511991240977;14725.926370368079;14870.851291486946;19382.61234775325;19479;19510;19568;19583.314583989493;9767.267454940908;9859.112679858847;9901.257405694458;9942.602295908082;9959.302848800658;9229.446233226028;9246.583903724348;9256.8611470828;9260.583273752563;9315.347925645276;8017.518948863087;8632.741017769476;8893.555322233862;9034.683786067457;9087.273018158532;2535.594304911205;2539.5809691400714;2545.7963362930923;2569.486102779452;2571.3186005708503;976.4434272464769;991.0832479956011;1010.8129995950773;1025.5436330832708;1072.5173671847544;21006.579868402547;21072.683909741365;21094.367168984954;21538.523069846855;22712.205072822373;1040.8750949886062;1048.70112018076;1058.2486434631498;1067.5730903798892;1073.0878753059837;10676.51955661997;11077.778444431227;11153.776924461465;11204.271722338022;11465.713357166138;504114;522494;523364;525879;568500;117479;120446;142712;143431;143481;1198.6943329450935;1212.8423304970297;1233.5559198688466;1242.87571242875;1245.4893493667553;19133.425997220107;19492.122854471865;20110.497237568594;20150.09324580368;20503.38489845307;68692;69217;70905.29094708987;71915;72227;93208;93675.53162234146;94993.5250323744;97563.51218243863;98488.50755746462;11604.593839215588;11642.592509262135;11709.180357374902;11812.055035596959;11860.644180674593;18400.723989139908;18537.629247415247;18553.907230463763;18837.434876953714;18872.961987090843;14330.426782928565;15306;15324.846751532343;15341.309641066402;15451;11791.349211110622;13139.277339746406;13411.798823017467;13469.191848489121;14363.138211706913;15516.689666206386;15811.209439527747;15821.683566328838;15870.7619385707;15889.761653575437;11472.31166130035;11598.362090085126;11628.069754419796;11641.708957275798;11654;13930;15084.849151508344;15151.939364244397;15156.090634561962;15221.315040822969 13:12:52 INFO - PID 2007 | |3;mootools.html;1251.7746805574998;1430.9284535773209;1449.5433938309425;1456.9708605827884;1472.300657187836;13563.728725425486;13929.442822287096;14064.859351406509;14067.929660351683;14122;13576.932115339434;13826.654333641663;13959.720805583907;13964.930175349109;13979.650508737286;2705.9729402705916;2749.5188342040165;2750.518659234636;2760.682521510032;2765.3777899972547;2700.162949485656;2746.3408781892404;2746.4644394343113;2755.61421401004;2756.9586456203174;2703.189043287016;2740.0272903119403;2758.9862050689817;2760.7101254368304;2762.8066035377533;2704.648395708555;2713.579395193748;2718.796090293226;2719.809613327068;2725.9591106133425;2690.744379283965;2701.3921867579834;2752.078053851962;2755.9448811023867;2757.4485102979484;13493.527726529623;13542.796858047137;13553.254570998592;13559.45762169512;13588.456461741518;7422.7030918763185;7612.3148916597775;7663.195364486704;7700.614969251515;7710.691572337156;4451.821927122911;4561.950751327214;4729.69256998294;4803.327534145187;4852.660313778037;1228.1587112827651;1229.434460148324;1381.9723605527934;1391.2278685329638;1408.7041721238552;18670.81329186704;19369.934653594046;20377.694334584994;20538;20557.69163462549;5951.761929522857;6193.628382297076;6494.253160886464;6823.69293381799;6834.179898412222;12257.14200005991;14457.493987710379;14488.637784055272;14493.710125797425;14494.492692755703;7172.103487064117;7475.289847464483;7504.737334193386;7515.661795219228;7548.131964824006;6659.766908158288;6799.082123913307;6860.416864566468;6862.279460656659;6906.205786334535;4466.531014243523;5002.824901128443;5074.873128171751;5076.949230507722;5096.464871188546;1273.4715093236202;1466.4061055272632;1470.1987416857837;1470.7279153356662;1488.9404423822925;814.9975530098012;830.1325115254574;835.5738573327525;845.1632883445345;863.0592654007162;6282.403171698681;6980.825479363057;7268.618962397233;7321.048263725682;7390.7413240536325;594.8007417515095;682.6586706646676;728.8469421421455;729.2051663686607;731.7365748330596;4085.5914408558906;4146.295129827876;4325.394444777734;4361.738295702268;4601.838935637304;17719.73420398695;18235;18287.268509259746;18430.447086587425;18541;17721.7341739874;18523.72214416785;18634.534136646693;18665.626687466174;18747.343842965704;8262.628181731836;9382.812343753223;9454.621815127319;9528.95235523818;9609.08713672214;6627.668616569056;7565.243475652391;7762.650680719268;7877.724279650185;8188.344932405513;16241.431549895697;16745.581360466087;16771.32914683399;16813.831861681225;17049.82950170532;15950.441734539236;16346.264418101451;17180.570485737957;17332.220050097523;17487.912560437115;10732.946335268274;10989.780204396027;10994.725131871768;11001.339919604849;11008;11781;12152.027837773134;12419.37903104823;12439.191452555555;12518.248905065726;9988.60045598197;9999.150072243941;10241.539130738985;10282.023207795248;10445.791084178425;9772.804543909224;9993.650222242186;10079.798404032024;10289;10328.948355258175;8940.955295223483;9197.908020919705;9416.717498475056;9417.482038487675;9453.24374050088;6668.799936001928;6801.68742627509;6866.896996545156;6931.994860745264;7030.191527974348;6777.525573209828;6850.760223392157;7021.297870212938;7095.82260443493;7156.212816590275 13:12:52 INFO - PID 2007 | |4;prototype.html;378.30580884077716;421.33008516458824;447.78058751211904;457.2752187782367;462.6853739457169;5989;6438.484921206304;6557.95072788354;6558.311377305386;6590.340965903413;5200.219967004951;6093.299270583884;6270;6272.153259309992;6376.617402955826;4932.8520144395725;4933.629977751665;4936.259561065842;4939.25911113334;4959.900062460962;4323.027318853265;4926.482719314474;4944.9258261126115;4959.115536096278;4960.60315174787;4727.929081063769;4908.779104940269;4920.089783390066;4948.653594248404;4956.330895329121;4950.628702847301;4951.811565224339;4963.379577552806;4965.875853103666;4977.651564390494;3662.7802331859907;3967.266055779661;4450.4881938577155;4475.127350166719;4499.370088187657;5780.393058728815;6304.744297599148;6328.620282783002;6330.283938465826;6356.427921487042;5946.672932988684;5957.463828255478;5964.850878728023;5982.222311099573;5988.7604495820115;6920.826979325507;6949.44404447643;6975.162980442379;6998;6999.650017499104;4427.734335939854;5162.277281180638;5181.1969144782615;5190.429052804188;5191.2472691459525;6325.6083661594375;6863.725450981955;7285.744998925013;7292.0520332356455;7323.414126869838;3921.705872059607;4155.771432571177;4824.396950381202;4884.633652476078;5311.548518375904;4376.737395756265;4761.95238047622;4893.828715994994;4941.604671626261;4942.75286235694;5009.849704508871;5596.916046259309;5614.410486898899;5620.803271885547;5643.661380317194;6154.353792851686;6269.8746025079245;6520.413162815369;6852.280510546321;6863.862722745517;1536.7694845773224;1542.9845701543065;1592.5540848562189;1679.546522438959;1700.7108791505473;944.3672739264572;979.3780949097375;1099.274478843959;1139.8404223408563;1139.9544018239178;6342.8414289642205;6379.904301435482;6395.968020159869;6427.228732552124;6517.837054073591;6093.69531523422;6773.796786096425;7058.941108862322;7113.407729096876;7113.573185608777;5670.971645141831;6410.551261411656;6415.518836087219;6457.192850893638;6467.482601391877;3120.438321102178;3297.268933809769;3314.436545787222;3730.1793605406765;3732.8320225589914;7280.344768970818;7284.526505777214;7291.380232680175;7309.342159205697;7317.670704818296;6111.877762444815;6490.513211509156;7204.783856484314;7292.34368906801;7293.343599076109;5657.43425657431;5765.711714414262;7264.200937896827;7274.599897005715;7280.96359518199;6259.467945224706;6820.454363650995;7064.858702826017;7111.395531379684;7130.393916517154;6327.272363678236;6746.61470044475;6871.3128687132885;6888.242293347627;6889.517733758589;6768.830779230559;7243.167035790741;7280.781576552712;7288.33975598892;7319.158296795724;4815;4815.422149342101;4827.565519103297;4853.6359773017875;4855.95144048555;6004.789832355847;6463.418292353711;7089.255628158969;7103;7108.147022357351;6068.544859135494;6217.052171870804;6564.606123632598;6836.863262734817;6840.384365407137;5717.999350113797;5783.768649253982;5917.319508256433;6706.966465167643;6864.5881247125335;6232.657203853651;6431.1317972073175;6685.197776266879;6697.5888680076105;6706.731730730716;6003.429674180946;6082.452579267887;6864.588124712333;6894.655267236518;6915.930840691529;5688.914666280092;6686.863233250262;6690.732370705312;6706.463482921257;6714.462842972648 13:12:52 INFO - PID 2007 | |5;yui.html;821.347029111856;834.6577903059747;835.9164083591642;865.7186414415316;866.5797088412123;16983.83016169838;17002;17238.482845514656;17255.654886902255;17507.73738393925;12960.54638087667;13359.465621375157;13480.66298342542;13558.796618050712;13559.457621695145;652.2564276724538;948;950.7100334398006;957;958.8993155718654;999.385377992533;1005.2460654509118;1006.3106771861262;1007.4761124215404;1009.2430676992255;1045.5086109528547;1046.7958748044134;1048.5019615682527;1052.1214785653988;1055.4828134214217;951.4909523405014;997.3018886779283;1043.890391508892;1046.9476526173698;1062.1131355318319;768.3584207187006;768.6233745464682;769.0348612491343;770.76106407014;772.3357912195507;17389.304427822874;17394.21726022332;17554.47336579892;17588.12059397038;17598.91200544002;15441.30514126867;16455.177241138015;16562;17670.558236044075;17714.822851771576;14443.277836108153;14448.566543003726;14942.551723448314;15009.249537523081;15070;3640.1445660269815;3643.744937854351;3649.6532829381167;3653.0502069461863;3655.3603119454256;92887;94360;131828.68171318356;132687.6731232685;135170;10264.076233139054;11164.776704466027;11170.888291116984;11442.14183936208;12121.878781212075;24996.875015624806;25363.619545706828;25797.968081276915;26741.465170696865;27001;8131.105578386373;8143.267105960492;8161;8162.347012239125;8225.971753530808;3878.786666733357;4045.4538637284177;4051.169510250392;4063.9796801015805;4091.5090189176894;4543.1822271990695;4691.366665500113;4693.037927224911;4695.859124226278;4700.130475861976;995.9950200248952;1014.6803756816579;1032.3341444768082;1033.3231733214757;1037.984430233547;635.3392471829338;657.4970147836813;667.6795138333628;676.3067855448205;681.1859827506082;11177.105831533458;12394.75210495803;12435.75128497425;12485.188462749893;12593.244405335709;611.4741322462678;644.9169183537108;651.791426743447;664.8437617159964;678.8608335291253;5110.565601923878;5344.706041167773;5451.182322651554;5502.889942201214;5796.62321949072;148134;149862;150509;150792;156447.43552564556;98474.50762746141;123133;126303;129478;133078.33843323274;92682;106800.46599766951;110983;111239.88760112296;112648.43675781568;10919.836202457129;11364.318140911571;11540.24988375753;11668.708282292671;11797.469113890067;41384.793076034424;50411;51622.48377516177;52010.21984670157;52599.73700131628;53581.2065252361;67164;68845.3115468839;72237;72540.27459725334;12350.62948111558;12529.18560293578;12540.561080362144;12611.74776504483;12728.490860365482;13345.332733363099;16017.919910400373;16202.837971620133;16244.187790610185;16438.424655137012;14475.059121157092;14702.485413010494;14809.85190148128;14842.925785371435;15028.17345046033;14511.78232326537;14671.339789709275;14681.48614798477;14918.328675209425;15067;13970.930145349208;14925.626859328604;15014.549563513112;15017.249137543298;16477.093759842843;11614.825777613512;12394.81407778902;12412.131150819356;12714.87285127137;13049.473884064875;11784.175107742376;12943.482260709467;13464.730705385642;13511.932440337736;13977.440902364198 13:12:52 INFO - PID 2007 | __end_tp_report 13:12:52 INFO - PID 2007 | __start_cc_report 13:12:52 INFO - PID 2007 | _x_x_mozilla_cycle_collect,472 13:12:52 INFO - PID 2007 | __end_cc_report 13:12:52 INFO - PID 2007 | __startTimestamp1497557571984__endTimestamp 13:12:52 INFO - PID 2007 | 13:12:52 INFO - PID 2007 | ------- Summary: start ------- 13:12:52 INFO - PID 2007 | Number of tests: 6 13:12:52 INFO - PID 2007 | 13:12:52 INFO - PID 2007 | [#0] dojo.html Cycles:165 Average:6194.71 Median:6466.94 stddev:754.05 (11.7%) stddev-sans-first:725.80 13:12:52 INFO - PID 2007 | Values: 3485.6 3547.6 3599.2 3616.2 3625.5 5228.6 6104.0 6150.1 6234.1 6238.4 6187.8 6197.5 6248.4 6272.2 6286.9 5521.0 5526.5 5532.6 5574.4 5654.0 5633.0 5640.3 5648.7 5651.8 5662.4 5380.6 5539.6 5542.4 5553.0 5595.2 5430.8 5473.6 5496.5 5513.2 5515.5 5429.1 5482.1 5504.2 5561.2 5563.2 6158.7 6273.1 6282.2 6290.1 6298.3 6825.5 6837.7 6845.2 6854.7 6857.8 6680.3 6789.3 6793.3 6933.7 6954.4 5370.3 6325.7 6360.4 6383.1 6396.0 6874.3 7044.1 7050.6 7052.4 7060.1 6510.0 6568.6 6660.4 6701.3 6722.8 6733.4 6743.2 6745.5 6755.0 6779.1 6096.5 6663.8 6733.6 6790.3 6806.6 5301.7 5756.9 6754.9 6762.8 6783.8 4523.0 4652.8 5264.6 5341.0 5440.5 4795.3 4802.4 4850.7 4914.0 4933.1 5032.9 5766.5 6318.4 6354.7 6395.9 6789.6 6890.8 6892.8 6924.0 6944.9 6198.8 6297.7 6329.3 6351.8 6393.9 5823.1 6081.0 6084.1 6086.0 6093.8 6813.1 6878.9 6895.8 7009.6 7044.7 6640.8 6727.7 6764.5 6773.2 6802.2 5579.9 6917.3 6922.8 6950.1 6961.6 6637.0 6644.4 6647.0 6661.1 6667.3 6663.4 6779.4 6787.7 6789.0 6825.1 6513.8 6586.7 6588.9 6598.9 6603.1 6586.8 6596.0 6597.2 6597.9 6604.0 6163.6 6443.3 6466.9 6480.0 6484.9 6428.7 6512.1 6514.3 6539.3 6575.0 6510.3 6516.9 6533.8 6548.8 6577.2 13:12:52 INFO - PID 2007 | 13:12:52 INFO - PID 2007 | [#1] ext.html Cycles:180 Average:43769.70 Median:32683.76 stddev:79385.53 (242.9%) stddev-sans-first:79542.41 13:12:52 INFO - PID 2007 | Values: 714.8 766.0 820.2 838.9 1217.6 32590.7 36735.0 36744.0 36779.6 36870.1 37250.0 37539.6 37540.6 37590.4 37695.0 6711.2 7797.8 7799.9 7801.1 7805.1 7274.9 7751.0 7766.7 7936.9 8138.1 8146.1 8222.3 8224.3 8224.9 8228.8 7261.2 8082.5 8139.1 8140.0 8204.9 7939.0 7949.2 7954.8 7989.0 7996.2 34005.8 34054.7 34091.7 34116.5 34202.0 3505.6 3510.6 3542.8 3547.6 3549.9 28379.9 28549.0 28677.7 28693.7 28731.4 3055.8 3062.8 3072.6 3089.2 3105.2 247655.5 253550.0 253643.0 264022.0 305524.0 44671.3 45057.6 45175.3 45205.0 45254.0 39390.8 46963.3 46975.5 47099.3 47162.0 12992.1 13424.9 14283.9 14284.0 14288.3 11665.4 11709.0 11714.0 11722.4 11744.4 11467.4 12084.9 12520.6 12846.5 12970.4 5790.2 5791.7 5793.8 5794.0 5864.5 995.6 1000.9 1006.2 1059.7 1061.9 19646.7 22014.4 22185.8 22506.3 22521.0 1085.2 1099.5 1145.2 1159.4 1161.3 11624.7 11665.8 11682.3 12044.3 15288.0 349592.0 350596.0 351050.0 351399.0 352557.0 52169.0 52535.0 52608.5 52692.0 52764.7 1466.3 1468.2 1480.6 1481.3 1487.4 26949.6 27552.6 28219.9 28658.9 28672.9 35607.8 35715.1 35787.3 35861.6 35906.0 281514.0 281525.0 281553.0 282143.0 282431.0 27731.2 28140.9 28187.9 32924.3 33373.2 32776.8 33743.0 37981.0 38247.0 40059.8 27114.9 27518.3 27909.4 28941.1 28994.4 27382.7 28444.1 28808.6 28906.6 29239.8 26044.0 32188.8 32908.7 33848.0 33962.5 25046.7 27084.5 27469.3 29283.9 30634.7 19346.4 24126.9 24169.6 24303.0 24481.0 13:12:52 INFO - PID 2007 | 13:12:52 INFO - PID 2007 | [#2] jquery.html Cycles:180 Average:37656.77 Median:2165.18 stddev:91214.29 (4212.8%) stddev-sans-first:91427.50 13:12:52 INFO - PID 2007 | Values: 498.5 499.5 500.4 509.6 525.4 21364.9 21943.5 21949.1 22109.6 22132.4 24286.4 24297.4 24320.5 24520.9 24582.4 6344.7 6521.1 6558.6 6581.8 6660.6 6331.4 6432.3 6520.1 6550.6 6554.8 6012.8 6386.0 6404.8 6406.5 6420.8 6388.7 6397.3 6404.4 6409.6 6417.0 5866.4 5879.3 5882.9 5893.5 5896.4 28139.3 28514.7 29227.0 29514.4 29536.4 1928.1 1976.8 2260.6 2279.4 2304.0 18549.3 19119.8 19138.2 19163.9 19187.5 2162.5 2167.9 2216.2 2233.4 2234.4 169283.2 172881.0 173417.0 180934.0 181306.2 12458.3 13580.5 14171.5 14725.9 14870.9 19382.6 19479.0 19510.0 19568.0 19583.3 9767.3 9859.1 9901.3 9942.6 9959.3 9229.4 9246.6 9256.9 9260.6 9315.3 8017.5 8632.7 8893.6 9034.7 9087.3 2535.6 2539.6 2545.8 2569.5 2571.3 976.4 991.1 1010.8 1025.5 1072.5 21006.6 21072.7 21094.4 21538.5 22712.2 1040.9 1048.7 1058.2 1067.6 1073.1 10676.5 11077.8 11153.8 11204.3 11465.7 504114.0 522494.0 523364.0 525879.0 568500.0 117479.0 120446.0 142712.0 143431.0 143481.0 1198.7 1212.8 1233.6 1242.9 1245.5 19133.4 19492.1 20110.5 20150.1 20503.4 68692.0 69217.0 70905.3 71915.0 72227.0 93208.0 93675.5 94993.5 97563.5 98488.5 11604.6 11642.6 11709.2 11812.1 11860.6 18400.7 18537.6 18553.9 18837.4 18873.0 14330.4 15306.0 15324.8 15341.3 15451.0 11791.3 13139.3 13411.8 13469.2 14363.1 15516.7 15811.2 15821.7 15870.8 15889.8 11472.3 11598.4 11628.1 11641.7 11654.0 13930.0 15084.8 15151.9 15156.1 15221.3 13:12:52 INFO - PID 2007 | 13:12:52 INFO - PID 2007 | [#3] mootools.html Cycles:180 Average:8271.07 Median:2751.30 stddev:5551.59 (201.8%) stddev-sans-first:5542.10 13:12:52 INFO - PID 2007 | Values: 1251.8 1430.9 1449.5 1457.0 1472.3 13563.7 13929.4 14064.9 14067.9 14122.0 13576.9 13826.7 13959.7 13964.9 13979.7 2706.0 2749.5 2750.5 2760.7 2765.4 2700.2 2746.3 2746.5 2755.6 2757.0 2703.2 2740.0 2759.0 2760.7 2762.8 2704.6 2713.6 2718.8 2719.8 2726.0 2690.7 2701.4 2752.1 2755.9 2757.4 13493.5 13542.8 13553.3 13559.5 13588.5 7422.7 7612.3 7663.2 7700.6 7710.7 4451.8 4562.0 4729.7 4803.3 4852.7 1228.2 1229.4 1382.0 1391.2 1408.7 18670.8 19369.9 20377.7 20538.0 20557.7 5951.8 6193.6 6494.3 6823.7 6834.2 12257.1 14457.5 14488.6 14493.7 14494.5 7172.1 7475.3 7504.7 7515.7 7548.1 6659.8 6799.1 6860.4 6862.3 6906.2 4466.5 5002.8 5074.9 5076.9 5096.5 1273.5 1466.4 1470.2 1470.7 1488.9 815.0 830.1 835.6 845.2 863.1 6282.4 6980.8 7268.6 7321.0 7390.7 594.8 682.7 728.8 729.2 731.7 4085.6 4146.3 4325.4 4361.7 4601.8 17719.7 18235.0 18287.3 18430.4 18541.0 17721.7 18523.7 18634.5 18665.6 18747.3 8262.6 9382.8 9454.6 9529.0 9609.1 6627.7 7565.2 7762.7 7877.7 8188.3 16241.4 16745.6 16771.3 16813.8 17049.8 15950.4 16346.3 17180.6 17332.2 17487.9 10732.9 10989.8 10994.7 11001.3 11008.0 11781.0 12152.0 12419.4 12439.2 12518.2 9988.6 9999.2 10241.5 10282.0 10445.8 9772.8 9993.7 10079.8 10289.0 10328.9 8941.0 9197.9 9416.7 9417.5 9453.2 6668.8 6801.7 6866.9 6932.0 7030.2 6777.5 6850.8 7021.3 7095.8 7156.2 13:12:52 INFO - PID 2007 | 13:12:52 INFO - PID 2007 | [#4] prototype.html Cycles:180 Average:5570.10 Median:6185.70 stddev:1693.45 (27.4%) stddev-sans-first:1652.76 13:12:52 INFO - PID 2007 | Values: 378.3 421.3 447.8 457.3 462.7 5989.0 6438.5 6558.0 6558.3 6590.3 5200.2 6093.3 6270.0 6272.2 6376.6 4932.9 4933.6 4936.3 4939.3 4959.9 4323.0 4926.5 4944.9 4959.1 4960.6 4727.9 4908.8 4920.1 4948.7 4956.3 4950.6 4951.8 4963.4 4965.9 4977.7 3662.8 3967.3 4450.5 4475.1 4499.4 5780.4 6304.7 6328.6 6330.3 6356.4 5946.7 5957.5 5964.9 5982.2 5988.8 6920.8 6949.4 6975.2 6998.0 6999.7 4427.7 5162.3 5181.2 5190.4 5191.2 6325.6 6863.7 7285.7 7292.1 7323.4 3921.7 4155.8 4824.4 4884.6 5311.5 4376.7 4762.0 4893.8 4941.6 4942.8 5009.8 5596.9 5614.4 5620.8 5643.7 6154.4 6269.9 6520.4 6852.3 6863.9 1536.8 1543.0 1592.6 1679.5 1700.7 944.4 979.4 1099.3 1139.8 1140.0 6342.8 6379.9 6396.0 6427.2 6517.8 6093.7 6773.8 7058.9 7113.4 7113.6 5671.0 6410.6 6415.5 6457.2 6467.5 3120.4 3297.3 3314.4 3730.2 3732.8 7280.3 7284.5 7291.4 7309.3 7317.7 6111.9 6490.5 7204.8 7292.3 7293.3 5657.4 5765.7 7264.2 7274.6 7281.0 6259.5 6820.5 7064.9 7111.4 7130.4 6327.3 6746.6 6871.3 6888.2 6889.5 6768.8 7243.2 7280.8 7288.3 7319.2 4815.0 4815.4 4827.6 4853.6 4856.0 6004.8 6463.4 7089.3 7103.0 7108.1 6068.5 6217.1 6564.6 6836.9 6840.4 5718.0 5783.8 5917.3 6707.0 6864.6 6232.7 6431.1 6685.2 6697.6 6706.7 6003.4 6082.5 6864.6 6894.7 6915.9 5688.9 6686.9 6690.7 6706.5 6714.5 13:12:52 INFO - PID 2007 | 13:12:52 INFO - PID 2007 | [#5] yui.html Cycles:180 Average:24632.47 Median:16341.31 stddev:38336.70 (234.6%) stddev-sans-first:38402.55 13:12:52 INFO - PID 2007 | Values: 821.3 834.7 835.9 865.7 866.6 16983.8 17002.0 17238.5 17255.7 17507.7 12960.5 13359.5 13480.7 13558.8 13559.5 652.3 948.0 950.7 957.0 958.9 999.4 1005.2 1006.3 1007.5 1009.2 1045.5 1046.8 1048.5 1052.1 1055.5 951.5 997.3 1043.9 1046.9 1062.1 768.4 768.6 769.0 770.8 772.3 17389.3 17394.2 17554.5 17588.1 17598.9 15441.3 16455.2 16562.0 17670.6 17714.8 14443.3 14448.6 14942.6 15009.2 15070.0 3640.1 3643.7 3649.7 3653.1 3655.4 92887.0 94360.0 131828.7 132687.7 135170.0 10264.1 11164.8 11170.9 11442.1 12121.9 24996.9 25363.6 25798.0 26741.5 27001.0 8131.1 8143.3 8161.0 8162.3 8226.0 3878.8 4045.5 4051.2 4064.0 4091.5 4543.2 4691.4 4693.0 4695.9 4700.1 996.0 1014.7 1032.3 1033.3 1038.0 635.3 657.5 667.7 676.3 681.2 11177.1 12394.8 12435.8 12485.2 12593.2 611.5 644.9 651.8 664.8 678.9 5110.6 5344.7 5451.2 5502.9 5796.6 148134.0 149862.0 150509.0 150792.0 156447.4 98474.5 123133.0 126303.0 129478.0 133078.3 92682.0 106800.5 110983.0 111239.9 112648.4 10919.8 11364.3 11540.2 11668.7 11797.5 41384.8 50411.0 51622.5 52010.2 52599.7 53581.2 67164.0 68845.3 72237.0 72540.3 12350.6 12529.2 12540.6 12611.7 12728.5 13345.3 16017.9 16202.8 16244.2 16438.4 14475.1 14702.5 14809.9 14842.9 15028.2 14511.8 14671.3 14681.5 14918.3 15067.0 13970.9 14925.6 15014.5 15017.2 16477.1 11614.8 12394.8 12412.1 12714.9 13049.5 11784.2 12943.5 13464.7 13511.9 13977.4 13:12:52 INFO - PID 2007 | -------- Summary: end -------- 13:12:52 INFO - PID 2007 | 13:12:52 INFO - TEST-INFO | 2007: exit 0 13:12:52 INFO - TEST-OK | dromaeo_css | took 1144249ms 13:12:52 INFO - TEST-START | kraken 13:12:52 INFO - operating with platform_type : mac_ 13:12:52 INFO - Initialising browser for kraken test... 13:12:52 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49494/getInfo.html -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpFoyLwa/profile 13:12:52 INFO - TEST-INFO | started process 2014 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49494/getInfo.html -foreground) 13:13:26 INFO - TEST-INFO | 2014: killed by SIGTERM 13:13:26 INFO - Browser initialized. 13:13:26 INFO - Running cycle 1/1 for kraken test... 13:13:26 INFO - TEST-INFO | started process 2018 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpFoyLwa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:13:28 INFO - PID 2018 | 2017-06-15 13:13:28.217 plugin-container[2019:17852] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 13:13:28 INFO - PID 2018 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:13:28 INFO - PID 2018 | 2017-06-15 13:13:28.217 plugin-container[2019:17852] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 13:13:28 INFO - PID 2018 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:13:28 INFO - PID 2018 | 2017-06-15 13:13:28.218 plugin-container[2019:17852] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:13:29 INFO - PID 2018 | 2017-06-15 13:13:29.007 plugin-container[2020:17897] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a4f, name = 'com.apple.tsm.portname' 13:13:29 INFO - PID 2018 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:13:29 INFO - PID 2018 | 2017-06-15 13:13:29.008 plugin-container[2020:17897] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 13:13:29 INFO - PID 2018 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:13:29 INFO - PID 2018 | 2017-06-15 13:13:29.008 plugin-container[2020:17897] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:13:38 INFO - PID 2018 | RSS: Main: 254611456 13:13:38 INFO - PID 2018 | 13:13:41 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49494/tests/kraken/driver.html?testName=audio-beat-detection) 13:13:42 INFO - PID 2018 | RSS: Main: 266715136 13:13:42 INFO - PID 2018 | 13:13:44 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49494/tests/kraken/driver.html?testName=audio-dft) 13:13:45 INFO - PID 2018 | RSS: Main: 278454272 13:13:45 INFO - PID 2018 | 13:13:48 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49494/tests/kraken/driver.html?testName=audio-fft) 13:13:49 INFO - PID 2018 | RSS: Main: 281755648 13:13:49 INFO - PID 2018 | 13:13:51 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49494/tests/kraken/driver.html?testName=audio-oscillator) 13:13:51 INFO - PID 2018 | RSS: Main: 282533888 13:13:51 INFO - PID 2018 | 13:13:53 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49494/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:13:54 INFO - PID 2018 | RSS: Main: 283136000 13:13:54 INFO - PID 2018 | 13:13:57 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49494/tests/kraken/driver.html?testName=imaging-darkroom) 13:13:57 INFO - PID 2018 | RSS: Main: 287981568 13:13:57 INFO - PID 2018 | 13:14:00 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49494/tests/kraken/driver.html?testName=imaging-desaturate) 13:14:01 INFO - PID 2018 | RSS: Main: 289439744 13:14:01 INFO - PID 2018 | 13:14:04 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49494/tests/kraken/driver.html?testName=json-parse-financial) 13:14:05 INFO - PID 2018 | RSS: Main: 289456128 13:14:05 INFO - PID 2018 | 13:14:06 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49494/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:14:07 INFO - PID 2018 | RSS: Main: 289189888 13:14:07 INFO - PID 2018 | 13:14:09 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49494/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:14:09 INFO - PID 2018 | RSS: Main: 288706560 13:14:09 INFO - PID 2018 | 13:14:11 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49494/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:14:12 INFO - PID 2018 | RSS: Main: 288583680 13:14:12 INFO - PID 2018 | 13:14:15 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49494/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:14:16 INFO - PID 2018 | RSS: Main: 288935936 13:14:16 INFO - PID 2018 | 13:14:19 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49494/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:14:19 INFO - PID 2018 | RSS: Main: 289169408 13:14:19 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | Cycle 1(1): loaded http://localhost:49494/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49494/tests/kraken/driver.html?testName=ai-astar) 13:14:21 INFO - PID 2018 | RSS: Main: 290918400 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | __start_tp_report 13:14:21 INFO - PID 2018 | _x_x_mozilla_page_load 13:14:21 INFO - PID 2018 | _x_x_mozilla_page_load_details 13:14:21 INFO - PID 2018 | |i|pagename|runs| 13:14:21 INFO - PID 2018 | |0;ai-astar;103;115;119;98;115;156;160;167;115;117 13:14:21 INFO - PID 2018 | |1;audio-beat-detection;134;112;150;136;108;126;128;112;152;112 13:14:21 INFO - PID 2018 | |2;audio-dft;202;205;200;219;219;236;202;202;201;198 13:14:21 INFO - PID 2018 | |3;audio-fft;74;81;69;129;57;59;58;64;135;71 13:14:21 INFO - PID 2018 | |4;audio-oscillator;69;69;136;98;110;86;105;68;70;111 13:14:21 INFO - PID 2018 | |5;imaging-gaussian-blur;90;90;89;90;92;89;89;90;97;88 13:14:21 INFO - PID 2018 | |6;imaging-darkroom;97;98;98;96;98;111;149;108;97;99 13:14:21 INFO - PID 2018 | |7;imaging-desaturate;78;133;147;128;93;109;120;92;93;93 13:14:21 INFO - PID 2018 | |8;json-parse-financial;44;43;69;73;72;74;73;75;54;52 13:14:21 INFO - PID 2018 | |9;json-stringify-tinderbox;56;58;58;61;84;70;64;59;56;72 13:14:21 INFO - PID 2018 | |10;stanford-crypto-aes;78;83;83;80;82;82;80;81;83;85 13:14:21 INFO - PID 2018 | |11;stanford-crypto-ccm;131;140;136;140;136;141;154;153;138;182 13:14:21 INFO - PID 2018 | |12;stanford-crypto-pbkdf2;214;224;182;226;221;181;183;182;192;212 13:14:21 INFO - PID 2018 | |13;stanford-crypto-sha256-iterative;66;66;64;64;67;63;64;64;66;64 13:14:21 INFO - PID 2018 | __end_tp_report 13:14:21 INFO - PID 2018 | __start_cc_report 13:14:21 INFO - PID 2018 | _x_x_mozilla_cycle_collect,1172 13:14:21 INFO - PID 2018 | __end_cc_report 13:14:21 INFO - PID 2018 | __startTimestamp1497557661814__endTimestamp 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | ------- Summary: start ------- 13:14:21 INFO - PID 2018 | Number of tests: 14 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#0] ai-astar Cycles:10 Average:126.50 Median:137.50 stddev:24.81 (18.0%) stddev-sans-first:24.82 13:14:21 INFO - PID 2018 | Values: 103.0 115.0 119.0 98.0 115.0 156.0 160.0 167.0 115.0 117.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#1] audio-beat-detection Cycles:10 Average:127.00 Median:131.00 stddev:16.06 (12.3%) stddev-sans-first:16.84 13:14:21 INFO - PID 2018 | Values: 134.0 112.0 150.0 136.0 108.0 126.0 128.0 112.0 152.0 112.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#2] audio-dft Cycles:10 Average:208.40 Median:203.50 stddev:12.27 (6.0%) stddev-sans-first:12.79 13:14:21 INFO - PID 2018 | Values: 202.0 205.0 200.0 219.0 219.0 236.0 202.0 202.0 201.0 198.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#3] audio-fft Cycles:10 Average:79.70 Median:66.50 stddev:28.62 (43.0%) stddev-sans-first:30.29 13:14:21 INFO - PID 2018 | Values: 74.0 81.0 69.0 129.0 57.0 59.0 58.0 64.0 135.0 71.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#4] audio-oscillator Cycles:10 Average:92.20 Median:69.00 stddev:23.52 (34.1%) stddev-sans-first:23.40 13:14:21 INFO - PID 2018 | Values: 69.0 69.0 136.0 98.0 110.0 86.0 105.0 68.0 70.0 111.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#5] imaging-gaussian-blur Cycles:10 Average:90.40 Median:90.00 stddev:2.55 (2.8%) stddev-sans-first:2.70 13:14:21 INFO - PID 2018 | Values: 90.0 90.0 89.0 90.0 92.0 89.0 89.0 90.0 97.0 88.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#6] imaging-darkroom Cycles:10 Average:105.10 Median:97.50 stddev:16.24 (16.7%) stddev-sans-first:16.96 13:14:21 INFO - PID 2018 | Values: 97.0 98.0 98.0 96.0 98.0 111.0 149.0 108.0 97.0 99.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:85.00 stddev:22.41 (26.4%) stddev-sans-first:20.85 13:14:21 INFO - PID 2018 | Values: 78.0 133.0 147.0 128.0 93.0 109.0 120.0 92.0 93.0 93.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:72.50 stddev:13.10 (18.1%) stddev-sans-first:11.98 13:14:21 INFO - PID 2018 | Values: 44.0 43.0 69.0 73.0 72.0 74.0 73.0 75.0 54.0 52.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#9] json-stringify-tinderbox Cycles:10 Average:63.80 Median:62.50 stddev:9.03 (14.4%) stddev-sans-first:9.12 13:14:21 INFO - PID 2018 | Values: 56.0 58.0 58.0 61.0 84.0 70.0 64.0 59.0 56.0 72.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#10] stanford-crypto-aes Cycles:10 Average:81.70 Median:82.50 stddev:2.00 (2.4%) stddev-sans-first:1.62 13:14:21 INFO - PID 2018 | Values: 78.0 83.0 83.0 80.0 82.0 82.0 80.0 81.0 83.0 85.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:140.50 stddev:14.86 (10.6%) stddev-sans-first:14.86 13:14:21 INFO - PID 2018 | Values: 131.0 140.0 136.0 140.0 136.0 141.0 154.0 153.0 138.0 182.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.70 Median:213.00 stddev:19.34 (9.1%) stddev-sans-first:19.99 13:14:21 INFO - PID 2018 | Values: 214.0 224.0 182.0 226.0 221.0 181.0 183.0 182.0 192.0 212.0 13:14:21 INFO - PID 2018 | 13:14:21 INFO - PID 2018 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.80 Median:65.00 stddev:1.32 (2.0%) stddev-sans-first:1.32 13:14:21 INFO - PID 2018 | Values: 66.0 66.0 64.0 64.0 67.0 63.0 64.0 64.0 66.0 64.0 13:14:21 INFO - PID 2018 | -------- Summary: end -------- 13:14:21 INFO - PID 2018 | 13:14:22 INFO - TEST-INFO | 2018: exit 0 13:14:22 INFO - TEST-OK | kraken | took 90073ms 13:14:22 INFO - SUITE-END | took 1234s 13:14:23 INFO - Completed test suite (00:20:34) 13:14:23 INFO - javascript benchmark 13:14:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8224.075095929213, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3485.5991560970497, 3547.5742910850704, 3599.208174201675, 3616.150204701896, 3625.474306225598, 5228.5555727763185, 6103.969480152598, 6150.09347102398, 6234.0648902664625, 6238.4385405313515, 6187.783427580036, 6197.5041996640275, 6248.37516248375, 6272.184615999925, 6286.874262514747, 5521.0, 5526.530244929186, 5532.5573954083675, 5574.386817450078, 5654.038813401711, 5632.971835140818, 5640.323161220639, 5648.717564121798, 5651.8304450866535, 5662.37713851476, 5380.569554435636, 5539.556835453154, 5542.39033706292, 5552.972700050504, 5595.160725891149, 5430.83707488776, 5473.644213126174, 5496.477834605706, 5513.173024046425, 5515.531179849718, 5429.104197807357, 5482.095454250044, 5504.2018907258625, 5561.221428999943, 5563.248961390201, 6158.6612736299485, 6273.130921640842, 6282.183316168915, 6290.087937249074, 6298.307186209513, 6825.453963682893, 6837.726490940406, 6845.247022827485, 6854.657267136673, 6857.759978400782, 6680.331966803281, 6789.321067893221, 6793.286704896013, 6933.68798404068, 6954.40887524561, 5370.301860758076, 6325.746970121236, 6360.363963603649, 6383.138276332728, 6396.0, 6874.2782007889455, 7044.084269044992, 7050.647467626598, 7052.435805135577, 7060.117485314336, 6509.967450162719, 6568.638724870178, 6660.400563949268, 6701.296363881722, 6722.798316050526, 6733.3939945405145, 6743.15710536183, 6745.460363170934, 6754.966225168941, 6779.0509328694025, 6096.542759293071, 6663.800085997428, 6733.562318449285, 6790.253072162058, 6806.625635589991, 5301.655392399482, 5756.9061878243, 6754.864902701918, 6762.83092922671, 6783.830404239933, 4522.977385113053, 4652.813887444532, 5264.6314757966575, 5340.95851308991, 5440.537554307928, 4795.256735206049, 4802.447718512416, 4850.733209673502, 4913.992631510532, 4933.136701077369, 5032.917922646649, 5766.538676905838, 6318.399752023551, 6354.745810167634, 6395.904061439082, 6789.558678685869, 6890.793276201722, 6892.827679308058, 6924.030635711005, 6944.861102778017, 6198.783042593487, 6297.685115744194, 6329.3354197809485, 6351.809445716636, 6393.9360606394275, 5823.068309070529, 6080.969595152084, 6084.087386892002, 6085.9695701521205, 6093.786717464867, 6813.080234168324, 6878.931210687929, 6895.75864844728, 7009.614471204132, 7044.718211271492, 6640.800775976535, 6727.730890764315, 6764.458843292623, 6773.221079575912, 6802.217744959393, 5579.888402231852, 6917.27368626287, 6922.7923162305215, 6950.096487456598, 6961.61711105894, 6637.037629543785, 6644.368784965421, 6647.036179754005, 6661.067450557023, 6667.2666006738245, 6663.400293973566, 6779.35596118368, 6787.694553744995, 6788.981652752127, 6825.1127353443735, 6513.804585862432, 6586.703598338182, 6588.868222635616, 6598.868022639616, 6603.108580341785, 6586.769463068769, 6595.967020164869, 6597.208334999831, 6597.868042639217, 6603.966980165068, 6163.630182189083, 6443.291237963726, 6466.935330646634, 6479.967600161969, 6484.870302593828, 6428.742850285937, 6512.120863683342, 6514.315996820265, 6539.346065393423, 6574.967125164344, 6510.283868774526, 6516.869662606816, 6533.803985880431, 6548.8362790930605, 6577.2436169841085], "value": 6145.2177623460075, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.832014476598, 765.9578723170226, 820.2453742556847, 838.8657814749641, 1217.567763443977, 32590.674093259062, 36735.0, 36744.0, 36779.63220367795, 36870.078248043814, 37250.0, 37539.62460375395, 37540.62459375405, 37590.43614345787, 37695.0, 6711.228208756007, 7797.844043119134, 7799.922000779976, 7801.141874393812, 7805.141434442206, 7274.890876636855, 7751.0311211098615, 7766.728164514243, 7936.920630793676, 8138.104808471064, 8146.144654811248, 8222.301104406133, 8224.300934420582, 8224.917750822475, 8228.83542329153, 7261.164966028895, 8082.515049097073, 8139.145389734082, 8140.0, 8204.917950820534, 7938.960305198437, 7949.165337639581, 7954.801129971739, 7989.0, 7996.24035716606, 34005.82997084999, 34054.659453405395, 34091.6590834091, 34116.48825267623, 34202.0, 3505.5968563615256, 3510.5787305523245, 3542.787432754017, 3547.6452354764574, 3549.8935031948827, 28379.858100709363, 28549.000784972635, 28677.713222867715, 28693.713062869312, 28731.425371492664, 3055.7555395568525, 3062.846857657108, 3072.646645635758, 3089.165925200206, 3105.192649911017, 247655.52344476504, 253550.0, 253643.0, 264022.0, 305524.0, 44671.32993005108, 45057.64827055194, 45175.322370164475, 45205.0, 45254.0, 39390.80304598516, 46963.29555056677, 46975.5302446978, 47099.29351059737, 47162.0, 12992.090553661154, 13424.865751342557, 14283.92858035724, 14284.0, 14288.285585720672, 11665.416729163508, 11709.0, 11714.004309633776, 11722.355270460035, 11744.412779360997, 11467.369294688706, 12084.939575302244, 12520.624381268577, 12846.486140554272, 12970.416331265116, 5790.247267855152, 5791.710414479344, 5793.768249269982, 5793.971030144822, 5864.530837532987, 995.6266400099962, 1000.8648832407678, 1006.1598565198142, 1059.6556119261118, 1061.867266591676, 19646.70529942052, 22014.44963875916, 22185.778142218693, 22506.32481025572, 22520.986555605035, 1085.1535802074395, 1099.543689368903, 1145.2498613408065, 1159.4202898550725, 1161.285809227336, 11624.6512604622, 11665.833416658266, 11682.299062056303, 12044.313796068558, 15288.006279591791, 349592.0, 350596.0, 351050.0, 351399.0, 352557.0, 52169.0, 52535.0, 52608.47391526112, 52692.0, 52764.73617631964, 1466.2961778346453, 1468.2438544149772, 1480.5558332500207, 1481.2519677562761, 1487.4422091715608, 26949.59575606328, 27552.586711198946, 28219.858900705363, 28658.85670571634, 28672.85663571669, 35607.75372861938, 35715.10712232215, 35787.28425431529, 35861.64138358687, 35906.0, 281514.0, 281525.0, 281553.0, 282143.0, 282431.0, 27731.168064958085, 28140.87436502599, 28187.859060704566, 32924.34151317008, 33373.16567085842, 32776.836115820224, 33743.0, 37981.0, 38247.0, 40059.7982060527, 27114.915403383642, 27518.312042199104, 27909.44181116407, 28941.10588941089, 28994.420111598072, 27382.726172738814, 28444.146675599764, 28808.567871481526, 28906.566401504417, 29239.83040678349, 26044.0, 32188.839055804572, 32908.67091329152, 33848.0, 33962.490562641084, 25046.74953250444, 27084.458310833277, 27469.31326716848, 29283.85358073281, 30634.693653064074, 19346.4196074118, 24126.87936560306, 24169.63745543783, 24303.0, 24481.0], "value": 16713.27923477682, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.499008496461, 499.48054023815234, 500.37703059690676, 509.58723434018435, 525.3800515391836, 21364.931753412326, 21943.451413714665, 21949.122035118577, 22109.557808843812, 22132.446688832788, 24286.392840179004, 24297.39256518588, 24320.513589728194, 24520.896559654815, 24582.385440364, 6344.651044192556, 6521.119648847416, 6558.639274839882, 6581.80254592363, 6660.633665148415, 6331.398517140888, 6432.292447830735, 6520.054592084145, 6550.639714815683, 6554.836129096763, 6012.819615411545, 6386.042093685938, 6404.839879003016, 6406.4554512866225, 6420.839479013039, 6388.744450221986, 6397.264314603834, 6404.4236018758065, 6409.647469389181, 6417.005364168562, 5866.354700982888, 5879.265091863517, 5882.941170588282, 5893.528517718572, 5896.351401345849, 28139.29651758702, 28514.71485285162, 29227.0, 29514.409711805645, 29536.409271814442, 1928.1323404467905, 1976.7529058867642, 2260.559190957764, 2279.4073540879326, 2304.0207911637485, 18549.258029678796, 19119.80880191208, 19138.23447062116, 19163.94598297093, 19187.52031199217, 2162.4593851537115, 2167.902444390006, 2216.213244298267, 2233.352327824929, 2234.36320648616, 169283.15358423252, 172881.0, 173417.0, 180934.0, 181306.18693813158, 12458.31479268649, 13580.45678172862, 14171.511991240977, 14725.926370368079, 14870.851291486946, 19382.61234775325, 19479.0, 19510.0, 19568.0, 19583.314583989493, 9767.267454940908, 9859.112679858847, 9901.257405694458, 9942.602295908082, 9959.302848800658, 9229.446233226028, 9246.583903724348, 9256.8611470828, 9260.583273752563, 9315.347925645276, 8017.518948863087, 8632.741017769476, 8893.555322233862, 9034.683786067457, 9087.273018158532, 2535.594304911205, 2539.5809691400714, 2545.7963362930923, 2569.486102779452, 2571.3186005708503, 976.4434272464769, 991.0832479956011, 1010.8129995950773, 1025.5436330832708, 1072.5173671847544, 21006.579868402547, 21072.683909741365, 21094.367168984954, 21538.523069846855, 22712.205072822373, 1040.8750949886062, 1048.70112018076, 1058.2486434631498, 1067.5730903798892, 1073.0878753059837, 10676.51955661997, 11077.778444431227, 11153.776924461465, 11204.271722338022, 11465.713357166138, 504114.0, 522494.0, 523364.0, 525879.0, 568500.0, 117479.0, 120446.0, 142712.0, 143431.0, 143481.0, 1198.6943329450935, 1212.8423304970297, 1233.5559198688466, 1242.87571242875, 1245.4893493667553, 19133.425997220107, 19492.122854471865, 20110.497237568594, 20150.09324580368, 20503.38489845307, 68692.0, 69217.0, 70905.29094708987, 71915.0, 72227.0, 93208.0, 93675.53162234146, 94993.5250323744, 97563.51218243863, 98488.50755746462, 11604.593839215588, 11642.592509262135, 11709.180357374902, 11812.055035596959, 11860.644180674593, 18400.723989139908, 18537.629247415247, 18553.907230463763, 18837.434876953714, 18872.961987090843, 14330.426782928565, 15306.0, 15324.846751532343, 15341.309641066402, 15451.0, 11791.349211110622, 13139.277339746406, 13411.798823017467, 13469.191848489121, 14363.138211706913, 15516.689666206386, 15811.209439527747, 15821.683566328838, 15870.7619385707, 15889.761653575437, 11472.31166130035, 11598.362090085126, 11628.069754419796, 11641.708957275798, 11654.0, 13930.0, 15084.849151508344, 15151.939364244397, 15156.090634561962, 15221.315040822969], "value": 11656.786046271429, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1251.7746805574998, 1430.9284535773209, 1449.5433938309425, 1456.9708605827884, 1472.300657187836, 13563.728725425486, 13929.442822287096, 14064.859351406509, 14067.929660351683, 14122.0, 13576.932115339434, 13826.654333641663, 13959.720805583907, 13964.930175349109, 13979.650508737286, 2705.9729402705916, 2749.5188342040165, 2750.518659234636, 2760.682521510032, 2765.3777899972547, 2700.162949485656, 2746.3408781892404, 2746.4644394343113, 2755.61421401004, 2756.9586456203174, 2703.189043287016, 2740.0272903119403, 2758.9862050689817, 2760.7101254368304, 2762.8066035377533, 2704.648395708555, 2713.579395193748, 2718.796090293226, 2719.809613327068, 2725.9591106133425, 2690.744379283965, 2701.3921867579834, 2752.078053851962, 2755.9448811023867, 2757.4485102979484, 13493.527726529623, 13542.796858047137, 13553.254570998592, 13559.45762169512, 13588.456461741518, 7422.7030918763185, 7612.3148916597775, 7663.195364486704, 7700.614969251515, 7710.691572337156, 4451.821927122911, 4561.950751327214, 4729.69256998294, 4803.327534145187, 4852.660313778037, 1228.1587112827651, 1229.434460148324, 1381.9723605527934, 1391.2278685329638, 1408.7041721238552, 18670.81329186704, 19369.934653594046, 20377.694334584994, 20538.0, 20557.69163462549, 5951.761929522857, 6193.628382297076, 6494.253160886464, 6823.69293381799, 6834.179898412222, 12257.14200005991, 14457.493987710379, 14488.637784055272, 14493.710125797425, 14494.492692755703, 7172.103487064117, 7475.289847464483, 7504.737334193386, 7515.661795219228, 7548.131964824006, 6659.766908158288, 6799.082123913307, 6860.416864566468, 6862.279460656659, 6906.205786334535, 4466.531014243523, 5002.824901128443, 5074.873128171751, 5076.949230507722, 5096.464871188546, 1273.4715093236202, 1466.4061055272632, 1470.1987416857837, 1470.7279153356662, 1488.9404423822925, 814.9975530098012, 830.1325115254574, 835.5738573327525, 845.1632883445345, 863.0592654007162, 6282.403171698681, 6980.825479363057, 7268.618962397233, 7321.048263725682, 7390.7413240536325, 594.8007417515095, 682.6586706646676, 728.8469421421455, 729.2051663686607, 731.7365748330596, 4085.5914408558906, 4146.295129827876, 4325.394444777734, 4361.738295702268, 4601.838935637304, 17719.73420398695, 18235.0, 18287.268509259746, 18430.447086587425, 18541.0, 17721.7341739874, 18523.72214416785, 18634.534136646693, 18665.626687466174, 18747.343842965704, 8262.628181731836, 9382.812343753223, 9454.621815127319, 9528.95235523818, 9609.08713672214, 6627.668616569056, 7565.243475652391, 7762.650680719268, 7877.724279650185, 8188.344932405513, 16241.431549895697, 16745.581360466087, 16771.32914683399, 16813.831861681225, 17049.82950170532, 15950.441734539236, 16346.264418101451, 17180.570485737957, 17332.220050097523, 17487.912560437115, 10732.946335268274, 10989.780204396027, 10994.725131871768, 11001.339919604849, 11008.0, 11781.0, 12152.027837773134, 12419.37903104823, 12439.191452555555, 12518.248905065726, 9988.60045598197, 9999.150072243941, 10241.539130738985, 10282.023207795248, 10445.791084178425, 9772.804543909224, 9993.650222242186, 10079.798404032024, 10289.0, 10328.948355258175, 8940.955295223483, 9197.908020919705, 9416.717498475056, 9417.482038487675, 9453.24374050088, 6668.799936001928, 6801.68742627509, 6866.896996545156, 6931.994860745264, 7030.191527974348, 6777.525573209828, 6850.760223392157, 7021.297870212938, 7095.82260443493, 7156.212816590275], "value": 6034.079510119523, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.30580884077716, 421.33008516458824, 447.78058751211904, 457.2752187782367, 462.6853739457169, 5989.0, 6438.484921206304, 6557.95072788354, 6558.311377305386, 6590.340965903413, 5200.219967004951, 6093.299270583884, 6270.0, 6272.153259309992, 6376.617402955826, 4932.8520144395725, 4933.629977751665, 4936.259561065842, 4939.25911113334, 4959.900062460962, 4323.027318853265, 4926.482719314474, 4944.9258261126115, 4959.115536096278, 4960.60315174787, 4727.929081063769, 4908.779104940269, 4920.089783390066, 4948.653594248404, 4956.330895329121, 4950.628702847301, 4951.811565224339, 4963.379577552806, 4965.875853103666, 4977.651564390494, 3662.7802331859907, 3967.266055779661, 4450.4881938577155, 4475.127350166719, 4499.370088187657, 5780.393058728815, 6304.744297599148, 6328.620282783002, 6330.283938465826, 6356.427921487042, 5946.672932988684, 5957.463828255478, 5964.850878728023, 5982.222311099573, 5988.7604495820115, 6920.826979325507, 6949.44404447643, 6975.162980442379, 6998.0, 6999.650017499104, 4427.734335939854, 5162.277281180638, 5181.1969144782615, 5190.429052804188, 5191.2472691459525, 6325.6083661594375, 6863.725450981955, 7285.744998925013, 7292.0520332356455, 7323.414126869838, 3921.705872059607, 4155.771432571177, 4824.396950381202, 4884.633652476078, 5311.548518375904, 4376.737395756265, 4761.95238047622, 4893.828715994994, 4941.604671626261, 4942.75286235694, 5009.849704508871, 5596.916046259309, 5614.410486898899, 5620.803271885547, 5643.661380317194, 6154.353792851686, 6269.8746025079245, 6520.413162815369, 6852.280510546321, 6863.862722745517, 1536.7694845773224, 1542.9845701543065, 1592.5540848562189, 1679.546522438959, 1700.7108791505473, 944.3672739264572, 979.3780949097375, 1099.274478843959, 1139.8404223408563, 1139.9544018239178, 6342.8414289642205, 6379.904301435482, 6395.968020159869, 6427.228732552124, 6517.837054073591, 6093.69531523422, 6773.796786096425, 7058.941108862322, 7113.407729096876, 7113.573185608777, 5670.971645141831, 6410.551261411656, 6415.518836087219, 6457.192850893638, 6467.482601391877, 3120.438321102178, 3297.268933809769, 3314.436545787222, 3730.1793605406765, 3732.8320225589914, 7280.344768970818, 7284.526505777214, 7291.380232680175, 7309.342159205697, 7317.670704818296, 6111.877762444815, 6490.513211509156, 7204.783856484314, 7292.34368906801, 7293.343599076109, 5657.43425657431, 5765.711714414262, 7264.200937896827, 7274.599897005715, 7280.96359518199, 6259.467945224706, 6820.454363650995, 7064.858702826017, 7111.395531379684, 7130.393916517154, 6327.272363678236, 6746.61470044475, 6871.3128687132885, 6888.242293347627, 6889.517733758589, 6768.830779230559, 7243.167035790741, 7280.781576552712, 7288.33975598892, 7319.158296795724, 4815.0, 4815.422149342101, 4827.565519103297, 4853.6359773017875, 4855.95144048555, 6004.789832355847, 6463.418292353711, 7089.255628158969, 7103.0, 7108.147022357351, 6068.544859135494, 6217.052171870804, 6564.606123632598, 6836.863262734817, 6840.384365407137, 5717.999350113797, 5783.768649253982, 5917.319508256433, 6706.966465167643, 6864.5881247125335, 6232.657203853651, 6431.1317972073175, 6685.197776266879, 6697.5888680076105, 6706.731730730716, 6003.429674180946, 6082.452579267887, 6864.588124712333, 6894.655267236518, 6915.930840691529, 5688.914666280092, 6686.863233250262, 6690.732370705312, 6706.463482921257, 6714.462842972648], "value": 5041.871675391109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.347029111856, 834.6577903059747, 835.9164083591642, 865.7186414415316, 866.5797088412123, 16983.83016169838, 17002.0, 17238.482845514656, 17255.654886902255, 17507.73738393925, 12960.54638087667, 13359.465621375157, 13480.66298342542, 13558.796618050712, 13559.457621695145, 652.2564276724538, 948.0, 950.7100334398006, 957.0, 958.8993155718654, 999.385377992533, 1005.2460654509118, 1006.3106771861262, 1007.4761124215404, 1009.2430676992255, 1045.5086109528547, 1046.7958748044134, 1048.5019615682527, 1052.1214785653988, 1055.4828134214217, 951.4909523405014, 997.3018886779283, 1043.890391508892, 1046.9476526173698, 1062.1131355318319, 768.3584207187006, 768.6233745464682, 769.0348612491343, 770.76106407014, 772.3357912195507, 17389.304427822874, 17394.21726022332, 17554.47336579892, 17588.12059397038, 17598.91200544002, 15441.30514126867, 16455.177241138015, 16562.0, 17670.558236044075, 17714.822851771576, 14443.277836108153, 14448.566543003726, 14942.551723448314, 15009.249537523081, 15070.0, 3640.1445660269815, 3643.744937854351, 3649.6532829381167, 3653.0502069461863, 3655.3603119454256, 92887.0, 94360.0, 131828.68171318356, 132687.6731232685, 135170.0, 10264.076233139054, 11164.776704466027, 11170.888291116984, 11442.14183936208, 12121.878781212075, 24996.875015624806, 25363.619545706828, 25797.968081276915, 26741.465170696865, 27001.0, 8131.105578386373, 8143.267105960492, 8161.0, 8162.347012239125, 8225.971753530808, 3878.786666733357, 4045.4538637284177, 4051.169510250392, 4063.9796801015805, 4091.5090189176894, 4543.1822271990695, 4691.366665500113, 4693.037927224911, 4695.859124226278, 4700.130475861976, 995.9950200248952, 1014.6803756816579, 1032.3341444768082, 1033.3231733214757, 1037.984430233547, 635.3392471829338, 657.4970147836813, 667.6795138333628, 676.3067855448205, 681.1859827506082, 11177.105831533458, 12394.75210495803, 12435.75128497425, 12485.188462749893, 12593.244405335709, 611.4741322462678, 644.9169183537108, 651.791426743447, 664.8437617159964, 678.8608335291253, 5110.565601923878, 5344.706041167773, 5451.182322651554, 5502.889942201214, 5796.62321949072, 148134.0, 149862.0, 150509.0, 150792.0, 156447.43552564556, 98474.50762746141, 123133.0, 126303.0, 129478.0, 133078.33843323274, 92682.0, 106800.46599766951, 110983.0, 111239.88760112296, 112648.43675781568, 10919.836202457129, 11364.318140911571, 11540.24988375753, 11668.708282292671, 11797.469113890067, 41384.793076034424, 50411.0, 51622.48377516177, 52010.21984670157, 52599.73700131628, 53581.2065252361, 67164.0, 68845.3115468839, 72237.0, 72540.27459725334, 12350.62948111558, 12529.18560293578, 12540.561080362144, 12611.74776504483, 12728.490860365482, 13345.332733363099, 16017.919910400373, 16202.837971620133, 16244.187790610185, 16438.424655137012, 14475.059121157092, 14702.485413010494, 14809.85190148128, 14842.925785371435, 15028.17345046033, 14511.78232326537, 14671.339789709275, 14681.48614798477, 14918.328675209425, 15067.0, 13970.930145349208, 14925.626859328604, 15014.549563513112, 15017.249137543298, 16477.093759842843, 11614.825777613512, 12394.81407778902, 12412.131150819356, 12714.87285127137, 13049.473884064875, 11784.175107742376, 12943.482260709467, 13464.730705385642, 13511.932440337736, 13977.440902364198], "value": 8494.063713851545, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 119.0, 98.0, 115.0, 156.0, 160.0, 167.0, 115.0, 117.0], "value": 126.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 112.0, 150.0, 136.0, 108.0, 126.0, 128.0, 112.0, 152.0, 112.0], "value": 127.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 205.0, 200.0, 219.0, 219.0, 236.0, 202.0, 202.0, 201.0, 198.0], "value": 208.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 81.0, 69.0, 129.0, 57.0, 59.0, 58.0, 64.0, 135.0, 71.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 136.0, 98.0, 110.0, 86.0, 105.0, 68.0, 70.0, 111.0], "value": 92.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 90.0, 92.0, 89.0, 89.0, 90.0, 97.0, 88.0], "value": 90.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 98.0, 96.0, 98.0, 111.0, 149.0, 108.0, 97.0, 99.0], "value": 105.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 133.0, 147.0, 128.0, 93.0, 109.0, 120.0, 92.0, 93.0, 93.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 43.0, 69.0, 73.0, 72.0, 74.0, 73.0, 75.0, 54.0, 52.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 58.0, 61.0, 84.0, 70.0, 64.0, 59.0, 56.0, 72.0], "value": 63.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 83.0, 80.0, 82.0, 82.0, 80.0, 81.0, 83.0, 85.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 140.0, 136.0, 140.0, 136.0, 141.0, 154.0, 153.0, 138.0, 182.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 224.0, 182.0, 226.0, 221.0, 181.0, 183.0, 182.0, 192.0, 212.0], "value": 201.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 64.0, 64.0, 67.0, 63.0, 64.0, 64.0, 66.0, 64.0], "value": 64.8, "unit": "score"}]}]} 13:14:23 INFO - Return code: 0 13:14:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:14:23 INFO - # TBPL SUCCESS # 13:14:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:14:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:14:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:14:23 INFO - 2017-06-15 13:14:23.205 screenresolution[2026:18280] starting screenresolution argv=screenresolution get 13:14:23 INFO - 2017-06-15 13:14:23.207 screenresolution[2026:18280] Display 0: 1600x1200x32@60 13:14:23 INFO - 2017-06-15 13:14:23.213 screenresolution[2027:18283] starting screenresolution argv=screenresolution list 13:14:23 INFO - Available Modes on Display 0 13:14:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:14:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:14:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:14:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:14:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:14:23 INFO - Intel Iris: 13:14:23 INFO - Chipset Model: Intel Iris 13:14:23 INFO - Type: GPU 13:14:23 INFO - Bus: Built-In 13:14:23 INFO - VRAM (Dynamic, Max): 1536 MB 13:14:23 INFO - Vendor: Intel (0x8086) 13:14:23 INFO - Device ID: 0x0a2e 13:14:23 INFO - Revision ID: 0x0009 13:14:23 INFO - Displays: 13:14:23 INFO - Display: 13:14:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:14:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:14:23 INFO - Display Serial Number: 1600x1200 60 13:14:23 INFO - Main Display: Yes 13:14:23 INFO - Mirror: Off 13:14:23 INFO - Online: Yes 13:14:23 INFO - Rotation: Supported 13:14:23 INFO - Return code: 0 13:14:23 INFO - Running post-action listener: _resource_record_post_action 13:14:23 INFO - [mozharness: 2017-06-15 20:14:23.449854Z] Finished run-tests step (success) 13:14:23 INFO - Running post-run listener: _resource_record_post_run 13:14:23 INFO - Total resource usage - Wall time: 1257s; CPU: 54.0%; Read bytes: 811008; Write bytes: 569450496; Read time: 11; Write time: 1900 13:14:23 INFO - TinderboxPrint: CPU usage
53.6% 13:14:23 INFO - TinderboxPrint: I/O read bytes / time
811,008 / 11 13:14:23 INFO - TinderboxPrint: I/O write bytes / time
569,450,496 / 1,900 13:14:23 INFO - TinderboxPrint: CPU idle
2,336.2 (46.4%) 13:14:23 INFO - TinderboxPrint: CPU system
1,024.4 (20.4%) 13:14:23 INFO - TinderboxPrint: CPU user
1,669.1 (33.2%) 13:14:23 INFO - TinderboxPrint: Swap in / out
254,545,920 / 0 13:14:23 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244513280; Write bytes: 238642688; Read time: 17744; Write time: 474 13:14:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 13:14:23 INFO - run-tests - Wall time: 1236s; CPU: 54.0%; Read bytes: 811008; Write bytes: 312453632; Read time: 11; Write time: 1383 13:14:23 INFO - Running post-run listener: _upload_blobber_files 13:14:23 INFO - Blob upload gear active. 13:14:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:14:23 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 13:14:23 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'] 13:14:23 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 13:14:24 INFO - (blobuploader) - INFO - Open directory for files ... 13:14:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:14:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:14:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:14:25 INFO - (blobuploader) - INFO - Done attempting. 13:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:14:26 INFO - (blobuploader) - INFO - Done attempting. 13:14:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:14:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:14:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:14:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:14:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:14:27 INFO - (blobuploader) - INFO - Done attempting. 13:14:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:14:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:14:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:14:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:14:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:14:29 INFO - (blobuploader) - INFO - Done attempting. 13:14:29 INFO - (blobuploader) - INFO - Iteration through files over. 13:14:29 INFO - Return code: 0 13:14:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:14:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:14:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df6d7558c1f7368e8540887a3ea8194d5e1903a82d5378991932f459453d085d3cad4a7fb55c589dfe027fd94eeca1fe0baa89ec9c90a6be13a0da33a327642d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/969b352b7098c3e728a4b18e0fd686edfe7782bcd33a4e7825378e8d991d9c871fe91bae8c499178b28a25e5eadb0763c06fc6772d8894d1618b5a1125ecbbb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b74c44f922b39e03c07fe1ff01d9aa366cc8794794b3ec3bed6025f18a7c2119530b2ee229360b7bd5e35884a55c9906e8095428029f8c03a2560a2c26ae162f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/851ce2e47a7971644acc2517410af4df07a243b7f91de5eb63e826101a52bf3de06030496dd38a36d4256501e8b754b127dd3a1fabba376d115df925cab99967"} 13:14:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:14:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:14:29 INFO - Contents: 13:14:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df6d7558c1f7368e8540887a3ea8194d5e1903a82d5378991932f459453d085d3cad4a7fb55c589dfe027fd94eeca1fe0baa89ec9c90a6be13a0da33a327642d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/969b352b7098c3e728a4b18e0fd686edfe7782bcd33a4e7825378e8d991d9c871fe91bae8c499178b28a25e5eadb0763c06fc6772d8894d1618b5a1125ecbbb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b74c44f922b39e03c07fe1ff01d9aa366cc8794794b3ec3bed6025f18a7c2119530b2ee229360b7bd5e35884a55c9906e8095428029f8c03a2560a2c26ae162f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/851ce2e47a7971644acc2517410af4df07a243b7f91de5eb63e826101a52bf3de06030496dd38a36d4256501e8b754b127dd3a1fabba376d115df925cab99967"} 13:14:29 INFO - Running post-run listener: copy_logs_to_upload_dir 13:14:29 INFO - Copying logs to upload dir... 13:14:29 INFO - mkdir: /builds/slave/test/build/upload/logs 13:14:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.153555 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-15 13:14:29.693080) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 13:14:29.697056) ========= 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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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/df6d7558c1f7368e8540887a3ea8194d5e1903a82d5378991932f459453d085d3cad4a7fb55c589dfe027fd94eeca1fe0baa89ec9c90a6be13a0da33a327642d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/969b352b7098c3e728a4b18e0fd686edfe7782bcd33a4e7825378e8d991d9c871fe91bae8c499178b28a25e5eadb0763c06fc6772d8894d1618b5a1125ecbbb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b74c44f922b39e03c07fe1ff01d9aa366cc8794794b3ec3bed6025f18a7c2119530b2ee229360b7bd5e35884a55c9906e8095428029f8c03a2560a2c26ae162f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/851ce2e47a7971644acc2517410af4df07a243b7f91de5eb63e826101a52bf3de06030496dd38a36d4256501e8b754b127dd3a1fabba376d115df925cab99967"} build_url:https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011727 build_url: 'https://queue.taskcluster.net/v1/task/FTThGpRES7qYQsJx2jc4pA/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df6d7558c1f7368e8540887a3ea8194d5e1903a82d5378991932f459453d085d3cad4a7fb55c589dfe027fd94eeca1fe0baa89ec9c90a6be13a0da33a327642d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/969b352b7098c3e728a4b18e0fd686edfe7782bcd33a4e7825378e8d991d9c871fe91bae8c499178b28a25e5eadb0763c06fc6772d8894d1618b5a1125ecbbb8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b74c44f922b39e03c07fe1ff01d9aa366cc8794794b3ec3bed6025f18a7c2119530b2ee229360b7bd5e35884a55c9906e8095428029f8c03a2560a2c26ae162f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/851ce2e47a7971644acc2517410af4df07a243b7f91de5eb63e826101a52bf3de06030496dd38a36d4256501e8b754b127dd3a1fabba376d115df925cab99967"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 13:14:29.733597) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 13:14:29.734255) ========= 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.wzd0R9yQwa/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.9rDcbLjg0h/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.005190 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 13:14:29.784988) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 13:14:29.785320) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 13:14:29.785715) ========= ========= Total master_lag: 0.19 =========