builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1495644668.99 results: success (0) revision: e84acaf49a8f5032864a2602c1c61b68ceb1b773 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:08.989217) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:08.989665) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:08.990021) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.013549) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.013933) ========= 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.1a7K1OWlZv/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.fab3bF7zb1/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-24 09:51:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2017-05-24 09:51:09 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143722 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.180828) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.181179) ========= 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.1a7K1OWlZv/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.fab3bF7zb1/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.024267 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.231293) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.231823) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e84acaf49a8f5032864a2602c1c61b68ceb1b773 --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 e84acaf49a8f5032864a2602c1c61b68ceb1b773 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1a7K1OWlZv/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.fab3bF7zb1/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-24 09:51:09,306 truncating revision to first 12 chars 2017-05-24 09:51:09,306 Setting DEBUG logging. 2017-05-24 09:51:09,306 attempt 1/10 2017-05-24 09:51:09,306 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e84acaf49a8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 09:51:09,758 unpacking tar archive at: mozilla-central-e84acaf49a8f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.703321 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.957938) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.958267) ========= script_repo_revision: e84acaf49a8f5032864a2602c1c61b68ceb1b773 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.958707) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.958994) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 09:51:09.978023) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-24 09:51:09.978496) ========= /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.1a7K1OWlZv/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.fab3bF7zb1/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:51:10 INFO - MultiFileLogger online at 20170524 09:51:10 in /builds/slave/test 09:51:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:51:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:51:10 INFO - {'append_to_log': False, 09:51:10 INFO - 'base_work_dir': '/builds/slave/test', 09:51:10 INFO - 'blob_upload_branch': 'Firefox', 09:51:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:51:10 INFO - 'branch': 'Firefox', 09:51:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:51:10 INFO - 'config_files': ('talos/mac_config.py',), 09:51:10 INFO - 'default_actions': ('clobber', 09:51:10 INFO - 'read-buildbot-config', 09:51:10 INFO - 'download-and-extract', 09:51:10 INFO - 'populate-webroot', 09:51:10 INFO - 'create-virtualenv', 09:51:10 INFO - 'install', 09:51:10 INFO - 'run-tests'), 09:51:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:51:10 INFO - 'download_minidump_stackwalk': True, 09:51:10 INFO - 'download_symbols': 'ondemand', 09:51:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:51:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:51:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:51:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:51:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:51:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:51:10 INFO - 'gecko_profile': False, 09:51:10 INFO - 'gecko_profile_interval': 0, 09:51:10 INFO - 'installer_path': 'installer.exe', 09:51:10 INFO - 'log_level': 'info', 09:51:10 INFO - 'log_name': 'talos', 09:51:10 INFO - 'log_to_console': True, 09:51:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:51:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:51:10 INFO - 'opt_config_files': (), 09:51:10 INFO - 'pip_index': False, 09:51:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:51:10 INFO - 'cmd': ('bash', 09:51:10 INFO - '-c', 09:51:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:51:10 INFO - 'enabled': True, 09:51:10 INFO - 'halt_on_failure': False, 09:51:10 INFO - 'name': 'check_screen_resolution'},), 09:51:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:51:10 INFO - 'cmd': ('bash', 09:51:10 INFO - '-c', 09:51:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:51:10 INFO - 'enabled': True, 09:51:10 INFO - 'halt_on_failure': False, 09:51:10 INFO - 'name': 'check_screen_resolution'},), 09:51:10 INFO - 'run_cmd_checks_enabled': True, 09:51:10 INFO - 'suite': 'dromaeojs-e10s', 09:51:10 INFO - 'system_bits': '32', 09:51:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:51:10 INFO - 'title': 't-yosemite-r7-0111', 09:51:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:51:10 INFO - 'use_talos_json': True, 09:51:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:51:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:51:10 INFO - 'work_dir': 'build'} 09:51:10 INFO - [mozharness: 2017-05-24 16:51:10.181900Z] Running clobber step. 09:51:10 INFO - Running pre-action listener: _resource_record_pre_action 09:51:10 INFO - Running main action method: clobber 09:51:10 INFO - rmtree: /builds/slave/test/build 09:51:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:51:10 INFO - Running post-action listener: _resource_record_post_action 09:51:10 INFO - [mozharness: 2017-05-24 16:51:10.340039Z] Finished clobber step (success) 09:51:10 INFO - [mozharness: 2017-05-24 16:51:10.340211Z] Running read-buildbot-config step. 09:51:10 INFO - Running pre-action listener: _resource_record_pre_action 09:51:10 INFO - Running main action method: read_buildbot_config 09:51:10 INFO - Using buildbot properties: 09:51:10 INFO - { 09:51:10 INFO - "project": "", 09:51:10 INFO - "product": "firefox", 09:51:10 INFO - "who": "ffxbld@noreply.mozilla.org", 09:51:10 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:51:10 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:51:10 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:51:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:51:10 INFO - "stage_platform": "macosx64", 09:51:10 INFO - "basedir": "/builds/slave/test", 09:51:10 INFO - "buildnumber": 33, 09:51:10 INFO - "platform": "yosemite_r7", 09:51:10 INFO - "parent_task_id": "d_nb_Q8ORDKJwLQ4oKGoXQ", 09:51:10 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:51:10 INFO - "slavebuilddir": "test", 09:51:10 INFO - "taskId": "drY7FPbWQ7aW6QHtKcpO5w", 09:51:10 INFO - "branch": "mozilla-central", 09:51:10 INFO - "script_repo_revision": "production", 09:51:10 INFO - "revision": "e84acaf49a8f5032864a2602c1c61b68ceb1b773", 09:51:10 INFO - "slavename": "t-yosemite-r7-0111", 09:51:10 INFO - "repo_path": "mozilla-central" 09:51:10 INFO - } 09:51:10 INFO - Finding installer, test and symbols from parent task. 09:51:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/drY7FPbWQ7aW6QHtKcpO5w'}, attempt #1 09:51:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/drY7FPbWQ7aW6QHtKcpO5w'}, attempt #1 09:51:11 INFO - Task dependencies: GGF9gOhNTpCMmZxIe16mow d_nb_Q8ORDKJwLQ4oKGoXQ 09:51:11 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ'}, attempt #1 09:51:12 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:51:12 INFO - Set test_url: https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/test_packages.json 09:51:12 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/target.crashreporter-symbols.zip 09:51:12 INFO - Running post-action listener: _resource_record_post_action 09:51:12 INFO - [mozharness: 2017-05-24 16:51:12.184020Z] Finished read-buildbot-config step (success) 09:51:12 INFO - [mozharness: 2017-05-24 16:51:12.184125Z] Running download-and-extract step. 09:51:12 INFO - Running pre-action listener: _resource_record_pre_action 09:51:12 INFO - Running main action method: download_and_extract 09:51:12 INFO - mkdir: /builds/slave/test/build/tests 09:51:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:12 INFO - trying https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:51:12 INFO - Downloading https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:51:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 09:51:13 INFO - Downloaded 1325 bytes. 09:51:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:51:13 INFO - Using the following test package requirements: 09:51:13 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:51:13 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:51:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:51:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:51:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'jsshell-mac.zip'], 09:51:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:51:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:51:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:51:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:51:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:51:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:51:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:51:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:51:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:51:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:51:13 INFO - Fetch https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:51:14 INFO - Content-Length response header: 57630750 09:51:14 INFO - Bytes received: 57630750 09:51:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:51:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:51:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:51:33 INFO - Fetch https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:51:35 INFO - Content-Length response header: 57630750 09:51:35 INFO - Bytes received: 57630750 09:51:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:51:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:51:52 INFO - Fetch https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:51:53 INFO - Content-Length response header: 14411859 09:51:53 INFO - Bytes received: 14411859 09:51:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:53 INFO - trying https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:51:53 INFO - Downloading https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:51:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 09:51:55 INFO - Downloaded 68009404 bytes. 09:51:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:51:55 INFO - mkdir: /builds/slave/test/properties 09:51:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:51:55 INFO - Writing to file /builds/slave/test/properties/build_url 09:51:55 INFO - Contents: 09:51:55 INFO - build_url:https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:51:55 INFO - Running post-action listener: _resource_record_post_action 09:51:55 INFO - Running post-action listener: set_extra_try_arguments 09:51:55 INFO - [mozharness: 2017-05-24 16:51:55.927566Z] Finished download-and-extract step (success) 09:51:55 INFO - [mozharness: 2017-05-24 16:51:55.927741Z] Running populate-webroot step. 09:51:55 INFO - Running pre-action listener: _resource_record_pre_action 09:51:55 INFO - Running main action method: populate_webroot 09:51:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:51:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:51:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:51:55 INFO - '--tppagecycles', 09:51:55 INFO - '7'], 09:51:55 INFO - 'tests': ['tsvgm']}}, 09:51:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:51:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:51:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:51:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:51:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:51:55 INFO - 'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:51:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:51:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:51:55 INFO - 'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['damp', 'tps']}, 09:51:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:51:55 INFO - 'tests': ['damp', 'tps']}, 09:51:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['dromaeo_dom']}, 09:51:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:51:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:51:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:51:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:51:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:51:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['a11yr', 09:51:55 INFO - 'ts_paint', 09:51:55 INFO - 'tpaint', 09:51:55 INFO - 'sessionrestore', 09:51:55 INFO - 'sessionrestore_no_auto_restore', 09:51:55 INFO - 'tabpaint']}, 09:51:55 INFO - 'other-e10s': {'tests': ['a11yr', 09:51:55 INFO - 'ts_paint', 09:51:55 INFO - 'tpaint', 09:51:55 INFO - 'sessionrestore', 09:51:55 INFO - 'sessionrestore_no_auto_restore', 09:51:55 INFO - 'tabpaint']}, 09:51:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:51:55 INFO - 'ts_paint', 09:51:55 INFO - 'tpaint', 09:51:55 INFO - 'sessionrestore', 09:51:55 INFO - 'sessionrestore_no_auto_restore', 09:51:55 INFO - 'tabpaint']}, 09:51:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:51:55 INFO - 'ts_paint', 09:51:55 INFO - 'tpaint', 09:51:55 INFO - 'sessionrestore', 09:51:55 INFO - 'sessionrestore_no_auto_restore', 09:51:55 INFO - 'tabpaint']}, 09:51:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['a11yr', 09:51:55 INFO - 'ts_paint', 09:51:55 INFO - 'tpaint', 09:51:55 INFO - 'sessionrestore', 09:51:55 INFO - 'sessionrestore_no_auto_restore', 09:51:55 INFO - 'tabpaint']}, 09:51:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['a11yr', 09:51:55 INFO - 'ts_paint', 09:51:55 INFO - 'tpaint', 09:51:55 INFO - 'sessionrestore', 09:51:55 INFO - 'sessionrestore_no_auto_restore', 09:51:55 INFO - 'tabpaint']}, 09:51:55 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:51:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:51:55 INFO - 'bloom_basic_ref']}, 09:51:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['tsvgx', 09:51:55 INFO - 'tsvgr_opacity', 09:51:55 INFO - 'tart', 09:51:55 INFO - 'tscrollx', 09:51:55 INFO - 'cart', 09:51:55 INFO - 'tsvg_static']}, 09:51:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:51:55 INFO - 'tsvgr_opacity', 09:51:55 INFO - 'tart', 09:51:55 INFO - 'tscrollx', 09:51:55 INFO - 'cart', 09:51:55 INFO - 'tsvg_static']}, 09:51:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:51:55 INFO - 'talos_options': ['--disable-e10s'], 09:51:55 INFO - 'tests': ['tp5o']}, 09:51:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:51:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:51:55 INFO - 'talos_options': ['--disable-e10s', 09:51:55 INFO - '--xperf_path', 09:51:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:55 INFO - 'tests': ['tp5n']}, 09:51:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:51:55 INFO - 'talos_options': ['--xperf_path', 09:51:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:51:55 INFO - 'tests': ['tp5n']}}, 09:51:55 INFO - 'talos.zip': {'path': '', 09:51:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:51:55 INFO - Running post-action listener: _resource_record_post_action 09:51:55 INFO - [mozharness: 2017-05-24 16:51:55.948785Z] Finished populate-webroot step (success) 09:51:55 INFO - [mozharness: 2017-05-24 16:51:55.948926Z] Running create-virtualenv step. 09:51:55 INFO - Running pre-action listener: _resource_record_pre_action 09:51:55 INFO - Running main action method: create_virtualenv 09:51:55 INFO - Creating virtualenv /builds/slave/test/build/venv 09:51:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:51:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:51:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:51:56 INFO - Using real prefix '/tools/python27' 09:51:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:51:56 INFO - Installing distribute.............................................................................................................................................................................................done. 09:51:59 INFO - Installing pip.................done. 09:51:59 INFO - Return code: 0 09:51:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:51:59 INFO - Reading from file tmpfile_stdout 09:51:59 INFO - Output received: 09:51:59 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:51:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:51:59 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:51:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:51:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:51:59 INFO - 'HOME': '/Users/cltbld', 09:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:51:59 INFO - 'LOGNAME': 'cltbld', 09:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:51:59 INFO - 'MOZ_NO_REMOTE': '1', 09:51:59 INFO - 'NO_EM_RESTART': '1', 09:51:59 INFO - 'PAGER': '/bin/cat', 09:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:51:59 INFO - 'PWD': '/builds/slave/test', 09:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:51:59 INFO - 'SHELL': '/bin/bash', 09:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:51:59 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:51:59 INFO - 'USER': 'cltbld', 09:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:51:59 INFO - 'XPC_FLAGS': '0x0', 09:51:59 INFO - 'XPC_SERVICE_NAME': '0', 09:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:52:00 INFO - Cleaning up... 09:52:00 INFO - Return code: 0 09:52:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:00 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:52:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:52:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:00 INFO - 'HOME': '/Users/cltbld', 09:52:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:00 INFO - 'LOGNAME': 'cltbld', 09:52:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:00 INFO - 'MOZ_NO_REMOTE': '1', 09:52:00 INFO - 'NO_EM_RESTART': '1', 09:52:00 INFO - 'PAGER': '/bin/cat', 09:52:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:00 INFO - 'PWD': '/builds/slave/test', 09:52:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:00 INFO - 'SHELL': '/bin/bash', 09:52:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:00 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:00 INFO - 'USER': 'cltbld', 09:52:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:00 INFO - 'XPC_FLAGS': '0x0', 09:52:00 INFO - 'XPC_SERVICE_NAME': '0', 09:52:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:00 INFO - Downloading/unpacking psutil>=3.1.1 09:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:52:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:02 INFO - Installing collected packages: psutil 09:52:02 INFO - Running setup.py install for psutil 09:52:02 INFO - building 'psutil._psutil_osx' extension 09:52:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:52:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:52:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:52:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:52:03 INFO - building 'psutil._psutil_posix' extension 09:52:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:52:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:52:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:52:03 INFO - ^ 09:52:03 INFO - 1 warning generated. 09:52:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:52:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:52:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:52:03 INFO - Successfully installed psutil 09:52:03 INFO - Cleaning up... 09:52:03 INFO - Return code: 0 09:52:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:03 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:52:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:52:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:03 INFO - 'HOME': '/Users/cltbld', 09:52:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:03 INFO - 'LOGNAME': 'cltbld', 09:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:03 INFO - 'MOZ_NO_REMOTE': '1', 09:52:03 INFO - 'NO_EM_RESTART': '1', 09:52:03 INFO - 'PAGER': '/bin/cat', 09:52:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:03 INFO - 'PWD': '/builds/slave/test', 09:52:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:03 INFO - 'SHELL': '/bin/bash', 09:52:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:03 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:03 INFO - 'USER': 'cltbld', 09:52:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:03 INFO - 'XPC_FLAGS': '0x0', 09:52:03 INFO - 'XPC_SERVICE_NAME': '0', 09:52:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:52:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:52:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:52:05 INFO - Installing collected packages: mozsystemmonitor 09:52:05 INFO - Running setup.py install for mozsystemmonitor 09:52:05 INFO - Successfully installed mozsystemmonitor 09:52:05 INFO - Cleaning up... 09:52:05 INFO - Return code: 0 09:52:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:52: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')]} 09:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:06 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:52:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:06 INFO - 'HOME': '/Users/cltbld', 09:52:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:06 INFO - 'LOGNAME': 'cltbld', 09:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:06 INFO - 'MOZ_NO_REMOTE': '1', 09:52:06 INFO - 'NO_EM_RESTART': '1', 09:52:06 INFO - 'PAGER': '/bin/cat', 09:52:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:06 INFO - 'PWD': '/builds/slave/test', 09:52:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:06 INFO - 'SHELL': '/bin/bash', 09:52:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:06 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:06 INFO - 'USER': 'cltbld', 09:52:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:06 INFO - 'XPC_FLAGS': '0x0', 09:52:06 INFO - 'XPC_SERVICE_NAME': '0', 09:52:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:06 INFO - Downloading/unpacking jsonschema==2.5.1 09:52: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 09:52: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 09:52: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 09:52: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 09:52:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:52:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:52: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 09:52: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 09:52: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 09:52: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 09:52:08 INFO - Downloading functools32-3.2.3-2.tar.gz 09:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:52:09 INFO - warning: no files found matching '*.txt' 09:52:09 INFO - no previously-included directories found matching 'build' 09:52:09 INFO - no previously-included directories found matching 'dist' 09:52:09 INFO - no previously-included directories found matching '.git*' 09:52:09 INFO - Installing collected packages: jsonschema, functools32 09:52:09 INFO - Running setup.py install for functools32 09:52:09 INFO - warning: no files found matching '*.txt' 09:52:09 INFO - no previously-included directories found matching 'build' 09:52:09 INFO - no previously-included directories found matching 'dist' 09:52:09 INFO - no previously-included directories found matching '.git*' 09:52:09 INFO - Successfully installed jsonschema functools32 09:52:09 INFO - Cleaning up... 09:52:09 INFO - Return code: 0 09:52:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:09 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:09 INFO - 'HOME': '/Users/cltbld', 09:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:09 INFO - 'LOGNAME': 'cltbld', 09:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:09 INFO - 'MOZ_NO_REMOTE': '1', 09:52:09 INFO - 'NO_EM_RESTART': '1', 09:52:09 INFO - 'PAGER': '/bin/cat', 09:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:09 INFO - 'PWD': '/builds/slave/test', 09:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:09 INFO - 'SHELL': '/bin/bash', 09:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:09 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:09 INFO - 'USER': 'cltbld', 09:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:09 INFO - 'XPC_FLAGS': '0x0', 09:52:09 INFO - 'XPC_SERVICE_NAME': '0', 09:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:52:09 INFO - Cleaning up... 09:52:09 INFO - Return code: 0 09:52:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:09 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:52:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:09 INFO - 'HOME': '/Users/cltbld', 09:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:09 INFO - 'LOGNAME': 'cltbld', 09:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:09 INFO - 'MOZ_NO_REMOTE': '1', 09:52:09 INFO - 'NO_EM_RESTART': '1', 09:52:09 INFO - 'PAGER': '/bin/cat', 09:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:09 INFO - 'PWD': '/builds/slave/test', 09:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:09 INFO - 'SHELL': '/bin/bash', 09:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:09 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:09 INFO - 'USER': 'cltbld', 09:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:09 INFO - 'XPC_FLAGS': '0x0', 09:52:09 INFO - 'XPC_SERVICE_NAME': '0', 09:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:09 INFO - Downloading/unpacking blobuploader==1.2.4 09:52: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 09:52: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 09:52: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 09:52: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 09:52:11 INFO - Downloading blobuploader-1.2.4.tar.gz 09:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:52:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:52:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:12 INFO - Downloading docopt-0.6.1.tar.gz 09:52:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:52:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:52:12 INFO - Running setup.py install for blobuploader 09:52:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:52:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:52:12 INFO - Running setup.py install for requests 09:52:12 INFO - Running setup.py install for docopt 09:52:12 INFO - Successfully installed blobuploader requests docopt 09:52:12 INFO - Cleaning up... 09:52:13 INFO - Return code: 0 09:52:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09: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')]} 09:52:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09: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')]} 09:52:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:13 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:52:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:52:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:13 INFO - 'HOME': '/Users/cltbld', 09:52:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:13 INFO - 'LOGNAME': 'cltbld', 09:52:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:13 INFO - 'MOZ_NO_REMOTE': '1', 09:52:13 INFO - 'NO_EM_RESTART': '1', 09:52:13 INFO - 'PAGER': '/bin/cat', 09:52:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:13 INFO - 'PWD': '/builds/slave/test', 09:52:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:13 INFO - 'SHELL': '/bin/bash', 09:52:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:13 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:13 INFO - 'USER': 'cltbld', 09:52:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:13 INFO - 'XPC_FLAGS': '0x0', 09:52:13 INFO - 'XPC_SERVICE_NAME': '0', 09:52:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:52:13 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-LlGs74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:52:13 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-BmQ_2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:52:13 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-R3elmX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:52:13 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-oIES9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:52:13 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-fdMyKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:52:13 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-m1FO_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:52:13 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-5LzLy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:52:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:52:13 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-K4wHe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WUwVXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-JCfmL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Vq7m3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-qN1W5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-5Wb9HT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UHqB9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-qUdIju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-FAH9ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:52:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:52:14 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Hzo3G0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:52:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:52:15 INFO - Running setup.py install for manifestparser 09:52:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:52:15 INFO - Running setup.py install for mozcrash 09:52:15 INFO - Running setup.py install for mozdebug 09:52:15 INFO - Running setup.py install for mozdevice 09:52:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:52:15 INFO - Running setup.py install for mozfile 09:52:15 INFO - Running setup.py install for mozhttpd 09:52:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:52:15 INFO - Running setup.py install for mozinfo 09:52:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:52:16 INFO - Running setup.py install for mozInstall 09:52:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:52:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:52:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:52:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:52:16 INFO - Running setup.py install for mozleak 09:52:16 INFO - Running setup.py install for mozlog 09:52:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:52:16 INFO - Running setup.py install for moznetwork 09:52:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:52:16 INFO - Running setup.py install for mozprocess 09:52:16 INFO - Running setup.py install for mozprofile 09:52:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:52:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:52:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:52:17 INFO - Running setup.py install for mozrunner 09:52:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:52:17 INFO - Running setup.py install for mozscreenshot 09:52:17 INFO - Running setup.py install for moztest 09:52:17 INFO - Running setup.py install for mozversion 09:52:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:52:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:52:17 INFO - Cleaning up... 09:52:17 INFO - Return code: 0 09:52:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:17 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:52:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:17 INFO - 'HOME': '/Users/cltbld', 09:52:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:17 INFO - 'LOGNAME': 'cltbld', 09:52:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:17 INFO - 'MOZ_NO_REMOTE': '1', 09:52:17 INFO - 'NO_EM_RESTART': '1', 09:52:17 INFO - 'PAGER': '/bin/cat', 09:52:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:17 INFO - 'PWD': '/builds/slave/test', 09:52:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:17 INFO - 'SHELL': '/bin/bash', 09:52:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:17 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:17 INFO - 'USER': 'cltbld', 09:52:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:17 INFO - 'XPC_FLAGS': '0x0', 09:52:17 INFO - 'XPC_SERVICE_NAME': '0', 09:52:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:52:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-92avFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-nsrl_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-jQ6hmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-SBennC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-NDm_cK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-323l9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Yyq_VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WmfWtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-rVjMdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:52:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:52:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:52:18 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-K41K74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:52:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-CMRSA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:52:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Y1qTEB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:52:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ncWBdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:52:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-QLidd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-s9ZQsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:52:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-oHyQvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:52:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:52:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4BF9do-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:52:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:21 INFO - Downloading blessings-1.6.tar.gz 09:52:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:52:21 INFO - Installing collected packages: blessings 09:52:21 INFO - Running setup.py install for blessings 09:52:21 INFO - Successfully installed blessings 09:52:21 INFO - Cleaning up... 09:52:21 INFO - Return code: 0 09:52:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:52:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:52:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:52:21 INFO - Reading from file tmpfile_stdout 09:52:21 INFO - Current package versions: 09:52:21 INFO - blessings == 1.6 09:52:21 INFO - blobuploader == 1.2.4 09:52:21 INFO - docopt == 0.6.1 09:52:21 INFO - functools32 == 3.2.3-2 09:52:21 INFO - jsonschema == 2.5.1 09:52:21 INFO - manifestparser == 1.1 09:52:21 INFO - mozInstall == 1.12 09:52:21 INFO - mozcrash == 1.0 09:52:21 INFO - mozdebug == 0.1 09:52:21 INFO - mozdevice == 0.50 09:52:21 INFO - mozfile == 1.2 09:52:21 INFO - mozhttpd == 0.7 09:52:21 INFO - mozinfo == 0.9 09:52:21 INFO - mozleak == 0.1 09:52:21 INFO - mozlog == 3.4 09:52:21 INFO - moznetwork == 0.27 09:52:21 INFO - mozprocess == 0.25 09:52:21 INFO - mozprofile == 0.28 09:52:21 INFO - mozrunner == 6.13 09:52:21 INFO - mozscreenshot == 0.1 09:52:21 INFO - mozsystemmonitor == 0.3 09:52:21 INFO - moztest == 0.8 09:52:21 INFO - mozversion == 1.4 09:52:21 INFO - psutil == 3.1.1 09:52:21 INFO - requests == 1.2.3 09:52:21 INFO - wsgiref == 0.1.2 09:52:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:52:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:21 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 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:52:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:52:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:21 INFO - 'HOME': '/Users/cltbld', 09:52:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:21 INFO - 'LOGNAME': 'cltbld', 09:52:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:21 INFO - 'MOZ_NO_REMOTE': '1', 09:52:21 INFO - 'NO_EM_RESTART': '1', 09:52:21 INFO - 'PAGER': '/bin/cat', 09:52:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:21 INFO - 'PWD': '/builds/slave/test', 09:52:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:21 INFO - 'SHELL': '/bin/bash', 09:52:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:21 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:21 INFO - 'USER': 'cltbld', 09:52:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:21 INFO - 'XPC_FLAGS': '0x0', 09:52:21 INFO - 'XPC_SERVICE_NAME': '0', 09:52:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:52:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:52:22 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:52:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:52:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:52:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:52:23 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)) 09:52:23 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)) 09:52:23 INFO - Installing collected packages: marionette-driver 09:52:23 INFO - Running setup.py install for marionette-driver 09:52:23 INFO - Successfully installed marionette-driver 09:52:23 INFO - Cleaning up... 09:52:23 INFO - Return code: 0 09:52:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:52:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:52:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d89bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d89f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdacac6a100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, '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.1a7K1OWlZv/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.fab3bF7zb1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:52:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:52:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:52:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:23 INFO - 'HOME': '/Users/cltbld', 09:52:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:23 INFO - 'LOGNAME': 'cltbld', 09:52:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:23 INFO - 'MOZ_NO_REMOTE': '1', 09:52:23 INFO - 'NO_EM_RESTART': '1', 09:52:23 INFO - 'PAGER': '/bin/cat', 09:52:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:23 INFO - 'PWD': '/builds/slave/test', 09:52:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:23 INFO - 'SHELL': '/bin/bash', 09:52:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:23 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:23 INFO - 'USER': 'cltbld', 09:52:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:23 INFO - 'XPC_FLAGS': '0x0', 09:52:23 INFO - 'XPC_SERVICE_NAME': '0', 09:52:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:52:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:52:24 INFO - Cleaning up... 09:52:24 INFO - Return code: 0 09:52:24 INFO - Running post-action listener: _resource_record_post_action 09:52:24 INFO - Running post-action listener: _start_resource_monitoring 09:52:24 INFO - Starting resource monitoring. 09:52:24 INFO - [mozharness: 2017-05-24 16:52:24.065500Z] Finished create-virtualenv step (success) 09:52:24 INFO - [mozharness: 2017-05-24 16:52:24.066304Z] Running install step. 09:52:24 INFO - Running pre-action listener: _resource_record_pre_action 09:52:24 INFO - Running main action method: install 09:52:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:52:24 INFO - Reading from file tmpfile_stdout 09:52:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:52:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:52:24 INFO - Reading from file tmpfile_stdout 09:52:24 INFO - Output received: 09:52:24 INFO - Usage: mozinstall [options] installer 09:52:24 INFO - Options: 09:52:24 INFO - -h, --help show this help message and exit 09:52:24 INFO - -d DEST, --destination=DEST 09:52:24 INFO - Directory to install application into. [default: 09:52:24 INFO - "/builds/slave/test"] 09:52:24 INFO - --app=APP Application being installed. [default: firefox] 09:52:24 INFO - mkdir: /builds/slave/test/build/application 09:52:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:52:45 INFO - Reading from file tmpfile_stdout 09:52:45 INFO - Output received: 09:52:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:52:45 INFO - Running post-action listener: _resource_record_post_action 09:52:45 INFO - [mozharness: 2017-05-24 16:52:45.697406Z] Finished install step (success) 09:52:45 INFO - [mozharness: 2017-05-24 16:52:45.697569Z] Running run-tests step. 09:52:45 INFO - Running pre-action listener: _resource_record_pre_action 09:52:45 INFO - Running main action method: run_tests 09:52:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:52:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:52:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:52:45 INFO - 2017-05-24 09:52:45.712 screenresolution[1884:8808] starting screenresolution argv=screenresolution get 09:52:45 INFO - 2017-05-24 09:52:45.714 screenresolution[1884:8808] Display 0: 1600x1200x32@60 09:52:45 INFO - 2017-05-24 09:52:45.720 screenresolution[1885:8810] starting screenresolution argv=screenresolution list 09:52:45 INFO - Available Modes on Display 0 09:52:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:52:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:52:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:52:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:52:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:52:45 INFO - Intel Iris: 09:52:45 INFO - Chipset Model: Intel Iris 09:52:45 INFO - Type: GPU 09:52:45 INFO - Bus: Built-In 09:52:45 INFO - VRAM (Dynamic, Max): 1536 MB 09:52:45 INFO - Vendor: Intel (0x8086) 09:52:45 INFO - Device ID: 0x0a2e 09:52:45 INFO - Revision ID: 0x0009 09:52:45 INFO - Displays: 09:52:45 INFO - Display: 09:52:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:52:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:52:45 INFO - Display Serial Number: 1600x1200 60 09:52:45 INFO - Main Display: Yes 09:52:45 INFO - Mirror: Off 09:52:45 INFO - Online: Yes 09:52:45 INFO - Rotation: Supported 09:52:45 INFO - Return code: 0 09:52:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:52:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:52:45 INFO - Python 2.7.3 09:52:45 INFO - Return code: 0 09:52:45 INFO - grabbing minidump binary from tooltool 09:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:52:45 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 0x10d5abd30>, '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 0x10d55fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d5a0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:52:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:52:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:52:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:52:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:52:46 INFO - Return code: 0 09:52:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:52:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:52:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:52:46 INFO - ENV: RUST_BACKTRACE is now 1 09:52:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:52:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:52:46 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/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:52:46 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/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0111 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:52:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1a7K1OWlZv/Render', 09:52:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:52:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:52:46 INFO - 'HOME': '/Users/cltbld', 09:52:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:52:46 INFO - 'LOGNAME': 'cltbld', 09:52:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:52:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:52:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:52:46 INFO - 'MOZ_NO_REMOTE': '1', 09:52:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:52:46 INFO - 'NO_EM_RESTART': '1', 09:52:46 INFO - 'PAGER': '/bin/cat', 09:52:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:52:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:52:46 INFO - 'PWD': '/builds/slave/test', 09:52:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:52:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:52:46 INFO - 'RUST_BACKTRACE': '1', 09:52:46 INFO - 'SHELL': '/bin/bash', 09:52:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fab3bF7zb1/Listeners', 09:52:46 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 09:52:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:52:46 INFO - 'USER': 'cltbld', 09:52:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:52:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:52:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:52:46 INFO - 'XPC_FLAGS': '0x0', 09:52:46 INFO - 'XPC_SERVICE_NAME': '0', 09:52:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:52:46 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/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:52:46 INFO - mozversion application_buildid: 20170524081235 09:52:46 INFO - mozversion application_changeset: e84acaf49a8f5032864a2602c1c61b68ceb1b773 09:52:46 INFO - mozversion application_display_name: Nightly 09:52:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:52:46 INFO - mozversion application_name: Firefox 09:52:46 INFO - mozversion application_remotingname: firefox 09:52:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:52:46 INFO - mozversion application_vendor: Mozilla 09:52:46 INFO - mozversion application_version: 55.0a1 09:52:46 INFO - mozversion platform_buildid: 20170524081235 09:52:46 INFO - mozversion platform_changeset: e84acaf49a8f5032864a2602c1c61b68ceb1b773 09:52:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:52:46 INFO - mozversion platform_version: 55.0a1 09:52:46 INFO - using testdate: 1495644766 09:52:46 INFO - actual date: 1495644766 09:52:46 INFO - starting webserver on 'localhost:49347' 09:52:46 INFO - SUITE-START | Running 2 tests 09:52:46 INFO - TEST-START | dromaeo_css 09:52:46 INFO - operating with platform_type : mac_ 09:52:46 INFO - Initialising browser for dromaeo_css test... 09:52:46 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49347/getInfo.html -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpDhDKm7/profile 09:52:46 INFO - TEST-INFO | started process 1895 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49347/getInfo.html -foreground) 09:53:19 INFO - TEST-INFO | 1895: killed by SIGTERM 09:53:19 INFO - Browser initialized. 09:53:19 INFO - Running cycle 1/1 for dromaeo_css test... 09:53:19 INFO - TEST-INFO | started process 1902 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpDhDKm7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:22 INFO - PID 1902 | 2017-05-24 09:53:22.295 plugin-container[1903:9443] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 09:53:22 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:22 INFO - PID 1902 | 2017-05-24 09:53:22.295 plugin-container[1903:9443] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 09:53:22 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:22 INFO - PID 1902 | 2017-05-24 09:53:22.295 plugin-container[1903:9443] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:22 INFO - PID 1902 | 2017-05-24 09:53:22.596 plugin-container[1904:9487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 09:53:22 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:22 INFO - PID 1902 | 2017-05-24 09:53:22.597 plugin-container[1904:9487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4823, name = 'com.apple.CFPasteboardClient' 09:53:22 INFO - PID 1902 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:22 INFO - PID 1902 | 2017-05-24 09:53:22.597 plugin-container[1904:9487] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:32 INFO - PID 1902 | RSS: Main: 246439936 09:53:32 INFO - PID 1902 | 09:56:35 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49347/tests/dromaeo/cssquery-ext.html) 09:56:37 INFO - PID 1902 | RSS: Main: 263995392 09:56:37 INFO - PID 1902 | 09:59:38 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-ext.html (next: http://localhost:49347/tests/dromaeo/cssquery-jquery.html) 09:59:39 INFO - PID 1902 | RSS: Main: 277762048 09:59:39 INFO - PID 1902 | 10:02:41 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49347/tests/dromaeo/cssquery-mootools.html) 10:02:42 INFO - PID 1902 | RSS: Main: 278384640 10:02:42 INFO - PID 1902 | 10:05:43 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49347/tests/dromaeo/cssquery-prototype.html) 10:05:44 INFO - PID 1902 | RSS: Main: 282882048 10:05:44 INFO - PID 1902 | 10:08:47 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49347/tests/dromaeo/cssquery-yui.html) 10:08:47 INFO - PID 1902 | RSS: Main: 283041792 10:08:47 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | Cycle 1(1): loaded http://localhost:49347/tests/dromaeo/cssquery-yui.html (next: http://localhost:49347/tests/dromaeo/cssquery-dojo.html) 10:11:49 INFO - PID 1902 | RSS: Main: 282984448 10:11:49 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | __start_tp_report 10:11:49 INFO - PID 1902 | _x_x_mozilla_page_load 10:11:49 INFO - PID 1902 | _x_x_mozilla_page_load_details 10:11:49 INFO - PID 1902 | |i|pagename|runs| 10:11:49 INFO - PID 1902 | |0;dojo.html;3618.1678214010776;3641.0351256916906;3653.415453527436;3668.8715894943684;3671.779693218407;6202;6223.1287619733275;6267.341929097447;6274.623522588648;6324.2727086385075;6260.9373906261035;6263.467605253548;6272.2159730033745;6297.496200303976;6357.872842543158;5437.945620543804;5504.284443022401;5532.723363831812;5548.528375088123;5563;5568.582356323272;5640.351359593658;5661;5686.488216060554;5713.485786279234;5428.4028756836715;5530.419305972855;5568.387477377485;5614.9719251403685;5640.097584386499;5473.534749546293;5526.585506087019;5530.474604912527;5555.444455554452;5555.472230138171;4658.813647454098;5501.229827824108;5504.4495550444635;5535;5550.111982082847;5392.676439413648;6317.147185129995;6322.114903913456;6328;6336.112944187817;6602.438792702626;6645.3354664533645;6705.195376554844;6769.289224631441;6779.186497620317;6928.376446119873;6950.026996220533;6963.338482844122;6971.163460384735;6981.441484681213;6401.583897046677;6404.743810247538;6433.485321174295;6437.2918978912285;6439.678016099223;6837.589744615339;6933.895991560131;6959.164900211906;7011.263817299212;7038.472114591324;6446.323136070739;6490.091387205701;6586.077949087132;6657.20113586363;6737.629430381376;6814.727410903607;6889.104416425933;6904.689288982008;6916;6916.757913473004;5552.694601796859;5709.74306156224;6079.088136779518;6760.763373281911;6785.59286442815;5810.273715785527;6229.657368844757;6623.536352455282;6664.900026499607;6743.190817101881;5367.543758780469;5449.509544141047;5456.8090116846515;5464.23500709901;5487.807926722546;4861.416630004422;4880.1215781159735;4885.145099607555;4895.241237608177;4925.088858561177;6120.714649923477;6309.684515774193;6524.836879078061;6534.7386104556235;6565.770198043141;6218.689065546704;6862.4853136015;6894.13823272091;6908;6927.203371612229;5424.159255315353;6509.674516274167;6511.34886511345;6534.95440729481;6562.606243625398;5064.974675126601;5619.43805619443;6115.296740874767;6127.877442451126;6146.754129834846;6893.345132212432;6947.3747362737595;7034.718611255493;7040.683169257498;7073.646317684198;6815.93184068153;6950.235474097744;6954.79135625932;6973.372396484341;6983.650817459208;6738.258791533026;7032.964835175791;7066.964665176642;7068.71725130989;7119.964400177966;5651.802186923438;6506.349365063646;6567.9014814778775;6730.865382692221;6773.051772751917;6877.415419689182;6893.345132212432;6894.689738961659;6921.515493915377;6927.58434493932;6462.741490340334;6549.181352330958;6593.109930159367;6629.303923088007;6662.56693314933;6520.706568204348;6543.803685889431;6639.867202655823;6647.867042659023;6664.566803157779;6376.330485298977;6423.582467139621;6526.282108968105;6535.248446428532;6547;6564.376384243489;6569.868602627825;6645.169353830771;6672.132622759011;6694.397504224643;6517.217933847968;6549.639769812706;6559.8032059038305;6562.901556476752;6590.868182636224 10:11:49 INFO - PID 1902 | |1;ext.html;780.6682160081967;798.9480683755555;811.2941740685602;1194.844670192875;1250.5998080614206;36971.89084327471;37919.24161516768;38017.23965520688;38057.238855222946;38093.80953095238;39245.41131883024;39461.80269098651;39534.60465395345;39554;39593.802030989806;6909.447244220451;7615.923840761605;7698.345640620554;8305.12796156404;8315.376346773986;8178.550179740112;8193.631286592117;8276.710315138971;8287.875681864776;8298.004239491269;8247.505149691038;8256.917430825704;8308.501489910625;8311.085780564134;8317.625706843177;8247.958760206191;8264.173582641717;8271.379646526504;8283.213094755989;8297.25324720772;6859.759908403182;7986;8018.558979256139;8055.355571554262;8099.06860711014;36828.81585592082;36934.630653693384;37032.814835925645;37117.81441092777;37119;3588.5155504006884;3595.7123430125525;3627;3630.909227269313;3634.4366623173187;26570.202893913214;29432;30839.845800771076;30849.84575077133;30948;3016.8491575421363;3056.816591004547;3063.3107550801114;3072.4930386486426;3073.784835061547;247001;249540;276824.99999999796;302151.4892425524;302880;48462.75768621134;48650.75674621675;48934.02131957341;48993;49036.26445603319;49085.509144908094;50926.23610645843;50940.235896461585;50958.74520627447;51008;14161.787573186204;14174.22041787691;14325.57023289303;14419.35112919921;14437.494687685881;11610.419479026015;11672.88327116718;11673.88326116745;11686.590969316034;11712.824307635392;11890.167688261912;13024.609261722164;13676.589702308947;13783.862161378458;13852.792208116887;6176.536759742948;6182.443580077815;6206.131141640174;6208.875822483525;6249.500039996789;999.1307562420741;1022.7340891368298;1032.2980373346197;1054.214610115469;1055.4458909072646;20252;24145.517089658108;24145.637815432783;24147.879260603935;24215.636765448533;1123.1520202247414;1125.5441546173813;1157.988420115805;1159.6173262823418;1160.5067846165346;11792.70518237051;12321.568745093879;12387.566435174725;12402.193857399421;12478.75042499145;281392;333054;334613;334667;334744;43562.782186088865;52618.73690631522;53159.20261196085;53195.73402132965;53373.7331313341;1490.4857824050684;1501.5120085972321;1529.5717199184246;1533.2103966457285;1538.100211376335;23607.937642806457;23737.762622374245;23793.881030594737;23836.284911452683;23842.284731458083;37570.8121459391;37639;37794.81102594469;37809.432858507695;37832.810835945646;274253.62873186305;274564;275053;275276;275450;34035;35018.29678126032;35092.64907350996;35187.47218791669;35595.288094237454;43792.56207438013;44189;44485.33272000858;44535;44590.554094459934;25658.61512077358;25689.614655779806;28209.858950705937;28645.856770716015;30577.388452230385;30036.549451757804;30115.69884301129;30736.846315768278;30919.690803091682;31098.533521996735;36148.27703445969;36232.09419764527;36720.63279367279;36790.8160459196;36902.63097368992;28954.710452896044;29003;30268.54597181088;30835.84582077075;30890.53664194994;21857.174994375604;23567.882160589088;23863.52272954566;23928.28215153548;24131.638025429984 10:11:49 INFO - PID 1902 | |2;jquery.html;432.0408692702202;434.5372178629761;442.17534298533235;453.60536333389933;455.8541266794625;19463.708044379327;21425.179334649678;22122.88938555309;22779.658305125395;22843.08627654896;19882.502937426572;22602.77397226027;22638;23446.944887480062;23635.5182705816;5965.403459654021;6272.3100458949475;6281.811545653615;6294.0873573332065;6301.369863013708;6009.75960961561;6185.876282474348;6187.1956645636;6241.21984751906;6295.716692748837;6195.132681424581;6195.473384762301;6199.659018753967;6207.813765587017;6219.813405597839;5988.251468566429;6141.07883817431;6149.969250153743;6164.7534098636;6202.627842329474;5511.559075273968;5553.583481238923;5556.111022236463;5572.359178694421;5574.442555744434;26504.86747566269;27491.17526474209;27771;27793.888244470196;27849.721502784916;2133.679948007811;2179.3897708641607;2184.8798316092607;2189.2447105748493;2191.7808219177955;13833.515826946008;17544.0350780707;17578.91210543939;17632.002559564906;18011.639767204582;2130.3289463818846;2148.387709502784;2172.4894649757293;2173.4349069241953;2198.3844523533435;126820.73179268182;148517;149259.25370373187;150965;159444;11065.170112241614;12272;12550.121491495507;12560.81158782619;12593.99248060153;15584.532464026097;16464.670706585803;16516.42192523256;16526.925749826223;16549.50351489457;8873.64505419776;9042.909570904338;9052.456852588866;9098.545072746336;9134.26925845944;8611.784705382415;8627.552700100801;8640.956795216109;8750.16873397039;8845.4250473719;6730.8653826924165;7822.921770782333;7985.600719963979;7997.7600671979935;8061.387722455533;1858.821415379689;2189.189999700121;2192.48476607997;2200.053976789996;2330.6154484510207;844.5819319436918;980.4019448299226;989.8465737810651;1017.577705252312;1018.6892997635643;20776.58446831055;21068.051937662844;21132.683009754866;21327.573448531253;21473.892630536746;982.4694664881027;996.3175224970918;1015.6445244164483;1020.2654089055868;1022.3967858963099;8099.311558517592;8640.956795215983;10322.896771032194;10659.467026648637;10737.731556711145;529655;538054;543481;543964;546175;85313;107669.46165269123;118253.40873295751;123742;135909.32045339906;1145.8109411946934;1158.5713286084203;1187.4181650991125;1190.9404529773476;1195.396324855942;17272.740908886124;21216.893915530323;22213.444663883532;23393.649095263925;23497.295081147593;44005.55994440143;58856.11715824352;58863.41136588751;58919.52701182505;59149.70425147847;79331.20668793238;79982;80442.59778701069;80750.59624701839;81228.5938570327;7140.821479463055;8859.822803543764;8865.867011994955;8955.507447090473;9106.544672766202;12849.164804287691;13935.58193254204;14096.224707641179;14339.641508961942;14605.269736512919;11718.765624687629;11847.289162650268;11993.820092698443;12052.156349055482;12223.022158227499;10559.366438013743;10753.569857205624;10826.296290741077;11063.170262230204;11119.444027798416;11936.522539098687;12047;12095;12167.574134905235;12307.446164922421;7582.696692132253;7904.960475197588;8923.955380223057;8969.192772650493;9234.584443700183;11850.051995840484;11918.821217681569;12102.09234307448;12263;12292.877071229173 10:11:49 INFO - PID 1902 | |3;mootools.html;1353.3774463746677;1400.0759498730833;1415.0872687116803;1421.7796241582553;1422.4168091082654;11692.240004399719;11768.646940591796;11960.222585531934;12200.450979705913;12221.1445198836;11606.709832254199;11852.229605075665;11856.644300670972;11862.457880475526;11889.643310700692;2516.4338023944647;2562.461883004571;2572.305477521068;2578.2909699832603;2582.122078493312;2498.4253621667026;2535.378832186117;2554.1443616388533;2557.0666706652123;2560.3215147985707;2503.8748062596887;2539.8857051432637;2539.9238022859345;2542.415244493758;2544.402065514603;2472.678551788274;2500.274920273119;2514.8365356251784;2516;2524.469861329123;2484.527939691453;2500.749925007503;2512.133314006665;2585.327814768155;2587.8964841406323;11566.190366674253;11624.651260462115;11713.824292635618;11773.940345368872;11777.88222117785;5503.449655034505;6452.419282264572;6698.631575263358;6723.899141512881;6726.0919775830125;4053.0677944073027;4209.326507758744;4245.936310955307;4269.060806622538;4279.828806847722;1204.3496511883573;1219.9691260884626;1248.2136254159911;1248.6316536621719;1272.41468924294;15102.39590416382;15534.84465155334;16301.836981630151;16571.42000029993;16585.83414165855;5969.880602387928;5981.820545383645;6102.328743838174;6168.1056246843955;6246.15676883624;13979.231142287272;13995.930020349833;13996.580102596938;14008.229547374789;14015.439382424589;6645.700943457556;6668.33316668339;6713.69788359525;6726.966365168143;6741.494387920926;5960.612560183574;6158.230221222348;6177.722002509898;6192.473639740582;6236.438720515175;4666.2767271073035;4704.717716936926;4784.378030855966;4853.757312134379;4865.0756356292195;1425.600831767107;1466.6406730351132;1466.9779953300495;1474.9631259218606;1478.1796103162633;793.797581616684;804.6097642643314;822.8066404394963;824.9133840946735;848.2874385516196;5349.705766182819;6206.348333424925;6207.379262073757;6300.905486417708;6388.520860935449;586.1852025684302;590.1914377303123;616.9321374648785;617.2716194890073;628.1206311164315;3852.24881148179;4264.850730224489;4439.245328294198;4558.384618076518;4592.88517787058;12426.937865310616;15275.847241527665;15354.309056092503;15355.769663455058;15359.539213823604;14879.776803347959;15097.54707358781;15122;15126.848731512764;15150.545483635287;8168.469049511763;8406.159384061544;8484.363672724447;8570.957145214234;8625.654973800978;6752.426043786333;7093.964530177316;7241.384482319062;7249.347558719741;7283.308085731847;13194.802077968647;13619;13651.317434128452;13665.18008919468;13703.040787144804;13752.381142848795;13915.652108697364;14035.438582456996;14049.22729249901;14055;9250.21373183287;9577.473238971923;9580.856287155837;9585.760355990877;9599.088086631758;9056.954715226382;10702.518386672775;10726.98093681099;10865.185111116856;10880.510377032893;9137.40606860552;9173;9296.256299495888;9327.720168394959;9453.251148537542;8948.776280593038;8966.193042625933;8990.955045224731;9054.366194366332;9062.139096785795;7958.681652733826;8233.094359620318;8305;8409.327253819558;8420.536870472182;5774.220480235114;5900.203472531325;6079.148919151234;6263.6241825490615;6338.239411270677;6161.845953851011;6273.215848018997;6296.779612713533;6321.399467050623;6412.006139048455 10:11:49 INFO - PID 1902 | |4;prototype.html;385.89387918322456;401.3297792686214;402.58332625732373;403.69521011636226;412.4061351653618;5244.947550524494;6185.134291068082;6196.752129914804;6220.688965551722;6228.096925945735;4957.380327459067;5868.823935281937;6037.456628903398;6060.969695151518;6095.329513753494;3978.4231286463446;4592.334111553822;4618.1456430560365;4621.74580398078;4628.930566041512;4181.665466762667;4573.954260457386;4582.9294372770155;4595.988882445873;4610.5389461053965;3578.4076086141677;4131.938020929689;4145.39891715701;4542.136993971135;4559.788502576248;3524.753267271292;4432.689711720181;4569.497355290915;4599.655025873073;4604.3323718060865;3555.5377800885976;3707.870224542155;3863.130795571002;4028.9049694158875;4073.657646853071;5166.3800343958965;5409.783608655649;5791.233673729511;5917.822465326047;5961.731722072517;5477.945220547784;5485.50630443262;5487.259220005288;5490.176473528963;5503.367112782042;6394.168758061469;6503.739850405978;6573.474122070223;6593.406593406617;6602.40578347951;4601.447826260836;4632.72203667781;4642.932125611094;4643.489216186217;4660.254359302496;5470.753816078286;5734.741936612821;6516.478681705452;6745.898811517831;6859.142607174103;4825.276208568743;4902.803887844446;4939.481354457802;4943.28322393251;4952.529509696645;4986.90026199474;5044.0920634286185;5048.823291184791;5056.9494305057215;5059.797608095709;5463.847475918251;5554.750036248379;5606.579506537026;5628.25524087526;5659.547236221092;6628.502862285348;6653.101831252816;6653.334666533308;6677.9666101670155;6707.49693772969;1583.6515966487354;1694.5763559110223;1714.9828501714824;1715.0652894172706;1733.9046352450737;980.9411435313904;1198.2750435986102;1227.613301809945;1228.072805032195;1231.7536492701317;6099;6237.5321850860455;6314.810555683245;6314.8421289467215;6346.555741098171;5656.91514627281;5934.910976335358;6560.311167327457;6815.420689241371;6844.315568443216;5236.24074509194;5835.883282334329;6019.548533859978;6232.096346029891;6281.057841323838;3401.6768407001296;3520.1727594015388;3551.5027896094566;3575.3028159508644;3591.9281614367565;6219.906701399483;6687.665616719145;6843.692033858489;6944.784662684111;6978.79063628092;7007.913773365137;7063.152421709325;7083.433325334064;7092.255313192144;7092.858142837114;6813.965930170318;6980.650967451607;7069.515401765584;7072.92927070733;7107.680154392961;6765.9363894012795;6792.083068785848;6831.9316806833285;6846.760363387258;6882.139732533434;5322.574194064388;6363.809085727436;6718.260991291052;6719.62247739222;6750.459963202834;6495.837604059936;7001.264867188872;7040;7073.575585464889;7096.893546596909;4218.725782824106;4435.824506505714;4766.45185803637;4861.1006963711825;4931.334269873521;5017.724025178651;6441;6545.37818907203;6862.529489383917;6871.690773915086;5690.886182276414;6260.744412905173;6584.50616203777;6604;6610.2398224204835;5728.484436400744;5791.407362975216;6163.84590385244;6262.060690896402;6443.935560644333;6422.871542569029;6452.548321617442;6459.935400646122;6471.967640161769;6474.449671777952;5125.538701516882;5984.401559843981;6024.036154215304;6366.586171898812;6584.572002819801;5623.606347555632;6403.716933529763;6413.16628838248;6416.165898433173;6463.773767918288 10:11:49 INFO - PID 1902 | |5;yui.html;744.7467860927285;785.2461636828648;816.889719887815;848.6562942008485;864.1617630898031;14585.124892506457;15121.84878151218;16680.83234173607;17215.91392043038;17270.48188554344;17317.39389121378;17881.7317740234;18084.457466276;18122.728159077626;18180.272789088453;933.9813203735921;973.8149751547219;977.2475194100539;991.7520619845038;993.9204863610894;984.2224642532391;994.4132961552682;995.004995004995;997.5211898288826;1000.3247807729754;938.9483578403185;993.940363578184;995.5569771451707;997.8652881860964;1013.1843865688118;967.0571193086754;982.130814229405;985.1527686189871;987.1115995603978;1039.153090231459;714.7998560403096;719.3597698048741;720.9870132463897;730.1821959405447;758.215247219131;16809.747853782203;17233.482995510156;17409.390671326444;17411.95528268308;17471.301147954066;17221.913890430595;17229.396971106078;17335.04657243851;17754.822451775446;17776.91111544434;14122;14477.131372117601;14490.782638260434;14527.418903243857;14535.927320363437;3577.678008979204;3578.1054736315923;3603.7837729736298;3605.2248766515063;3606.170580766412;138039;139898;140708.2964585191;143625;159744;13718.628137186202;13942.790858137136;14065.648358791112;14078.929605352112;14410.991230613756;20614;25345.73271336463;26457.07400241021;26836;26840.86579567129;6822.761203357859;7043.36609705129;7262.092238470192;8001.599920004093;8125.8374832504205;3971.245463361952;4075.9592404076175;4080.6327430531396;4083.816228269735;4085.1421201547414;4159.854405095807;4196.118815048813;4225.535191128974;4241.024564350217;4257.6168144867115;921.3780698028804;926.1849572376266;935.4948327902856;939.0609390609391;940.1068984464786;551.8565173054955;647.1942431672597;668.7826456401689;671.5701950751522;690.6926417744056;9600.375975561567;11097.83353249702;12215.375572237685;12439.06706996979;12490.562830301078;619.495111484148;651.7490766055008;655.7999810057883;672.9798106056826;674.2347435660463;5671.546276297886;5790.9286781945475;5800.100984347433;5813.505852002542;5847.44449277318;127212.36393817971;152173;154063;155282;155574.2221288909;115098;117012;117085.41457292659;118842.26826195826;119223.40388298003;68233.65883170553;81455.18544814477;103471;105284.47357763162;114113;11680.824787628022;12270.386480676108;12337.93831030875;13097;13098.2141071536;41821.58178418177;45462.31806522971;46107.76946115248;46374.53625463837;46522.53477465318;85285;86139;88023.6796448041;88286.67569986326;89787;17257.223424946158;17304.394346197823;17753;18005.819941800415;18078.45764627063;18726.344577939708;18789;19050.809491905457;19496;19657.803421966168;15517.5344739658;17561.385351512636;17861.642767144844;18032.729509057113;18153.81846181521;17181.57046073858;17486.1045337854;17492.387766428114;17823.01973391476;17890.731639025686;16944.915275423544;17475.038872862115;17547.56131096733;17629.382971596446;17686.469405917844;11787.705307367041;11905.583304584645;12309.015278777855;12427.751444970869;12546.749065018466;13091.214527128403;13433;13478.258695771827;13497.32513374347;13527 10:11:49 INFO - PID 1902 | __end_tp_report 10:11:49 INFO - PID 1902 | __start_cc_report 10:11:49 INFO - PID 1902 | _x_x_mozilla_cycle_collect,401 10:11:49 INFO - PID 1902 | __end_cc_report 10:11:49 INFO - PID 1902 | __startTimestamp1495645909883__endTimestamp 10:11:49 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | ------- Summary: start ------- 10:11:49 INFO - PID 1902 | Number of tests: 6 10:11:49 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | [#0] dojo.html Cycles:165 Average:6236.83 Median:6511.35 stddev:744.91 (11.4%) stddev-sans-first:718.31 10:11:49 INFO - PID 1902 | Values: 3618.2 3641.0 3653.4 3668.9 3671.8 6202.0 6223.1 6267.3 6274.6 6324.3 6260.9 6263.5 6272.2 6297.5 6357.9 5437.9 5504.3 5532.7 5548.5 5563.0 5568.6 5640.4 5661.0 5686.5 5713.5 5428.4 5530.4 5568.4 5615.0 5640.1 5473.5 5526.6 5530.5 5555.4 5555.5 4658.8 5501.2 5504.4 5535.0 5550.1 5392.7 6317.1 6322.1 6328.0 6336.1 6602.4 6645.3 6705.2 6769.3 6779.2 6928.4 6950.0 6963.3 6971.2 6981.4 6401.6 6404.7 6433.5 6437.3 6439.7 6837.6 6933.9 6959.2 7011.3 7038.5 6446.3 6490.1 6586.1 6657.2 6737.6 6814.7 6889.1 6904.7 6916.0 6916.8 5552.7 5709.7 6079.1 6760.8 6785.6 5810.3 6229.7 6623.5 6664.9 6743.2 5367.5 5449.5 5456.8 5464.2 5487.8 4861.4 4880.1 4885.1 4895.2 4925.1 6120.7 6309.7 6524.8 6534.7 6565.8 6218.7 6862.5 6894.1 6908.0 6927.2 5424.2 6509.7 6511.3 6535.0 6562.6 5065.0 5619.4 6115.3 6127.9 6146.8 6893.3 6947.4 7034.7 7040.7 7073.6 6815.9 6950.2 6954.8 6973.4 6983.7 6738.3 7033.0 7067.0 7068.7 7120.0 5651.8 6506.3 6567.9 6730.9 6773.1 6877.4 6893.3 6894.7 6921.5 6927.6 6462.7 6549.2 6593.1 6629.3 6662.6 6520.7 6543.8 6639.9 6647.9 6664.6 6376.3 6423.6 6526.3 6535.2 6547.0 6564.4 6569.9 6645.2 6672.1 6694.4 6517.2 6549.6 6559.8 6562.9 6590.9 10:11:49 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | [#1] ext.html Cycles:180 Average:44190.34 Median:184389.50 stddev:76807.89 (41.7%) stddev-sans-first:76954.20 10:11:49 INFO - PID 1902 | Values: 780.7 798.9 811.3 1194.8 1250.6 36971.9 37919.2 38017.2 38057.2 38093.8 39245.4 39461.8 39534.6 39554.0 39593.8 6909.4 7615.9 7698.3 8305.1 8315.4 8178.6 8193.6 8276.7 8287.9 8298.0 8247.5 8256.9 8308.5 8311.1 8317.6 8248.0 8264.2 8271.4 8283.2 8297.3 6859.8 7986.0 8018.6 8055.4 8099.1 36828.8 36934.6 37032.8 37117.8 37119.0 3588.5 3595.7 3627.0 3630.9 3634.4 26570.2 29432.0 30839.8 30849.8 30948.0 3016.8 3056.8 3063.3 3072.5 3073.8 247001.0 249540.0 276825.0 302151.5 302880.0 48462.8 48650.8 48934.0 48993.0 49036.3 49085.5 50926.2 50940.2 50958.7 51008.0 14161.8 14174.2 14325.6 14419.4 14437.5 11610.4 11672.9 11673.9 11686.6 11712.8 11890.2 13024.6 13676.6 13783.9 13852.8 6176.5 6182.4 6206.1 6208.9 6249.5 999.1 1022.7 1032.3 1054.2 1055.4 20252.0 24145.5 24145.6 24147.9 24215.6 1123.2 1125.5 1158.0 1159.6 1160.5 11792.7 12321.6 12387.6 12402.2 12478.8 281392.0 333054.0 334613.0 334667.0 334744.0 43562.8 52618.7 53159.2 53195.7 53373.7 1490.5 1501.5 1529.6 1533.2 1538.1 23607.9 23737.8 23793.9 23836.3 23842.3 37570.8 37639.0 37794.8 37809.4 37832.8 274253.6 274564.0 275053.0 275276.0 275450.0 34035.0 35018.3 35092.6 35187.5 35595.3 43792.6 44189.0 44485.3 44535.0 44590.6 25658.6 25689.6 28209.9 28645.9 30577.4 30036.5 30115.7 30736.8 30919.7 31098.5 36148.3 36232.1 36720.6 36790.8 36902.6 28954.7 29003.0 30268.5 30835.8 30890.5 21857.2 23567.9 23863.5 23928.3 24131.6 10:11:49 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | [#2] jquery.html Cycles:180 Average:34754.60 Median:22811.37 stddev:91217.99 (399.9%) stddev-sans-first:91437.47 10:11:49 INFO - PID 1902 | Values: 432.0 434.5 442.2 453.6 455.9 19463.7 21425.2 22122.9 22779.7 22843.1 19882.5 22602.8 22638.0 23446.9 23635.5 5965.4 6272.3 6281.8 6294.1 6301.4 6009.8 6185.9 6187.2 6241.2 6295.7 6195.1 6195.5 6199.7 6207.8 6219.8 5988.3 6141.1 6150.0 6164.8 6202.6 5511.6 5553.6 5556.1 5572.4 5574.4 26504.9 27491.2 27771.0 27793.9 27849.7 2133.7 2179.4 2184.9 2189.2 2191.8 13833.5 17544.0 17578.9 17632.0 18011.6 2130.3 2148.4 2172.5 2173.4 2198.4 126820.7 148517.0 149259.3 150965.0 159444.0 11065.2 12272.0 12550.1 12560.8 12594.0 15584.5 16464.7 16516.4 16526.9 16549.5 8873.6 9042.9 9052.5 9098.5 9134.3 8611.8 8627.6 8641.0 8750.2 8845.4 6730.9 7822.9 7985.6 7997.8 8061.4 1858.8 2189.2 2192.5 2200.1 2330.6 844.6 980.4 989.8 1017.6 1018.7 20776.6 21068.1 21132.7 21327.6 21473.9 982.5 996.3 1015.6 1020.3 1022.4 8099.3 8641.0 10322.9 10659.5 10737.7 529655.0 538054.0 543481.0 543964.0 546175.0 85313.0 107669.5 118253.4 123742.0 135909.3 1145.8 1158.6 1187.4 1190.9 1195.4 17272.7 21216.9 22213.4 23393.6 23497.3 44005.6 58856.1 58863.4 58919.5 59149.7 79331.2 79982.0 80442.6 80750.6 81228.6 7140.8 8859.8 8865.9 8955.5 9106.5 12849.2 13935.6 14096.2 14339.6 14605.3 11718.8 11847.3 11993.8 12052.2 12223.0 10559.4 10753.6 10826.3 11063.2 11119.4 11936.5 12047.0 12095.0 12167.6 12307.4 7582.7 7905.0 8924.0 8969.2 9234.6 11850.1 11918.8 12102.1 12263.0 12292.9 10:11:49 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | [#3] mootools.html Cycles:180 Average:7230.18 Median:4131.20 stddev:4568.61 (110.6%) stddev-sans-first:4560.08 10:11:49 INFO - PID 1902 | Values: 1353.4 1400.1 1415.1 1421.8 1422.4 11692.2 11768.6 11960.2 12200.5 12221.1 11606.7 11852.2 11856.6 11862.5 11889.6 2516.4 2562.5 2572.3 2578.3 2582.1 2498.4 2535.4 2554.1 2557.1 2560.3 2503.9 2539.9 2539.9 2542.4 2544.4 2472.7 2500.3 2514.8 2516.0 2524.5 2484.5 2500.7 2512.1 2585.3 2587.9 11566.2 11624.7 11713.8 11773.9 11777.9 5503.4 6452.4 6698.6 6723.9 6726.1 4053.1 4209.3 4245.9 4269.1 4279.8 1204.3 1220.0 1248.2 1248.6 1272.4 15102.4 15534.8 16301.8 16571.4 16585.8 5969.9 5981.8 6102.3 6168.1 6246.2 13979.2 13995.9 13996.6 14008.2 14015.4 6645.7 6668.3 6713.7 6727.0 6741.5 5960.6 6158.2 6177.7 6192.5 6236.4 4666.3 4704.7 4784.4 4853.8 4865.1 1425.6 1466.6 1467.0 1475.0 1478.2 793.8 804.6 822.8 824.9 848.3 5349.7 6206.3 6207.4 6300.9 6388.5 586.2 590.2 616.9 617.3 628.1 3852.2 4264.9 4439.2 4558.4 4592.9 12426.9 15275.8 15354.3 15355.8 15359.5 14879.8 15097.5 15122.0 15126.8 15150.5 8168.5 8406.2 8484.4 8571.0 8625.7 6752.4 7094.0 7241.4 7249.3 7283.3 13194.8 13619.0 13651.3 13665.2 13703.0 13752.4 13915.7 14035.4 14049.2 14055.0 9250.2 9577.5 9580.9 9585.8 9599.1 9057.0 10702.5 10727.0 10865.2 10880.5 9137.4 9173.0 9296.3 9327.7 9453.3 8948.8 8966.2 8991.0 9054.4 9062.1 7958.7 8233.1 8305.0 8409.3 8420.5 5774.2 5900.2 6079.1 6263.6 6338.2 6161.8 6273.2 6296.8 6321.4 6412.0 10:11:49 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | [#4] prototype.html Cycles:180 Average:5344.90 Median:5852.35 stddev:1624.80 (27.8%) stddev-sans-first:1586.15 10:11:49 INFO - PID 1902 | Values: 385.9 401.3 402.6 403.7 412.4 5244.9 6185.1 6196.8 6220.7 6228.1 4957.4 5868.8 6037.5 6061.0 6095.3 3978.4 4592.3 4618.1 4621.7 4628.9 4181.7 4574.0 4582.9 4596.0 4610.5 3578.4 4131.9 4145.4 4542.1 4559.8 3524.8 4432.7 4569.5 4599.7 4604.3 3555.5 3707.9 3863.1 4028.9 4073.7 5166.4 5409.8 5791.2 5917.8 5961.7 5477.9 5485.5 5487.3 5490.2 5503.4 6394.2 6503.7 6573.5 6593.4 6602.4 4601.4 4632.7 4642.9 4643.5 4660.3 5470.8 5734.7 6516.5 6745.9 6859.1 4825.3 4902.8 4939.5 4943.3 4952.5 4986.9 5044.1 5048.8 5056.9 5059.8 5463.8 5554.8 5606.6 5628.3 5659.5 6628.5 6653.1 6653.3 6678.0 6707.5 1583.7 1694.6 1715.0 1715.1 1733.9 980.9 1198.3 1227.6 1228.1 1231.8 6099.0 6237.5 6314.8 6314.8 6346.6 5656.9 5934.9 6560.3 6815.4 6844.3 5236.2 5835.9 6019.5 6232.1 6281.1 3401.7 3520.2 3551.5 3575.3 3591.9 6219.9 6687.7 6843.7 6944.8 6978.8 7007.9 7063.2 7083.4 7092.3 7092.9 6814.0 6980.7 7069.5 7072.9 7107.7 6765.9 6792.1 6831.9 6846.8 6882.1 5322.6 6363.8 6718.3 6719.6 6750.5 6495.8 7001.3 7040.0 7073.6 7096.9 4218.7 4435.8 4766.5 4861.1 4931.3 5017.7 6441.0 6545.4 6862.5 6871.7 5690.9 6260.7 6584.5 6604.0 6610.2 5728.5 5791.4 6163.8 6262.1 6443.9 6422.9 6452.5 6459.9 6472.0 6474.4 5125.5 5984.4 6024.0 6366.6 6584.6 5623.6 6403.7 6413.2 6416.2 6463.8 10:11:49 INFO - PID 1902 | 10:11:49 INFO - PID 1902 | [#5] yui.html Cycles:180 Average:25896.66 Median:18757.67 stddev:39682.29 (211.6%) stddev-sans-first:39748.67 10:11:49 INFO - PID 1902 | Values: 744.7 785.2 816.9 848.7 864.2 14585.1 15121.8 16680.8 17215.9 17270.5 17317.4 17881.7 18084.5 18122.7 18180.3 934.0 973.8 977.2 991.8 993.9 984.2 994.4 995.0 997.5 1000.3 938.9 993.9 995.6 997.9 1013.2 967.1 982.1 985.2 987.1 1039.2 714.8 719.4 721.0 730.2 758.2 16809.7 17233.5 17409.4 17412.0 17471.3 17221.9 17229.4 17335.0 17754.8 17776.9 14122.0 14477.1 14490.8 14527.4 14535.9 3577.7 3578.1 3603.8 3605.2 3606.2 138039.0 139898.0 140708.3 143625.0 159744.0 13718.6 13942.8 14065.6 14078.9 14411.0 20614.0 25345.7 26457.1 26836.0 26840.9 6822.8 7043.4 7262.1 8001.6 8125.8 3971.2 4076.0 4080.6 4083.8 4085.1 4159.9 4196.1 4225.5 4241.0 4257.6 921.4 926.2 935.5 939.1 940.1 551.9 647.2 668.8 671.6 690.7 9600.4 11097.8 12215.4 12439.1 12490.6 619.5 651.7 655.8 673.0 674.2 5671.5 5790.9 5800.1 5813.5 5847.4 127212.4 152173.0 154063.0 155282.0 155574.2 115098.0 117012.0 117085.4 118842.3 119223.4 68233.7 81455.2 103471.0 105284.5 114113.0 11680.8 12270.4 12337.9 13097.0 13098.2 41821.6 45462.3 46107.8 46374.5 46522.5 85285.0 86139.0 88023.7 88286.7 89787.0 17257.2 17304.4 17753.0 18005.8 18078.5 18726.3 18789.0 19050.8 19496.0 19657.8 15517.5 17561.4 17861.6 18032.7 18153.8 17181.6 17486.1 17492.4 17823.0 17890.7 16944.9 17475.0 17547.6 17629.4 17686.5 11787.7 11905.6 12309.0 12427.8 12546.7 13091.2 13433.0 13478.3 13497.3 13527.0 10:11:49 INFO - PID 1902 | -------- Summary: end -------- 10:11:49 INFO - PID 1902 | 10:11:50 INFO - TEST-INFO | 1902: exit 0 10:11:50 INFO - TEST-OK | dromaeo_css | took 1144079ms 10:11:50 INFO - TEST-START | kraken 10:11:50 INFO - operating with platform_type : mac_ 10:11:50 INFO - Initialising browser for kraken test... 10:11:50 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49347/getInfo.html -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpPEnu6N/profile 10:11:50 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49347/getInfo.html -foreground) 10:12:23 INFO - TEST-INFO | 1909: killed by SIGTERM 10:12:23 INFO - Browser initialized. 10:12:23 INFO - Running cycle 1/1 for kraken test... 10:12:23 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpPEnu6N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:12:26 INFO - PID 1913 | 2017-05-24 10:12:26.280 plugin-container[1914:11873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9743, name = 'com.apple.tsm.portname' 10:12:26 INFO - PID 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:12:26 INFO - PID 1913 | 2017-05-24 10:12:26.281 plugin-container[1914:11873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 10:12:26 INFO - PID 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:12:26 INFO - PID 1913 | 2017-05-24 10:12:26.281 plugin-container[1914:11873] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:12:26 INFO - PID 1913 | 2017-05-24 10:12:26.651 plugin-container[1915:11916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 10:12:26 INFO - PID 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:12:26 INFO - PID 1913 | 2017-05-24 10:12:26.652 plugin-container[1915:11916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 10:12:26 INFO - PID 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:12:26 INFO - PID 1913 | 2017-05-24 10:12:26.652 plugin-container[1915:11916] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:12:36 INFO - PID 1913 | RSS: Main: 246132736 10:12:36 INFO - PID 1913 | 10:12:38 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-beat-detection) 10:12:39 INFO - PID 1913 | RSS: Main: 260190208 10:12:39 INFO - PID 1913 | 10:12:42 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-dft) 10:12:42 INFO - PID 1913 | RSS: Main: 269197312 10:12:42 INFO - PID 1913 | 10:12:46 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-fft) 10:12:46 INFO - PID 1913 | RSS: Main: 271683584 10:12:46 INFO - PID 1913 | 10:12:48 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49347/tests/kraken/driver.html?testName=audio-oscillator) 10:12:49 INFO - PID 1913 | RSS: Main: 273240064 10:12:49 INFO - PID 1913 | 10:12:51 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:12:51 INFO - PID 1913 | RSS: Main: 273514496 10:12:51 INFO - PID 1913 | 10:12:54 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-darkroom) 10:12:55 INFO - PID 1913 | RSS: Main: 279724032 10:12:55 INFO - PID 1913 | 10:12:58 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49347/tests/kraken/driver.html?testName=imaging-desaturate) 10:12:59 INFO - PID 1913 | RSS: Main: 279740416 10:12:59 INFO - PID 1913 | 10:13:02 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49347/tests/kraken/driver.html?testName=json-parse-financial) 10:13:02 INFO - PID 1913 | RSS: Main: 279941120 10:13:02 INFO - PID 1913 | 10:13:04 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49347/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:13:04 INFO - PID 1913 | RSS: Main: 279678976 10:13:04 INFO - PID 1913 | 10:13:06 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:13:07 INFO - PID 1913 | RSS: Main: 279642112 10:13:07 INFO - PID 1913 | 10:13:09 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:13:10 INFO - PID 1913 | RSS: Main: 279617536 10:13:10 INFO - PID 1913 | 10:13:13 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:13:13 INFO - PID 1913 | RSS: Main: 279646208 10:13:13 INFO - PID 1913 | 10:13:16 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:13:17 INFO - PID 1913 | RSS: Main: 279937024 10:13:17 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | Cycle 1(1): loaded http://localhost:49347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49347/tests/kraken/driver.html?testName=ai-astar) 10:13:19 INFO - PID 1913 | RSS: Main: 281513984 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | __start_tp_report 10:13:19 INFO - PID 1913 | _x_x_mozilla_page_load 10:13:19 INFO - PID 1913 | _x_x_mozilla_page_load_details 10:13:19 INFO - PID 1913 | |i|pagename|runs| 10:13:19 INFO - PID 1913 | |0;ai-astar;103;115;115;115;115;116;116;116;116;114 10:13:19 INFO - PID 1913 | |1;audio-beat-detection;139;113;108;155;106;110;111;109;134;110 10:13:19 INFO - PID 1913 | |2;audio-dft;197;198;196;198;207;218;245;212;278;276 10:13:19 INFO - PID 1913 | |3;audio-fft;83;64;86;72;78;87;50;78;93;48 10:13:19 INFO - PID 1913 | |4;audio-oscillator;90;73;85;73;70;70;87;71;70;89 10:13:19 INFO - PID 1913 | |5;imaging-gaussian-blur;88;89;107;90;88;90;88;90;88;89 10:13:19 INFO - PID 1913 | |6;imaging-darkroom;97;96;121;109;152;150;97;96;97;96 10:13:19 INFO - PID 1913 | |7;imaging-desaturate;92;92;91;90;94;91;91;93;113;148 10:13:19 INFO - PID 1913 | |8;json-parse-financial;49;49;49;49;51;50;54;50;51;53 10:13:19 INFO - PID 1913 | |9;json-stringify-tinderbox;59;60;60;63;63;62;55;57;58;56 10:13:19 INFO - PID 1913 | |10;stanford-crypto-aes;85;88;90;87;97;87;90;90;124;86 10:13:19 INFO - PID 1913 | |11;stanford-crypto-ccm;138;142;142;148;138;139;138;139;161;148 10:13:19 INFO - PID 1913 | |12;stanford-crypto-pbkdf2;188;189;187;188;188;189;194;186;181;187 10:13:19 INFO - PID 1913 | |13;stanford-crypto-sha256-iterative;69;69;66;84;76;66;68;68;80;95 10:13:19 INFO - PID 1913 | __end_tp_report 10:13:19 INFO - PID 1913 | __start_cc_report 10:13:19 INFO - PID 1913 | _x_x_mozilla_cycle_collect,1056 10:13:19 INFO - PID 1913 | __end_cc_report 10:13:19 INFO - PID 1913 | __startTimestamp1495645999396__endTimestamp 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | ------- Summary: start ------- 10:13:19 INFO - PID 1913 | Number of tests: 14 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.50 stddev:3.96 (3.4%) stddev-sans-first:0.71 10:13:19 INFO - PID 1913 | Values: 103.0 115.0 115.0 115.0 115.0 116.0 116.0 116.0 116.0 114.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:112.00 stddev:16.90 (15.1%) stddev-sans-first:16.39 10:13:19 INFO - PID 1913 | Values: 139.0 113.0 108.0 155.0 106.0 110.0 111.0 109.0 134.0 110.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#2] audio-dft Cycles:10 Average:222.50 Median:215.00 stddev:32.27 (15.0%) stddev-sans-first:32.88 10:13:19 INFO - PID 1913 | Values: 197.0 198.0 196.0 198.0 207.0 218.0 245.0 212.0 278.0 276.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#3] audio-fft Cycles:10 Average:73.90 Median:80.50 stddev:15.43 (19.2%) stddev-sans-first:16.01 10:13:19 INFO - PID 1913 | Values: 83.0 64.0 86.0 72.0 78.0 87.0 50.0 78.0 93.0 48.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:79.00 stddev:8.73 (11.0%) stddev-sans-first:8.06 10:13:19 INFO - PID 1913 | Values: 90.0 73.0 85.0 73.0 70.0 70.0 87.0 71.0 70.0 89.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:89.00 stddev:5.79 (6.5%) stddev-sans-first:6.06 10:13:19 INFO - PID 1913 | Values: 88.0 89.0 107.0 90.0 88.0 90.0 88.0 90.0 88.0 89.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#6] imaging-darkroom Cycles:10 Average:111.10 Median:96.00 stddev:22.53 (23.5%) stddev-sans-first:23.31 10:13:19 INFO - PID 1913 | Values: 97.0 96.0 121.0 109.0 152.0 150.0 97.0 96.0 97.0 96.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#7] imaging-desaturate Cycles:10 Average:99.50 Median:91.50 stddev:18.34 (20.0%) stddev-sans-first:19.25 10:13:19 INFO - PID 1913 | Values: 92.0 92.0 91.0 90.0 94.0 91.0 91.0 93.0 113.0 148.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#8] json-parse-financial Cycles:10 Average:50.50 Median:50.50 stddev:1.78 (3.5%) stddev-sans-first:1.80 10:13:19 INFO - PID 1913 | Values: 49.0 49.0 49.0 49.0 51.0 50.0 54.0 50.0 51.0 53.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:60.00 stddev:2.83 (4.7%) stddev-sans-first:3.00 10:13:19 INFO - PID 1913 | Values: 59.0 60.0 60.0 63.0 63.0 62.0 55.0 57.0 58.0 56.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:89.00 stddev:11.60 (13.0%) stddev-sans-first:11.99 10:13:19 INFO - PID 1913 | Values: 85.0 88.0 90.0 87.0 97.0 87.0 90.0 90.0 124.0 86.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:142.00 stddev:7.32 (5.2%) stddev-sans-first:7.51 10:13:19 INFO - PID 1913 | Values: 138.0 142.0 142.0 148.0 138.0 139.0 138.0 139.0 161.0 148.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.70 Median:188.00 stddev:3.20 (1.7%) stddev-sans-first:3.39 10:13:19 INFO - PID 1913 | Values: 188.0 189.0 187.0 188.0 188.0 189.0 194.0 186.0 181.0 187.0 10:13:19 INFO - PID 1913 | 10:13:19 INFO - PID 1913 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.10 Median:72.50 stddev:9.61 (13.3%) stddev-sans-first:10.01 10:13:19 INFO - PID 1913 | Values: 69.0 69.0 66.0 84.0 76.0 66.0 68.0 68.0 80.0 95.0 10:13:19 INFO - PID 1913 | -------- Summary: end -------- 10:13:19 INFO - PID 1913 | 10:13:20 INFO - TEST-INFO | 1913: exit 0 10:13:20 INFO - TEST-OK | kraken | took 89741ms 10:13:20 INFO - SUITE-END | took 1233s 10:13:20 INFO - Completed test suite (00:20:34) 10:13:20 INFO - javascript benchmark 10:13:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7984.441180521658, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3618.1678214010776, 3641.0351256916906, 3653.415453527436, 3668.8715894943684, 3671.779693218407, 6202.0, 6223.1287619733275, 6267.341929097447, 6274.623522588648, 6324.2727086385075, 6260.9373906261035, 6263.467605253548, 6272.2159730033745, 6297.496200303976, 6357.872842543158, 5437.945620543804, 5504.284443022401, 5532.723363831812, 5548.528375088123, 5563.0, 5568.582356323272, 5640.351359593658, 5661.0, 5686.488216060554, 5713.485786279234, 5428.4028756836715, 5530.419305972855, 5568.387477377485, 5614.9719251403685, 5640.097584386499, 5473.534749546293, 5526.585506087019, 5530.474604912527, 5555.444455554452, 5555.472230138171, 4658.813647454098, 5501.229827824108, 5504.4495550444635, 5535.0, 5550.111982082847, 5392.676439413648, 6317.147185129995, 6322.114903913456, 6328.0, 6336.112944187817, 6602.438792702626, 6645.3354664533645, 6705.195376554844, 6769.289224631441, 6779.186497620317, 6928.376446119873, 6950.026996220533, 6963.338482844122, 6971.163460384735, 6981.441484681213, 6401.583897046677, 6404.743810247538, 6433.485321174295, 6437.2918978912285, 6439.678016099223, 6837.589744615339, 6933.895991560131, 6959.164900211906, 7011.263817299212, 7038.472114591324, 6446.323136070739, 6490.091387205701, 6586.077949087132, 6657.20113586363, 6737.629430381376, 6814.727410903607, 6889.104416425933, 6904.689288982008, 6916.0, 6916.757913473004, 5552.694601796859, 5709.74306156224, 6079.088136779518, 6760.763373281911, 6785.59286442815, 5810.273715785527, 6229.657368844757, 6623.536352455282, 6664.900026499607, 6743.190817101881, 5367.543758780469, 5449.509544141047, 5456.8090116846515, 5464.23500709901, 5487.807926722546, 4861.416630004422, 4880.1215781159735, 4885.145099607555, 4895.241237608177, 4925.088858561177, 6120.714649923477, 6309.684515774193, 6524.836879078061, 6534.7386104556235, 6565.770198043141, 6218.689065546704, 6862.4853136015, 6894.13823272091, 6908.0, 6927.203371612229, 5424.159255315353, 6509.674516274167, 6511.34886511345, 6534.95440729481, 6562.606243625398, 5064.974675126601, 5619.43805619443, 6115.296740874767, 6127.877442451126, 6146.754129834846, 6893.345132212432, 6947.3747362737595, 7034.718611255493, 7040.683169257498, 7073.646317684198, 6815.93184068153, 6950.235474097744, 6954.79135625932, 6973.372396484341, 6983.650817459208, 6738.258791533026, 7032.964835175791, 7066.964665176642, 7068.71725130989, 7119.964400177966, 5651.802186923438, 6506.349365063646, 6567.9014814778775, 6730.865382692221, 6773.051772751917, 6877.415419689182, 6893.345132212432, 6894.689738961659, 6921.515493915377, 6927.58434493932, 6462.741490340334, 6549.181352330958, 6593.109930159367, 6629.303923088007, 6662.56693314933, 6520.706568204348, 6543.803685889431, 6639.867202655823, 6647.867042659023, 6664.566803157779, 6376.330485298977, 6423.582467139621, 6526.282108968105, 6535.248446428532, 6547.0, 6564.376384243489, 6569.868602627825, 6645.169353830771, 6672.132622759011, 6694.397504224643, 6517.217933847968, 6549.639769812706, 6559.8032059038305, 6562.901556476752, 6590.868182636224], "value": 6188.817207067802, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.6682160081967, 798.9480683755555, 811.2941740685602, 1194.844670192875, 1250.5998080614206, 36971.89084327471, 37919.24161516768, 38017.23965520688, 38057.238855222946, 38093.80953095238, 39245.41131883024, 39461.80269098651, 39534.60465395345, 39554.0, 39593.802030989806, 6909.447244220451, 7615.923840761605, 7698.345640620554, 8305.12796156404, 8315.376346773986, 8178.550179740112, 8193.631286592117, 8276.710315138971, 8287.875681864776, 8298.004239491269, 8247.505149691038, 8256.917430825704, 8308.501489910625, 8311.085780564134, 8317.625706843177, 8247.958760206191, 8264.173582641717, 8271.379646526504, 8283.213094755989, 8297.25324720772, 6859.759908403182, 7986.0, 8018.558979256139, 8055.355571554262, 8099.06860711014, 36828.81585592082, 36934.630653693384, 37032.814835925645, 37117.81441092777, 37119.0, 3588.5155504006884, 3595.7123430125525, 3627.0, 3630.909227269313, 3634.4366623173187, 26570.202893913214, 29432.0, 30839.845800771076, 30849.84575077133, 30948.0, 3016.8491575421363, 3056.816591004547, 3063.3107550801114, 3072.4930386486426, 3073.784835061547, 247001.0, 249540.0, 276824.99999999796, 302151.4892425524, 302880.0, 48462.75768621134, 48650.75674621675, 48934.02131957341, 48993.0, 49036.26445603319, 49085.509144908094, 50926.23610645843, 50940.235896461585, 50958.74520627447, 51008.0, 14161.787573186204, 14174.22041787691, 14325.57023289303, 14419.35112919921, 14437.494687685881, 11610.419479026015, 11672.88327116718, 11673.88326116745, 11686.590969316034, 11712.824307635392, 11890.167688261912, 13024.609261722164, 13676.589702308947, 13783.862161378458, 13852.792208116887, 6176.536759742948, 6182.443580077815, 6206.131141640174, 6208.875822483525, 6249.500039996789, 999.1307562420741, 1022.7340891368298, 1032.2980373346197, 1054.214610115469, 1055.4458909072646, 20252.0, 24145.517089658108, 24145.637815432783, 24147.879260603935, 24215.636765448533, 1123.1520202247414, 1125.5441546173813, 1157.988420115805, 1159.6173262823418, 1160.5067846165346, 11792.70518237051, 12321.568745093879, 12387.566435174725, 12402.193857399421, 12478.75042499145, 281392.0, 333054.0, 334613.0, 334667.0, 334744.0, 43562.782186088865, 52618.73690631522, 53159.20261196085, 53195.73402132965, 53373.7331313341, 1490.4857824050684, 1501.5120085972321, 1529.5717199184246, 1533.2103966457285, 1538.100211376335, 23607.937642806457, 23737.762622374245, 23793.881030594737, 23836.284911452683, 23842.284731458083, 37570.8121459391, 37639.0, 37794.81102594469, 37809.432858507695, 37832.810835945646, 274253.62873186305, 274564.0, 275053.0, 275276.0, 275450.0, 34035.0, 35018.29678126032, 35092.64907350996, 35187.47218791669, 35595.288094237454, 43792.56207438013, 44189.0, 44485.33272000858, 44535.0, 44590.554094459934, 25658.61512077358, 25689.614655779806, 28209.858950705937, 28645.856770716015, 30577.388452230385, 30036.549451757804, 30115.69884301129, 30736.846315768278, 30919.690803091682, 31098.533521996735, 36148.27703445969, 36232.09419764527, 36720.63279367279, 36790.8160459196, 36902.63097368992, 28954.710452896044, 29003.0, 30268.54597181088, 30835.84582077075, 30890.53664194994, 21857.174994375604, 23567.882160589088, 23863.52272954566, 23928.28215153548, 24131.638025429984], "value": 17338.619264431793, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.0408692702202, 434.5372178629761, 442.17534298533235, 453.60536333389933, 455.8541266794625, 19463.708044379327, 21425.179334649678, 22122.88938555309, 22779.658305125395, 22843.08627654896, 19882.502937426572, 22602.77397226027, 22638.0, 23446.944887480062, 23635.5182705816, 5965.403459654021, 6272.3100458949475, 6281.811545653615, 6294.0873573332065, 6301.369863013708, 6009.75960961561, 6185.876282474348, 6187.1956645636, 6241.21984751906, 6295.716692748837, 6195.132681424581, 6195.473384762301, 6199.659018753967, 6207.813765587017, 6219.813405597839, 5988.251468566429, 6141.07883817431, 6149.969250153743, 6164.7534098636, 6202.627842329474, 5511.559075273968, 5553.583481238923, 5556.111022236463, 5572.359178694421, 5574.442555744434, 26504.86747566269, 27491.17526474209, 27771.0, 27793.888244470196, 27849.721502784916, 2133.679948007811, 2179.3897708641607, 2184.8798316092607, 2189.2447105748493, 2191.7808219177955, 13833.515826946008, 17544.0350780707, 17578.91210543939, 17632.002559564906, 18011.639767204582, 2130.3289463818846, 2148.387709502784, 2172.4894649757293, 2173.4349069241953, 2198.3844523533435, 126820.73179268182, 148517.0, 149259.25370373187, 150965.0, 159444.0, 11065.170112241614, 12272.0, 12550.121491495507, 12560.81158782619, 12593.99248060153, 15584.532464026097, 16464.670706585803, 16516.42192523256, 16526.925749826223, 16549.50351489457, 8873.64505419776, 9042.909570904338, 9052.456852588866, 9098.545072746336, 9134.26925845944, 8611.784705382415, 8627.552700100801, 8640.956795216109, 8750.16873397039, 8845.4250473719, 6730.8653826924165, 7822.921770782333, 7985.600719963979, 7997.7600671979935, 8061.387722455533, 1858.821415379689, 2189.189999700121, 2192.48476607997, 2200.053976789996, 2330.6154484510207, 844.5819319436918, 980.4019448299226, 989.8465737810651, 1017.577705252312, 1018.6892997635643, 20776.58446831055, 21068.051937662844, 21132.683009754866, 21327.573448531253, 21473.892630536746, 982.4694664881027, 996.3175224970918, 1015.6445244164483, 1020.2654089055868, 1022.3967858963099, 8099.311558517592, 8640.956795215983, 10322.896771032194, 10659.467026648637, 10737.731556711145, 529655.0, 538054.0, 543481.0, 543964.0, 546175.0, 85313.0, 107669.46165269123, 118253.40873295751, 123742.0, 135909.32045339906, 1145.8109411946934, 1158.5713286084203, 1187.4181650991125, 1190.9404529773476, 1195.396324855942, 17272.740908886124, 21216.893915530323, 22213.444663883532, 23393.649095263925, 23497.295081147593, 44005.55994440143, 58856.11715824352, 58863.41136588751, 58919.52701182505, 59149.70425147847, 79331.20668793238, 79982.0, 80442.59778701069, 80750.59624701839, 81228.5938570327, 7140.821479463055, 8859.822803543764, 8865.867011994955, 8955.507447090473, 9106.544672766202, 12849.164804287691, 13935.58193254204, 14096.224707641179, 14339.641508961942, 14605.269736512919, 11718.765624687629, 11847.289162650268, 11993.820092698443, 12052.156349055482, 12223.022158227499, 10559.366438013743, 10753.569857205624, 10826.296290741077, 11063.170262230204, 11119.444027798416, 11936.522539098687, 12047.0, 12095.0, 12167.574134905235, 12307.446164922421, 7582.696692132253, 7904.960475197588, 8923.955380223057, 8969.192772650493, 9234.584443700183, 11850.051995840484, 11918.821217681569, 12102.09234307448, 12263.0, 12292.877071229173], "value": 10396.506898117452, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.3774463746677, 1400.0759498730833, 1415.0872687116803, 1421.7796241582553, 1422.4168091082654, 11692.240004399719, 11768.646940591796, 11960.222585531934, 12200.450979705913, 12221.1445198836, 11606.709832254199, 11852.229605075665, 11856.644300670972, 11862.457880475526, 11889.643310700692, 2516.4338023944647, 2562.461883004571, 2572.305477521068, 2578.2909699832603, 2582.122078493312, 2498.4253621667026, 2535.378832186117, 2554.1443616388533, 2557.0666706652123, 2560.3215147985707, 2503.8748062596887, 2539.8857051432637, 2539.9238022859345, 2542.415244493758, 2544.402065514603, 2472.678551788274, 2500.274920273119, 2514.8365356251784, 2516.0, 2524.469861329123, 2484.527939691453, 2500.749925007503, 2512.133314006665, 2585.327814768155, 2587.8964841406323, 11566.190366674253, 11624.651260462115, 11713.824292635618, 11773.940345368872, 11777.88222117785, 5503.449655034505, 6452.419282264572, 6698.631575263358, 6723.899141512881, 6726.0919775830125, 4053.0677944073027, 4209.326507758744, 4245.936310955307, 4269.060806622538, 4279.828806847722, 1204.3496511883573, 1219.9691260884626, 1248.2136254159911, 1248.6316536621719, 1272.41468924294, 15102.39590416382, 15534.84465155334, 16301.836981630151, 16571.42000029993, 16585.83414165855, 5969.880602387928, 5981.820545383645, 6102.328743838174, 6168.1056246843955, 6246.15676883624, 13979.231142287272, 13995.930020349833, 13996.580102596938, 14008.229547374789, 14015.439382424589, 6645.700943457556, 6668.33316668339, 6713.69788359525, 6726.966365168143, 6741.494387920926, 5960.612560183574, 6158.230221222348, 6177.722002509898, 6192.473639740582, 6236.438720515175, 4666.2767271073035, 4704.717716936926, 4784.378030855966, 4853.757312134379, 4865.0756356292195, 1425.600831767107, 1466.6406730351132, 1466.9779953300495, 1474.9631259218606, 1478.1796103162633, 793.797581616684, 804.6097642643314, 822.8066404394963, 824.9133840946735, 848.2874385516196, 5349.705766182819, 6206.348333424925, 6207.379262073757, 6300.905486417708, 6388.520860935449, 586.1852025684302, 590.1914377303123, 616.9321374648785, 617.2716194890073, 628.1206311164315, 3852.24881148179, 4264.850730224489, 4439.245328294198, 4558.384618076518, 4592.88517787058, 12426.937865310616, 15275.847241527665, 15354.309056092503, 15355.769663455058, 15359.539213823604, 14879.776803347959, 15097.54707358781, 15122.0, 15126.848731512764, 15150.545483635287, 8168.469049511763, 8406.159384061544, 8484.363672724447, 8570.957145214234, 8625.654973800978, 6752.426043786333, 7093.964530177316, 7241.384482319062, 7249.347558719741, 7283.308085731847, 13194.802077968647, 13619.0, 13651.317434128452, 13665.18008919468, 13703.040787144804, 13752.381142848795, 13915.652108697364, 14035.438582456996, 14049.22729249901, 14055.0, 9250.21373183287, 9577.473238971923, 9580.856287155837, 9585.760355990877, 9599.088086631758, 9056.954715226382, 10702.518386672775, 10726.98093681099, 10865.185111116856, 10880.510377032893, 9137.40606860552, 9173.0, 9296.256299495888, 9327.720168394959, 9453.251148537542, 8948.776280593038, 8966.193042625933, 8990.955045224731, 9054.366194366332, 9062.139096785795, 7958.681652733826, 8233.094359620318, 8305.0, 8409.327253819558, 8420.536870472182, 5774.220480235114, 5900.203472531325, 6079.148919151234, 6263.6241825490615, 6338.239411270677, 6161.845953851011, 6273.215848018997, 6296.779612713533, 6321.399467050623, 6412.006139048455], "value": 5428.523803633754, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.89387918322456, 401.3297792686214, 402.58332625732373, 403.69521011636226, 412.4061351653618, 5244.947550524494, 6185.134291068082, 6196.752129914804, 6220.688965551722, 6228.096925945735, 4957.380327459067, 5868.823935281937, 6037.456628903398, 6060.969695151518, 6095.329513753494, 3978.4231286463446, 4592.334111553822, 4618.1456430560365, 4621.74580398078, 4628.930566041512, 4181.665466762667, 4573.954260457386, 4582.9294372770155, 4595.988882445873, 4610.5389461053965, 3578.4076086141677, 4131.938020929689, 4145.39891715701, 4542.136993971135, 4559.788502576248, 3524.753267271292, 4432.689711720181, 4569.497355290915, 4599.655025873073, 4604.3323718060865, 3555.5377800885976, 3707.870224542155, 3863.130795571002, 4028.9049694158875, 4073.657646853071, 5166.3800343958965, 5409.783608655649, 5791.233673729511, 5917.822465326047, 5961.731722072517, 5477.945220547784, 5485.50630443262, 5487.259220005288, 5490.176473528963, 5503.367112782042, 6394.168758061469, 6503.739850405978, 6573.474122070223, 6593.406593406617, 6602.40578347951, 4601.447826260836, 4632.72203667781, 4642.932125611094, 4643.489216186217, 4660.254359302496, 5470.753816078286, 5734.741936612821, 6516.478681705452, 6745.898811517831, 6859.142607174103, 4825.276208568743, 4902.803887844446, 4939.481354457802, 4943.28322393251, 4952.529509696645, 4986.90026199474, 5044.0920634286185, 5048.823291184791, 5056.9494305057215, 5059.797608095709, 5463.847475918251, 5554.750036248379, 5606.579506537026, 5628.25524087526, 5659.547236221092, 6628.502862285348, 6653.101831252816, 6653.334666533308, 6677.9666101670155, 6707.49693772969, 1583.6515966487354, 1694.5763559110223, 1714.9828501714824, 1715.0652894172706, 1733.9046352450737, 980.9411435313904, 1198.2750435986102, 1227.613301809945, 1228.072805032195, 1231.7536492701317, 6099.0, 6237.5321850860455, 6314.810555683245, 6314.8421289467215, 6346.555741098171, 5656.91514627281, 5934.910976335358, 6560.311167327457, 6815.420689241371, 6844.315568443216, 5236.24074509194, 5835.883282334329, 6019.548533859978, 6232.096346029891, 6281.057841323838, 3401.6768407001296, 3520.1727594015388, 3551.5027896094566, 3575.3028159508644, 3591.9281614367565, 6219.906701399483, 6687.665616719145, 6843.692033858489, 6944.784662684111, 6978.79063628092, 7007.913773365137, 7063.152421709325, 7083.433325334064, 7092.255313192144, 7092.858142837114, 6813.965930170318, 6980.650967451607, 7069.515401765584, 7072.92927070733, 7107.680154392961, 6765.9363894012795, 6792.083068785848, 6831.9316806833285, 6846.760363387258, 6882.139732533434, 5322.574194064388, 6363.809085727436, 6718.260991291052, 6719.62247739222, 6750.459963202834, 6495.837604059936, 7001.264867188872, 7040.0, 7073.575585464889, 7096.893546596909, 4218.725782824106, 4435.824506505714, 4766.45185803637, 4861.1006963711825, 4931.334269873521, 5017.724025178651, 6441.0, 6545.37818907203, 6862.529489383917, 6871.690773915086, 5690.886182276414, 6260.744412905173, 6584.50616203777, 6604.0, 6610.2398224204835, 5728.484436400744, 5791.407362975216, 6163.84590385244, 6262.060690896402, 6443.935560644333, 6422.871542569029, 6452.548321617442, 6459.935400646122, 6471.967640161769, 6474.449671777952, 5125.538701516882, 5984.401559843981, 6024.036154215304, 6366.586171898812, 6584.572002819801, 5623.606347555632, 6403.716933529763, 6413.16628838248, 6416.165898433173, 6463.773767918288], "value": 4852.7526427389175, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.7467860927285, 785.2461636828648, 816.889719887815, 848.6562942008485, 864.1617630898031, 14585.124892506457, 15121.84878151218, 16680.83234173607, 17215.91392043038, 17270.48188554344, 17317.39389121378, 17881.7317740234, 18084.457466276, 18122.728159077626, 18180.272789088453, 933.9813203735921, 973.8149751547219, 977.2475194100539, 991.7520619845038, 993.9204863610894, 984.2224642532391, 994.4132961552682, 995.004995004995, 997.5211898288826, 1000.3247807729754, 938.9483578403185, 993.940363578184, 995.5569771451707, 997.8652881860964, 1013.1843865688118, 967.0571193086754, 982.130814229405, 985.1527686189871, 987.1115995603978, 1039.153090231459, 714.7998560403096, 719.3597698048741, 720.9870132463897, 730.1821959405447, 758.215247219131, 16809.747853782203, 17233.482995510156, 17409.390671326444, 17411.95528268308, 17471.301147954066, 17221.913890430595, 17229.396971106078, 17335.04657243851, 17754.822451775446, 17776.91111544434, 14122.0, 14477.131372117601, 14490.782638260434, 14527.418903243857, 14535.927320363437, 3577.678008979204, 3578.1054736315923, 3603.7837729736298, 3605.2248766515063, 3606.170580766412, 138039.0, 139898.0, 140708.2964585191, 143625.0, 159744.0, 13718.628137186202, 13942.790858137136, 14065.648358791112, 14078.929605352112, 14410.991230613756, 20614.0, 25345.73271336463, 26457.07400241021, 26836.0, 26840.86579567129, 6822.761203357859, 7043.36609705129, 7262.092238470192, 8001.599920004093, 8125.8374832504205, 3971.245463361952, 4075.9592404076175, 4080.6327430531396, 4083.816228269735, 4085.1421201547414, 4159.854405095807, 4196.118815048813, 4225.535191128974, 4241.024564350217, 4257.6168144867115, 921.3780698028804, 926.1849572376266, 935.4948327902856, 939.0609390609391, 940.1068984464786, 551.8565173054955, 647.1942431672597, 668.7826456401689, 671.5701950751522, 690.6926417744056, 9600.375975561567, 11097.83353249702, 12215.375572237685, 12439.06706996979, 12490.562830301078, 619.495111484148, 651.7490766055008, 655.7999810057883, 672.9798106056826, 674.2347435660463, 5671.546276297886, 5790.9286781945475, 5800.100984347433, 5813.505852002542, 5847.44449277318, 127212.36393817971, 152173.0, 154063.0, 155282.0, 155574.2221288909, 115098.0, 117012.0, 117085.41457292659, 118842.26826195826, 119223.40388298003, 68233.65883170553, 81455.18544814477, 103471.0, 105284.47357763162, 114113.0, 11680.824787628022, 12270.386480676108, 12337.93831030875, 13097.0, 13098.2141071536, 41821.58178418177, 45462.31806522971, 46107.76946115248, 46374.53625463837, 46522.53477465318, 85285.0, 86139.0, 88023.6796448041, 88286.67569986326, 89787.0, 17257.223424946158, 17304.394346197823, 17753.0, 18005.819941800415, 18078.45764627063, 18726.344577939708, 18789.0, 19050.809491905457, 19496.0, 19657.803421966168, 15517.5344739658, 17561.385351512636, 17861.642767144844, 18032.729509057113, 18153.81846181521, 17181.57046073858, 17486.1045337854, 17492.387766428114, 17823.01973391476, 17890.731639025686, 16944.915275423544, 17475.038872862115, 17547.56131096733, 17629.382971596446, 17686.469405917844, 11787.705307367041, 11905.583304584645, 12309.015278777855, 12427.751444970869, 12546.749065018466, 13091.214527128403, 13433.0, 13478.258695771827, 13497.32513374347, 13527.0], "value": 8815.729378296028, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1516.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 115.0, 115.0, 115.0, 116.0, 116.0, 116.0, 116.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 113.0, 108.0, 155.0, 106.0, 110.0, 111.0, 109.0, 134.0, 110.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 198.0, 196.0, 198.0, 207.0, 218.0, 245.0, 212.0, 278.0, 276.0], "value": 222.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 64.0, 86.0, 72.0, 78.0, 87.0, 50.0, 78.0, 93.0, 48.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 73.0, 85.0, 73.0, 70.0, 70.0, 87.0, 71.0, 70.0, 89.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 107.0, 90.0, 88.0, 90.0, 88.0, 90.0, 88.0, 89.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 121.0, 109.0, 152.0, 150.0, 97.0, 96.0, 97.0, 96.0], "value": 111.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 91.0, 90.0, 94.0, 91.0, 91.0, 93.0, 113.0, 148.0], "value": 99.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 49.0, 51.0, 50.0, 54.0, 50.0, 51.0, 53.0], "value": 50.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 60.0, 63.0, 63.0, 62.0, 55.0, 57.0, 58.0, 56.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 90.0, 87.0, 97.0, 87.0, 90.0, 90.0, 124.0, 86.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 142.0, 142.0, 148.0, 138.0, 139.0, 138.0, 139.0, 161.0, 148.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 189.0, 187.0, 188.0, 188.0, 189.0, 194.0, 186.0, 181.0, 187.0], "value": 187.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 66.0, 84.0, 76.0, 66.0, 68.0, 68.0, 80.0, 95.0], "value": 74.1, "unit": "score"}]}]} 10:13:20 INFO - Return code: 0 10:13:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:13:20 INFO - # TBPL SUCCESS # 10:13:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:13:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:13:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:13:20 INFO - 2017-05-24 10:13:20.693 screenresolution[1920:12295] starting screenresolution argv=screenresolution get 10:13:20 INFO - 2017-05-24 10:13:20.696 screenresolution[1920:12295] Display 0: 1600x1200x32@60 10:13:20 INFO - 2017-05-24 10:13:20.702 screenresolution[1921:12298] starting screenresolution argv=screenresolution list 10:13:20 INFO - Available Modes on Display 0 10:13:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:13:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:13:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:13:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:13:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:13:20 INFO - Intel Iris: 10:13:20 INFO - Chipset Model: Intel Iris 10:13:20 INFO - Type: GPU 10:13:20 INFO - Bus: Built-In 10:13:20 INFO - VRAM (Dynamic, Max): 1536 MB 10:13:20 INFO - Vendor: Intel (0x8086) 10:13:20 INFO - Device ID: 0x0a2e 10:13:20 INFO - Revision ID: 0x0009 10:13:20 INFO - Displays: 10:13:20 INFO - Display: 10:13:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:13:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:13:20 INFO - Display Serial Number: 1600x1200 60 10:13:20 INFO - Main Display: Yes 10:13:20 INFO - Mirror: Off 10:13:20 INFO - Online: Yes 10:13:20 INFO - Rotation: Supported 10:13:20 INFO - Return code: 0 10:13:20 INFO - Running post-action listener: _resource_record_post_action 10:13:20 INFO - [mozharness: 2017-05-24 17:13:20.936622Z] Finished run-tests step (success) 10:13:20 INFO - Running post-run listener: _resource_record_post_run 10:13:20 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 6791680; Write bytes: 566796800; Read time: 62; Write time: 1851 10:13:20 INFO - TinderboxPrint: CPU usage
53.2% 10:13:20 INFO - TinderboxPrint: I/O read bytes / time
6,791,680 / 62 10:13:20 INFO - TinderboxPrint: I/O write bytes / time
566,796,800 / 1,851 10:13:20 INFO - TinderboxPrint: CPU idle
2,351.4 (46.8%) 10:13:20 INFO - TinderboxPrint: CPU system
1,011.8 (20.1%) 10:13:20 INFO - TinderboxPrint: CPU user
1,662.6 (33.1%) 10:13:20 INFO - TinderboxPrint: Swap in / out
252,555,264 / 0 10:13:20 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232032768; Write bytes: 230450688; Read time: 16834; Write time: 476 10:13:21 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 5927424; Write bytes: 317037568; Read time: 59; Write time: 1299 10:13:21 INFO - Running post-run listener: _upload_blobber_files 10:13:21 INFO - Blob upload gear active. 10:13:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:13:21 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:13:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:13:21 INFO - (blobuploader) - INFO - Open directory for files ... 10:13:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:13:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:13:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:22 INFO - (blobuploader) - INFO - Done attempting. 10:13:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:13:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:13:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:23 INFO - (blobuploader) - INFO - Done attempting. 10:13:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:13:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:13:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:24 INFO - (blobuploader) - INFO - Done attempting. 10:13:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:13:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:13:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:13:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:13:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:13:26 INFO - (blobuploader) - INFO - Done attempting. 10:13:26 INFO - (blobuploader) - INFO - Iteration through files over. 10:13:26 INFO - Return code: 0 10:13:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:13:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:13:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aad3d7cb0e1ab9b0f7532684628654a963e40d0661b3c8478cb5a9976b48f94d07d9bf3bfe84e4162741ca9034c1390aaf6ddf09221806d2ed4c3501adcaff71", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddd030a5cc627dacdbe4ec297d35d028c4320532db20dc82c49c77b66da877a4bf23f8725d6107e4dac529763de3c1facbdd878104501e006b620b222ea5a9c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50925fdb936a1284012324ef63b53a52073af601808286b248246a6231adc61d593ab5172aa606d0ad9b30d3bfaa097b3f81bade7502097295e2763ef45b79e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97e054fc3ba3ffe9d3b5d2ab7896870878e0cca3e610d4b44016d853012a59a29770c2393a7d48943064758e168751f6fbb48ffb1966394ee6b251467c414c7d"} 10:13:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:13:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:13:26 INFO - Contents: 10:13:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aad3d7cb0e1ab9b0f7532684628654a963e40d0661b3c8478cb5a9976b48f94d07d9bf3bfe84e4162741ca9034c1390aaf6ddf09221806d2ed4c3501adcaff71", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddd030a5cc627dacdbe4ec297d35d028c4320532db20dc82c49c77b66da877a4bf23f8725d6107e4dac529763de3c1facbdd878104501e006b620b222ea5a9c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50925fdb936a1284012324ef63b53a52073af601808286b248246a6231adc61d593ab5172aa606d0ad9b30d3bfaa097b3f81bade7502097295e2763ef45b79e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97e054fc3ba3ffe9d3b5d2ab7896870878e0cca3e610d4b44016d853012a59a29770c2393a7d48943064758e168751f6fbb48ffb1966394ee6b251467c414c7d"} 10:13:26 INFO - Running post-run listener: copy_logs_to_upload_dir 10:13:26 INFO - Copying logs to upload dir... 10:13:26 INFO - mkdir: /builds/slave/test/build/upload/logs 10:13:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.050300 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-24 10:13:27.067437) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 10:13:27.071491) ========= 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.1a7K1OWlZv/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.fab3bF7zb1/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/aad3d7cb0e1ab9b0f7532684628654a963e40d0661b3c8478cb5a9976b48f94d07d9bf3bfe84e4162741ca9034c1390aaf6ddf09221806d2ed4c3501adcaff71", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddd030a5cc627dacdbe4ec297d35d028c4320532db20dc82c49c77b66da877a4bf23f8725d6107e4dac529763de3c1facbdd878104501e006b620b222ea5a9c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50925fdb936a1284012324ef63b53a52073af601808286b248246a6231adc61d593ab5172aa606d0ad9b30d3bfaa097b3f81bade7502097295e2763ef45b79e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97e054fc3ba3ffe9d3b5d2ab7896870878e0cca3e610d4b44016d853012a59a29770c2393a7d48943064758e168751f6fbb48ffb1966394ee6b251467c414c7d"} build_url:https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010329 build_url: 'https://queue.taskcluster.net/v1/task/d_nb_Q8ORDKJwLQ4oKGoXQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aad3d7cb0e1ab9b0f7532684628654a963e40d0661b3c8478cb5a9976b48f94d07d9bf3bfe84e4162741ca9034c1390aaf6ddf09221806d2ed4c3501adcaff71", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ddd030a5cc627dacdbe4ec297d35d028c4320532db20dc82c49c77b66da877a4bf23f8725d6107e4dac529763de3c1facbdd878104501e006b620b222ea5a9c4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c50925fdb936a1284012324ef63b53a52073af601808286b248246a6231adc61d593ab5172aa606d0ad9b30d3bfaa097b3f81bade7502097295e2763ef45b79e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/97e054fc3ba3ffe9d3b5d2ab7896870878e0cca3e610d4b44016d853012a59a29770c2393a7d48943064758e168751f6fbb48ffb1966394ee6b251467c414c7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 10:13:27.103647) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:13:27.103984) ========= 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.1a7K1OWlZv/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.fab3bF7zb1/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005313 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:13:27.152923) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 10:13:27.153250) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 10:13:27.153572) ========= ========= Total master_lag: 0.18 =========