builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0333 starttime: 1496508985.12 results: success (0) revision: 98f1390029f9bd558de991a53c92342ac0addfc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:25.118507) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:25.118911) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:25.119185) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:25.144999) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:25.145296) ========= 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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-03 09:56:25-- 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% 17.5M=0.001s 2017-06-03 09:56:25 (17.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.170804 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:25.342223) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:25.342540) ========= 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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.027249 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:25.391739) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-03 09:56:25.392133) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 98f1390029f9bd558de991a53c92342ac0addfc4 --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 98f1390029f9bd558de991a53c92342ac0addfc4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-03 09:56:25,491 truncating revision to first 12 chars 2017-06-03 09:56:25,491 Setting DEBUG logging. 2017-06-03 09:56:25,491 attempt 1/10 2017-06-03 09:56:25,491 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/98f1390029f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-03 09:56:26,786 unpacking tar archive at: mozilla-central-98f1390029f9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.571145 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-03 09:56:26.983893) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:26.984207) ========= script_repo_revision: 98f1390029f9bd558de991a53c92342ac0addfc4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:26.984581) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:26.984893) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-03 09:56:27.002534) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-03 09:56:27.003142) ========= /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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:56:27 INFO - MultiFileLogger online at 20170603 09:56:27 in /builds/slave/test 09:56:27 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:56:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:56:27 INFO - {'append_to_log': False, 09:56:27 INFO - 'base_work_dir': '/builds/slave/test', 09:56:27 INFO - 'blob_upload_branch': 'Firefox', 09:56:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:56:27 INFO - 'branch': 'Firefox', 09:56:27 INFO - 'buildbot_json_path': 'buildprops.json', 09:56:27 INFO - 'config_files': ('talos/mac_config.py',), 09:56:27 INFO - 'default_actions': ('clobber', 09:56:27 INFO - 'read-buildbot-config', 09:56:27 INFO - 'download-and-extract', 09:56:27 INFO - 'populate-webroot', 09:56:27 INFO - 'create-virtualenv', 09:56:27 INFO - 'install', 09:56:27 INFO - 'setup-mitmproxy', 09:56:27 INFO - 'run-tests'), 09:56:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:56:27 INFO - 'download_minidump_stackwalk': True, 09:56:27 INFO - 'download_symbols': 'ondemand', 09:56:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:56:27 INFO - 'tooltool.py': '/tools/tooltool.py', 09:56:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:56:27 INFO - '/tools/misc-python/virtualenv.py')}, 09:56:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:56:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:56:27 INFO - 'gecko_profile': False, 09:56:27 INFO - 'gecko_profile_interval': 0, 09:56:27 INFO - 'installer_path': 'installer.exe', 09:56:27 INFO - 'log_level': 'info', 09:56:27 INFO - 'log_name': 'talos', 09:56:27 INFO - 'log_to_console': True, 09:56:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:56:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:56:27 INFO - 'opt_config_files': (), 09:56:27 INFO - 'pip_index': False, 09:56:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:56:27 INFO - 'cmd': ('bash', 09:56:27 INFO - '-c', 09:56:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:56:27 INFO - 'enabled': True, 09:56:27 INFO - 'halt_on_failure': False, 09:56:27 INFO - 'name': 'check_screen_resolution'},), 09:56:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:56:27 INFO - 'cmd': ('bash', 09:56:27 INFO - '-c', 09:56:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:56:27 INFO - 'enabled': True, 09:56:27 INFO - 'halt_on_failure': False, 09:56:27 INFO - 'name': 'check_screen_resolution'},), 09:56:27 INFO - 'run_cmd_checks_enabled': True, 09:56:27 INFO - 'suite': 'dromaeojs-e10s', 09:56:27 INFO - 'system_bits': '32', 09:56:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:56:27 INFO - 'title': 't-yosemite-r7-0333', 09:56:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:56:27 INFO - 'use_talos_json': True, 09:56:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:56:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:56:27 INFO - 'work_dir': 'build'} 09:56:27 INFO - [mozharness: 2017-06-03 16:56:27.198350Z] Running clobber step. 09:56:27 INFO - Running pre-action listener: _resource_record_pre_action 09:56:27 INFO - Running main action method: clobber 09:56:27 INFO - rmtree: /builds/slave/test/build 09:56:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:56:29 INFO - Running post-action listener: _resource_record_post_action 09:56:29 INFO - [mozharness: 2017-06-03 16:56:29.725724Z] Finished clobber step (success) 09:56:29 INFO - [mozharness: 2017-06-03 16:56:29.725858Z] Running read-buildbot-config step. 09:56:29 INFO - Running pre-action listener: _resource_record_pre_action 09:56:29 INFO - Running main action method: read_buildbot_config 09:56:29 INFO - Using buildbot properties: 09:56:29 INFO - { 09:56:29 INFO - "project": "", 09:56:29 INFO - "product": "firefox", 09:56:29 INFO - "who": "ffxbld@noreply.mozilla.org", 09:56:29 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:56:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:56:29 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:56:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:56:29 INFO - "stage_platform": "macosx64", 09:56:29 INFO - "basedir": "/builds/slave/test", 09:56:29 INFO - "buildnumber": 230, 09:56:29 INFO - "platform": "yosemite_r7", 09:56:29 INFO - "parent_task_id": "X_JOMqD8Sae-3frvDhChAA", 09:56:29 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:56:29 INFO - "slavebuilddir": "test", 09:56:29 INFO - "taskId": "KuxUCbevTTu7UXUCpi2c2A", 09:56:29 INFO - "branch": "mozilla-central", 09:56:29 INFO - "script_repo_revision": "production", 09:56:29 INFO - "revision": "98f1390029f9bd558de991a53c92342ac0addfc4", 09:56:29 INFO - "slavename": "t-yosemite-r7-0333", 09:56:29 INFO - "repo_path": "mozilla-central" 09:56:29 INFO - } 09:56:29 INFO - Finding installer, test and symbols from parent task. 09:56:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KuxUCbevTTu7UXUCpi2c2A'}, attempt #1 09:56:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KuxUCbevTTu7UXUCpi2c2A'}, attempt #1 09:56:30 INFO - Task dependencies: HslJ2qj9SXmkiOvEYG4z5A X_JOMqD8Sae-3frvDhChAA 09:56:30 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA'}, attempt #1 09:56:31 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:56:31 INFO - Set test_url: https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/test_packages.json 09:56:31 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/target.crashreporter-symbols.zip 09:56:31 INFO - Running post-action listener: _resource_record_post_action 09:56:31 INFO - [mozharness: 2017-06-03 16:56:31.079431Z] Finished read-buildbot-config step (success) 09:56:31 INFO - [mozharness: 2017-06-03 16:56:31.079543Z] Running download-and-extract step. 09:56:31 INFO - Running pre-action listener: _resource_record_pre_action 09:56:31 INFO - Running main action method: download_and_extract 09:56:31 INFO - mkdir: /builds/slave/test/build/tests 09:56:31 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:56:31 INFO - trying https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:56:31 INFO - Downloading https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/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:56:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/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:56:32 INFO - Downloaded 1328 bytes. 09:56:32 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:56:32 INFO - Using the following test package requirements: 09:56:32 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:56:32 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:56:32 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:56:32 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:56:32 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'jsshell-mac.zip'], 09:56:32 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:56:32 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:56:32 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:56:32 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:56:32 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:56:32 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:56:32 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:56:32 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:56:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:56:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:56:32 INFO - Fetch https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:56:34 INFO - Content-Length response header: 57631805 09:56:34 INFO - Bytes received: 57631805 09:56:52 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:56:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:56:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:56:52 INFO - Fetch https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:56:54 INFO - Content-Length response header: 57631805 09:56:54 INFO - Bytes received: 57631805 09:57:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:57:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:57:11 INFO - Fetch https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:57:12 INFO - Content-Length response header: 14447425 09:57:12 INFO - Bytes received: 14447425 09:57: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:57:13 INFO - trying https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:57:13 INFO - Downloading https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:57:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/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:57:15 INFO - Downloaded 67781772 bytes. 09:57:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:57:15 INFO - mkdir: /builds/slave/test/properties 09:57:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:57:15 INFO - Writing to file /builds/slave/test/properties/build_url 09:57:15 INFO - Contents: 09:57:15 INFO - build_url:https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:57:15 INFO - Running post-action listener: _resource_record_post_action 09:57:15 INFO - Running post-action listener: set_extra_try_arguments 09:57:15 INFO - [mozharness: 2017-06-03 16:57:15.118729Z] Finished download-and-extract step (success) 09:57:15 INFO - [mozharness: 2017-06-03 16:57:15.118842Z] Running populate-webroot step. 09:57:15 INFO - Running pre-action listener: _resource_record_pre_action 09:57:15 INFO - Running main action method: populate_webroot 09:57:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:57:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:57:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:57:15 INFO - '--tppagecycles', 09:57:15 INFO - '7'], 09:57:15 INFO - 'tests': ['tsvgm']}}, 09:57:15 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:57:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:57:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:57:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:57:15 INFO - 'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:57:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:57:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:57:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:57:15 INFO - 'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['damp', 'tps']}, 09:57:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:57:15 INFO - 'tests': ['damp', 'tps']}, 09:57:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['dromaeo_dom']}, 09:57:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:57:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:57:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:57:15 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:57:15 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:57:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['a11yr', 09:57:15 INFO - 'ts_paint', 09:57:15 INFO - 'tpaint', 09:57:15 INFO - 'sessionrestore', 09:57:15 INFO - 'sessionrestore_no_auto_restore', 09:57:15 INFO - 'tabpaint']}, 09:57:15 INFO - 'other-e10s': {'tests': ['a11yr', 09:57:15 INFO - 'ts_paint', 09:57:15 INFO - 'tpaint', 09:57:15 INFO - 'sessionrestore', 09:57:15 INFO - 'sessionrestore_no_auto_restore', 09:57:15 INFO - 'tabpaint']}, 09:57:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:57:15 INFO - 'ts_paint', 09:57:15 INFO - 'tpaint', 09:57:15 INFO - 'sessionrestore', 09:57:15 INFO - 'sessionrestore_no_auto_restore', 09:57:15 INFO - 'tabpaint']}, 09:57:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:57:15 INFO - 'ts_paint', 09:57:15 INFO - 'tpaint', 09:57:15 INFO - 'sessionrestore', 09:57:15 INFO - 'sessionrestore_no_auto_restore', 09:57:15 INFO - 'tabpaint']}, 09:57:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['a11yr', 09:57:15 INFO - 'ts_paint', 09:57:15 INFO - 'tpaint', 09:57:15 INFO - 'sessionrestore', 09:57:15 INFO - 'sessionrestore_no_auto_restore', 09:57:15 INFO - 'tabpaint']}, 09:57:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['a11yr', 09:57:15 INFO - 'ts_paint', 09:57:15 INFO - 'tpaint', 09:57:15 INFO - 'sessionrestore', 09:57:15 INFO - 'sessionrestore_no_auto_restore', 09:57:15 INFO - 'tabpaint']}, 09:57:15 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:57:15 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:57:15 INFO - 'bloom_basic_ref']}, 09:57:15 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:57:15 INFO - 'talos_options': ['--mitmproxy', 09:57:15 INFO - 'mitmproxy-recording-1.mp', 09:57:15 INFO - '--firstNonBlankPaint'], 09:57:15 INFO - 'tests': ['Quantum_1']}, 09:57:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['tsvgx', 09:57:15 INFO - 'tsvgr_opacity', 09:57:15 INFO - 'tart', 09:57:15 INFO - 'tscrollx', 09:57:15 INFO - 'cart', 09:57:15 INFO - 'tsvg_static']}, 09:57:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:57:15 INFO - 'tsvgr_opacity', 09:57:15 INFO - 'tart', 09:57:15 INFO - 'tscrollx', 09:57:15 INFO - 'cart', 09:57:15 INFO - 'tsvg_static']}, 09:57:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:57:15 INFO - 'talos_options': ['--disable-e10s'], 09:57:15 INFO - 'tests': ['tp5o']}, 09:57:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:57:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:57:15 INFO - 'talos_options': ['--disable-e10s', 09:57:15 INFO - '--xperf_path', 09:57:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:57:15 INFO - 'tests': ['tp5n']}, 09:57:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:57:15 INFO - 'talos_options': ['--xperf_path', 09:57:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:57:15 INFO - 'tests': ['tp5n']}}, 09:57:15 INFO - 'talos.zip': {'path': '', 09:57:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:57:15 INFO - Running post-action listener: _resource_record_post_action 09:57:15 INFO - [mozharness: 2017-06-03 16:57:15.140773Z] Finished populate-webroot step (success) 09:57:15 INFO - [mozharness: 2017-06-03 16:57:15.140911Z] Running create-virtualenv step. 09:57:15 INFO - Running pre-action listener: _resource_record_pre_action 09:57:15 INFO - Running main action method: create_virtualenv 09:57:15 INFO - Creating virtualenv /builds/slave/test/build/venv 09:57:15 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:57:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:57:15 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:57:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:57:15 INFO - Using real prefix '/tools/python27' 09:57:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:57:16 INFO - Installing distribute.............................................................................................................................................................................................done. 09:57:18 INFO - Installing pip.................done. 09:57:18 INFO - Return code: 0 09:57:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:57:19 INFO - Reading from file tmpfile_stdout 09:57:19 INFO - Output received: 09:57:19 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:57:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:19 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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:19 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:57:19 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:57:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:19 INFO - 'HOME': '/Users/cltbld', 09:57:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:19 INFO - 'LOGNAME': 'cltbld', 09:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:19 INFO - 'MOZ_NO_REMOTE': '1', 09:57:19 INFO - 'NO_EM_RESTART': '1', 09:57:19 INFO - 'PAGER': '/bin/cat', 09:57:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:19 INFO - 'PWD': '/builds/slave/test', 09:57:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:19 INFO - 'SHELL': '/bin/bash', 09:57:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:19 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:19 INFO - 'USER': 'cltbld', 09:57:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:19 INFO - 'XPC_FLAGS': '0x0', 09:57:19 INFO - 'XPC_SERVICE_NAME': '0', 09:57:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:19 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:57:19 INFO - Cleaning up... 09:57:19 INFO - Return code: 0 09:57:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:19 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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:19 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:57:19 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:57:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:19 INFO - 'HOME': '/Users/cltbld', 09:57:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:19 INFO - 'LOGNAME': 'cltbld', 09:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:19 INFO - 'MOZ_NO_REMOTE': '1', 09:57:19 INFO - 'NO_EM_RESTART': '1', 09:57:19 INFO - 'PAGER': '/bin/cat', 09:57:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:19 INFO - 'PWD': '/builds/slave/test', 09:57:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:19 INFO - 'SHELL': '/bin/bash', 09:57:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:19 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:19 INFO - 'USER': 'cltbld', 09:57:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:19 INFO - 'XPC_FLAGS': '0x0', 09:57:19 INFO - 'XPC_SERVICE_NAME': '0', 09:57:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:19 INFO - Downloading/unpacking psutil>=3.1.1 09:57: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:57: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:57: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:57: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:57:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:57:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:57:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:57:21 INFO - Installing collected packages: psutil 09:57:21 INFO - Running setup.py install for psutil 09:57:22 INFO - building 'psutil._psutil_osx' extension 09:57:22 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:57:22 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:57:22 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:57:22 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:57:22 INFO - building 'psutil._psutil_posix' extension 09:57:22 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:57:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:57:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:57:22 INFO - ^ 09:57:22 INFO - 1 warning generated. 09:57:22 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:57:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:57:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:57:23 INFO - Successfully installed psutil 09:57:23 INFO - Cleaning up... 09:57:23 INFO - Return code: 0 09:57:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:57: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:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57: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:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:57: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 mozsystemmonitor==0.3 09:57:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:23 INFO - 'HOME': '/Users/cltbld', 09:57:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:23 INFO - 'LOGNAME': 'cltbld', 09:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:23 INFO - 'MOZ_NO_REMOTE': '1', 09:57:23 INFO - 'NO_EM_RESTART': '1', 09:57:23 INFO - 'PAGER': '/bin/cat', 09:57:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:23 INFO - 'PWD': '/builds/slave/test', 09:57:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:23 INFO - 'SHELL': '/bin/bash', 09:57:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:23 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:23 INFO - 'USER': 'cltbld', 09:57:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:23 INFO - 'XPC_FLAGS': '0x0', 09:57:23 INFO - 'XPC_SERVICE_NAME': '0', 09:57:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:57:24 INFO - Installing collected packages: mozsystemmonitor 09:57:24 INFO - Running setup.py install for mozsystemmonitor 09:57:25 INFO - Successfully installed mozsystemmonitor 09:57:25 INFO - Cleaning up... 09:57:25 INFO - Return code: 0 09:57:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:57:25 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:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:25 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:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:25 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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:25 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:57:25 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:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:25 INFO - 'HOME': '/Users/cltbld', 09:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:25 INFO - 'LOGNAME': 'cltbld', 09:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:25 INFO - 'MOZ_NO_REMOTE': '1', 09:57:25 INFO - 'NO_EM_RESTART': '1', 09:57:25 INFO - 'PAGER': '/bin/cat', 09:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:25 INFO - 'PWD': '/builds/slave/test', 09:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:25 INFO - 'SHELL': '/bin/bash', 09:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:25 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:25 INFO - 'USER': 'cltbld', 09:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:25 INFO - 'XPC_FLAGS': '0x0', 09:57:25 INFO - 'XPC_SERVICE_NAME': '0', 09:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:25 INFO - Downloading/unpacking jsonschema==2.5.1 09:57:25 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:57:25 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:57:25 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:57:25 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:57:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:57:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:57:27 INFO - warning: no files found matching '*.txt' 09:57:27 INFO - no previously-included directories found matching 'build' 09:57:27 INFO - no previously-included directories found matching 'dist' 09:57:27 INFO - no previously-included directories found matching '.git*' 09:57:27 INFO - Installing collected packages: jsonschema, functools32 09:57:27 INFO - Running setup.py install for functools32 09:57:27 INFO - warning: no files found matching '*.txt' 09:57:27 INFO - no previously-included directories found matching 'build' 09:57:27 INFO - no previously-included directories found matching 'dist' 09:57:27 INFO - no previously-included directories found matching '.git*' 09:57:27 INFO - Successfully installed jsonschema functools32 09:57:27 INFO - Cleaning up... 09:57:27 INFO - Return code: 0 09:57:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:27 INFO - 'HOME': '/Users/cltbld', 09:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:27 INFO - 'LOGNAME': 'cltbld', 09:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:27 INFO - 'MOZ_NO_REMOTE': '1', 09:57:27 INFO - 'NO_EM_RESTART': '1', 09:57:27 INFO - 'PAGER': '/bin/cat', 09:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:27 INFO - 'PWD': '/builds/slave/test', 09:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:27 INFO - 'SHELL': '/bin/bash', 09:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:27 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:27 INFO - 'USER': 'cltbld', 09:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:27 INFO - 'XPC_FLAGS': '0x0', 09:57:27 INFO - 'XPC_SERVICE_NAME': '0', 09:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:57:27 INFO - Cleaning up... 09:57:27 INFO - Return code: 0 09:57:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:27 INFO - 'HOME': '/Users/cltbld', 09:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:27 INFO - 'LOGNAME': 'cltbld', 09:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:27 INFO - 'MOZ_NO_REMOTE': '1', 09:57:27 INFO - 'NO_EM_RESTART': '1', 09:57:27 INFO - 'PAGER': '/bin/cat', 09:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:27 INFO - 'PWD': '/builds/slave/test', 09:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:27 INFO - 'SHELL': '/bin/bash', 09:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:27 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:27 INFO - 'USER': 'cltbld', 09:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:27 INFO - 'XPC_FLAGS': '0x0', 09:57:27 INFO - 'XPC_SERVICE_NAME': '0', 09:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:27 INFO - Downloading/unpacking blobuploader==1.2.4 09:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:29 INFO - Downloading blobuploader-1.2.4.tar.gz 09:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:57:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:57:29 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:57:29 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:57:29 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:57:29 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:57:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:57:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:57:29 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:57:29 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:57:29 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:57:29 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:57:30 INFO - Downloading docopt-0.6.1.tar.gz 09:57:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:57:30 INFO - Installing collected packages: blobuploader, requests, docopt 09:57:30 INFO - Running setup.py install for blobuploader 09:57:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:57:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:57:30 INFO - Running setup.py install for requests 09:57:30 INFO - Running setup.py install for docopt 09:57:30 INFO - Successfully installed blobuploader requests docopt 09:57:30 INFO - Cleaning up... 09:57:31 INFO - Return code: 0 09:57:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57:31 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:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:31 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:57:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:31 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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:31 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:57:31 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:57:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:31 INFO - 'HOME': '/Users/cltbld', 09:57:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:31 INFO - 'LOGNAME': 'cltbld', 09:57:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:31 INFO - 'MOZ_NO_REMOTE': '1', 09:57:31 INFO - 'NO_EM_RESTART': '1', 09:57:31 INFO - 'PAGER': '/bin/cat', 09:57:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:31 INFO - 'PWD': '/builds/slave/test', 09:57:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:31 INFO - 'SHELL': '/bin/bash', 09:57:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:31 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:31 INFO - 'USER': 'cltbld', 09:57:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:31 INFO - 'XPC_FLAGS': '0x0', 09:57:31 INFO - 'XPC_SERVICE_NAME': '0', 09:57:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:57:31 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-dh2XNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:57:31 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-mI7Qvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:57:31 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-sjAK0f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:57:31 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-n1qbti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:57:31 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ckfbYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:57:31 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Ky66fM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:57:31 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-emhQ4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:57:31 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-XOsiB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Vshpyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-P2kOYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-9APc2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-UP4OgC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ZJl14S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-I6oMZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-XGhGWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ZYNPZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:57:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:57:32 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-9QjKac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:57:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:57:33 INFO - Running setup.py install for manifestparser 09:57:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:57:33 INFO - Running setup.py install for mozcrash 09:57:33 INFO - Running setup.py install for mozdebug 09:57:33 INFO - Running setup.py install for mozdevice 09:57:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:57:33 INFO - Running setup.py install for mozfile 09:57:33 INFO - Running setup.py install for mozhttpd 09:57:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:57:33 INFO - Running setup.py install for mozinfo 09:57:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Running setup.py install for mozInstall 09:57:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Running setup.py install for mozleak 09:57:34 INFO - Running setup.py install for mozlog 09:57:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Running setup.py install for moznetwork 09:57:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Running setup.py install for mozprocess 09:57:34 INFO - Running setup.py install for mozprofile 09:57:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:57:34 INFO - Running setup.py install for mozrunner 09:57:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:57:35 INFO - Running setup.py install for mozscreenshot 09:57:35 INFO - Running setup.py install for moztest 09:57:35 INFO - Running setup.py install for mozversion 09:57:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:57:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:57:35 INFO - Cleaning up... 09:57:35 INFO - Return code: 0 09:57:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:35 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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:35 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:57:35 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:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:35 INFO - 'HOME': '/Users/cltbld', 09:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:35 INFO - 'LOGNAME': 'cltbld', 09:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:35 INFO - 'MOZ_NO_REMOTE': '1', 09:57:35 INFO - 'NO_EM_RESTART': '1', 09:57:35 INFO - 'PAGER': '/bin/cat', 09:57:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:35 INFO - 'PWD': '/builds/slave/test', 09:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:35 INFO - 'SHELL': '/bin/bash', 09:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:35 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:35 INFO - 'USER': 'cltbld', 09:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:35 INFO - 'XPC_FLAGS': '0x0', 09:57:35 INFO - 'XPC_SERVICE_NAME': '0', 09:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:57:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-D59bTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:57:35 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:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:57:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-BAQ0gy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:57:36 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:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-zvywKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:57:36 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:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-C_rNN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:57:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-RngJP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:57:36 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:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Z98nCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:57:36 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:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-9_gZBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:57:36 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:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-sRzJ8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:57:36 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:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-I7zeBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:57:36 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:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-HpYTTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:57:36 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:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:57:36 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-aqP781-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:57:37 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:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:57:37 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-mj681a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:57:37 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:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:57:37 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-OZt2MD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:57:37 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:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:57:37 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-BdffF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:57:37 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:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:37 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-48S9nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:57:37 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:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:57:37 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-2F0dlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:57:37 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:57:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:57:37 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-3PlMIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:57:37 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:57:37 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:57:37 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:57:37 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:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:57:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:57:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:57:37 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:57:37 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:57:37 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:57:37 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:57:39 INFO - Downloading blessings-1.6.tar.gz 09:57:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:57:39 INFO - Installing collected packages: blessings 09:57:39 INFO - Running setup.py install for blessings 09:57:39 INFO - Successfully installed blessings 09:57:39 INFO - Cleaning up... 09:57:39 INFO - Return code: 0 09:57:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:57:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:57:39 INFO - Reading from file tmpfile_stdout 09:57:39 INFO - Current package versions: 09:57:39 INFO - blessings == 1.6 09:57:39 INFO - blobuploader == 1.2.4 09:57:39 INFO - docopt == 0.6.1 09:57:39 INFO - functools32 == 3.2.3-2 09:57:39 INFO - jsonschema == 2.5.1 09:57:39 INFO - manifestparser == 1.1 09:57:39 INFO - mozInstall == 1.12 09:57:39 INFO - mozcrash == 1.0 09:57:39 INFO - mozdebug == 0.1 09:57:39 INFO - mozdevice == 0.51 09:57:39 INFO - mozfile == 1.2 09:57:39 INFO - mozhttpd == 0.7 09:57:39 INFO - mozinfo == 0.9 09:57:39 INFO - mozleak == 0.1 09:57:39 INFO - mozlog == 3.4 09:57:39 INFO - moznetwork == 0.27 09:57:39 INFO - mozprocess == 0.25 09:57:39 INFO - mozprofile == 0.28 09:57:39 INFO - mozrunner == 6.13 09:57:39 INFO - mozscreenshot == 0.1 09:57:39 INFO - mozsystemmonitor == 0.3 09:57:39 INFO - moztest == 0.8 09:57:39 INFO - mozversion == 1.4 09:57:39 INFO - psutil == 3.1.1 09:57:39 INFO - requests == 1.2.3 09:57:39 INFO - wsgiref == 0.1.2 09:57:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:57:39 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:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:39 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:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:39 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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:39 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:57:39 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:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:39 INFO - 'HOME': '/Users/cltbld', 09:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:39 INFO - 'LOGNAME': 'cltbld', 09:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:39 INFO - 'MOZ_NO_REMOTE': '1', 09:57:39 INFO - 'NO_EM_RESTART': '1', 09:57:39 INFO - 'PAGER': '/bin/cat', 09:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:39 INFO - 'PWD': '/builds/slave/test', 09:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:39 INFO - 'SHELL': '/bin/bash', 09:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:39 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:39 INFO - 'USER': 'cltbld', 09:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:39 INFO - 'XPC_FLAGS': '0x0', 09:57:39 INFO - 'XPC_SERVICE_NAME': '0', 09:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:39 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:57:39 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:57:39 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:57:39 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:57:39 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:57:39 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:57:39 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:57:39 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:57:39 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:57:39 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:57:39 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:57:39 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:57:39 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:57:39 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:57:41 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:57:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:57:41 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:57:41 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:57:41 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:57:41 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:57:41 INFO - Installing collected packages: marionette-driver 09:57:41 INFO - Running setup.py install for marionette-driver 09:57:41 INFO - Successfully installed marionette-driver 09:57:41 INFO - Cleaning up... 09:57:41 INFO - Return code: 0 09:57:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:57:41 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:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:41 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:57:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:41 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 0x10be26c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10be2a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad7ad7cc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, '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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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:57:41 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:57:41 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:57:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:57:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:41 INFO - 'HOME': '/Users/cltbld', 09:57:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:41 INFO - 'LOGNAME': 'cltbld', 09:57:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:41 INFO - 'MOZ_NO_REMOTE': '1', 09:57:41 INFO - 'NO_EM_RESTART': '1', 09:57:41 INFO - 'PAGER': '/bin/cat', 09:57:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:41 INFO - 'PWD': '/builds/slave/test', 09:57:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:41 INFO - 'SHELL': '/bin/bash', 09:57:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:57:41 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:57:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:41 INFO - 'USER': 'cltbld', 09:57:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:41 INFO - 'XPC_FLAGS': '0x0', 09:57:41 INFO - 'XPC_SERVICE_NAME': '0', 09:57:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:57:41 INFO - Cleaning up... 09:57:41 INFO - Return code: 0 09:57:41 INFO - Running post-action listener: _resource_record_post_action 09:57:41 INFO - Running post-action listener: _start_resource_monitoring 09:57:41 INFO - Starting resource monitoring. 09:57:41 INFO - [mozharness: 2017-06-03 16:57:41.955444Z] Finished create-virtualenv step (success) 09:57:41 INFO - [mozharness: 2017-06-03 16:57:41.956404Z] Running install step. 09:57:41 INFO - Running pre-action listener: _resource_record_pre_action 09:57:41 INFO - Running main action method: install 09:57:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:57:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:57:42 INFO - Reading from file tmpfile_stdout 09:57:42 INFO - Detecting whether we're running mozinstall >=1.0... 09:57:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:57:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:57:42 INFO - Reading from file tmpfile_stdout 09:57:42 INFO - Output received: 09:57:42 INFO - Usage: mozinstall [options] installer 09:57:42 INFO - Options: 09:57:42 INFO - -h, --help show this help message and exit 09:57:42 INFO - -d DEST, --destination=DEST 09:57:42 INFO - Directory to install application into. [default: 09:57:42 INFO - "/builds/slave/test"] 09:57:42 INFO - --app=APP Application being installed. [default: firefox] 09:57:42 INFO - mkdir: /builds/slave/test/build/application 09:57:42 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:57:42 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:58:04 INFO - Reading from file tmpfile_stdout 09:58:04 INFO - Output received: 09:58:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:58:04 INFO - Running post-action listener: _resource_record_post_action 09:58:04 INFO - [mozharness: 2017-06-03 16:58:04.269977Z] Finished install step (success) 09:58:04 INFO - [mozharness: 2017-06-03 16:58:04.270135Z] Running setup-mitmproxy step. 09:58:04 INFO - Running pre-action listener: _resource_record_pre_action 09:58:04 INFO - Running main action method: setup_mitmproxy 09:58:04 INFO - Skipping: mitmproxy is not required 09:58:04 INFO - Running post-action listener: _resource_record_post_action 09:58:04 INFO - [mozharness: 2017-06-03 16:58:04.270812Z] Finished setup-mitmproxy step (success) 09:58:04 INFO - [mozharness: 2017-06-03 16:58:04.270923Z] Running run-tests step. 09:58:04 INFO - Running pre-action listener: _resource_record_pre_action 09:58:04 INFO - Running main action method: run_tests 09:58:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:04 INFO - 2017-06-03 09:58:04.285 screenresolution[1636:8514] starting screenresolution argv=screenresolution get 09:58:04 INFO - 2017-06-03 09:58:04.288 screenresolution[1636:8514] Display 0: 1600x1200x32@60 09:58:04 INFO - 2017-06-03 09:58:04.294 screenresolution[1637:8516] starting screenresolution argv=screenresolution list 09:58:04 INFO - Available Modes on Display 0 09:58:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:04 INFO - Intel Iris: 09:58:04 INFO - Chipset Model: Intel Iris 09:58:04 INFO - Type: GPU 09:58:04 INFO - Bus: Built-In 09:58:04 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:04 INFO - Vendor: Intel (0x8086) 09:58:04 INFO - Device ID: 0x0a2e 09:58:04 INFO - Revision ID: 0x0009 09:58:04 INFO - Displays: 09:58:04 INFO - Display: 09:58:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:04 INFO - Display Serial Number: 1600x1200 60 09:58:04 INFO - Main Display: Yes 09:58:04 INFO - Mirror: Off 09:58:04 INFO - Online: Yes 09:58:04 INFO - Rotation: Supported 09:58:04 INFO - Return code: 0 09:58:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:58:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:58:04 INFO - Python 2.7.3 09:58:04 INFO - Return code: 0 09:58:04 INFO - grabbing minidump binary from tooltool 09:58:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:04 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 0x10bdafc60>, '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 0x10bb44cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bb6dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:58:04 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:58:04 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:58:04 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:58:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:58:04 INFO - Return code: 0 09:58:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:58:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:58:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:58:04 INFO - ENV: RUST_BACKTRACE is now 1 09:58:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:58:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:58:04 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/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--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:58:04 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/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0333 --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:58:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRr1fie3Rt/Render', 09:58:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:04 INFO - 'HOME': '/Users/cltbld', 09:58:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:04 INFO - 'LOGNAME': 'cltbld', 09:58:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:58:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:58:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:04 INFO - 'MOZ_NO_REMOTE': '1', 09:58:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:58:04 INFO - 'NO_EM_RESTART': '1', 09:58:04 INFO - 'PAGER': '/bin/cat', 09:58:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:04 INFO - 'PWD': '/builds/slave/test', 09:58:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:58:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:04 INFO - 'RUST_BACKTRACE': '1', 09:58:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:58:04 INFO - 'SHELL': '/bin/bash', 09:58:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fL9GzsL5Qw/Listeners', 09:58:04 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:58:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:04 INFO - 'USER': 'cltbld', 09:58:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:04 INFO - 'XPC_FLAGS': '0x0', 09:58:04 INFO - 'XPC_SERVICE_NAME': '0', 09:58:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:04 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/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--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:58:04 INFO - mozversion application_buildid: 20170603083646 09:58:04 INFO - mozversion application_changeset: 98f1390029f9bd558de991a53c92342ac0addfc4 09:58:04 INFO - mozversion application_display_name: Nightly 09:58:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:58:04 INFO - mozversion application_name: Firefox 09:58:04 INFO - mozversion application_remotingname: firefox 09:58:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:58:04 INFO - mozversion application_vendor: Mozilla 09:58:04 INFO - mozversion application_version: 55.0a1 09:58:04 INFO - mozversion platform_buildid: 20170603083646 09:58:04 INFO - mozversion platform_changeset: 98f1390029f9bd558de991a53c92342ac0addfc4 09:58:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:58:04 INFO - mozversion platform_version: 55.0a1 09:58:04 INFO - using testdate: 1496509084 09:58:04 INFO - actual date: 1496509084 09:58:04 INFO - starting webserver on 'localhost:49228' 09:58:04 INFO - SUITE-START | Running 2 tests 09:58:04 INFO - TEST-START | dromaeo_css 09:58:04 INFO - operating with platform_type : mac_ 09:58:04 INFO - Initialising browser for dromaeo_css test... 09:58:04 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmppyrVYE/profile 09:58:04 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 09:58:38 INFO - TEST-INFO | 1647: killed by SIGTERM 09:58:38 INFO - Browser initialized. 09:58:38 INFO - Running cycle 1/1 for dromaeo_css test... 09:58:38 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmppyrVYE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:58:40 INFO - PID 1655 | 2017-06-03 09:58:40.610 plugin-container[1656:9011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3b, name = 'com.apple.tsm.portname' 09:58:40 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:40 INFO - PID 1655 | 2017-06-03 09:58:40.611 plugin-container[1656:9011] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4623, name = 'com.apple.CFPasteboardClient' 09:58:40 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:40 INFO - PID 1655 | 2017-06-03 09:58:40.611 plugin-container[1656:9011] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:58:41 INFO - PID 1655 | 2017-06-03 09:58:41.292 plugin-container[1657:9056] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 09:58:41 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:41 INFO - PID 1655 | 2017-06-03 09:58:41.293 plugin-container[1657:9056] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 09:58:41 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:58:41 INFO - PID 1655 | 2017-06-03 09:58:41.293 plugin-container[1657:9056] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:58:50 INFO - PID 1655 | RSS: Main: 260509696 09:58:50 INFO - PID 1655 | 10:01:52 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 10:01:54 INFO - PID 1655 | RSS: Main: 268279808 10:01:54 INFO - PID 1655 | 10:04:56 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 10:04:56 INFO - PID 1655 | RSS: Main: 282193920 10:04:56 INFO - PID 1655 | 10:07:58 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 10:07:59 INFO - PID 1655 | RSS: Main: 282095616 10:07:59 INFO - PID 1655 | 10:11:01 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 10:11:01 INFO - PID 1655 | RSS: Main: 285843456 10:11:01 INFO - PID 1655 | 10:14:04 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 10:14:05 INFO - PID 1655 | RSS: Main: 286101504 10:14:05 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 10:17:07 INFO - PID 1655 | RSS: Main: 286437376 10:17:07 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | __start_tp_report 10:17:07 INFO - PID 1655 | _x_x_mozilla_page_load 10:17:07 INFO - PID 1655 | _x_x_mozilla_page_load_details 10:17:07 INFO - PID 1655 | |i|pagename|runs| 10:17:07 INFO - PID 1655 | |0;dojo.html;2829.8585070746462;3088.3668847886183;3616.312900548894;3621.5473065866768;3627.9455808162866;6129.878419756035;6156.846078848031;6211.658358790265;6236.5946213496145;6243.219597550306;5130.948690513093;5144.691318520891;6139.032198454753;6180.196574445327;6251.09359142924;5296.92054619181;5472.616916815835;5483.588730845183;5496.697681627529;5533.972330138364;5533.557315414777;5596.244506991565;5605.075162598187;5610.971945140269;5631.465010823965;5335.279737235482;5402.91895621566;5467.753951072212;5468.28912241408;5571.359293681238;5509.752061157217;5521.834344969658;5522.475364840334;5537.363203231599;5546.805861794858;5439.782408703687;5443.074677304828;5483.533899618497;5520.1443776214755;5536.335639723218;4585.564371384713;6133.304060594571;6197.752089916398;6242.938700420894;6264.27960784511;6626.768063117768;6737.6968036438475;6741.898871516931;6756.966215168942;6762.289959554225;6756.93243067568;6792.694328755218;6817.011533327689;6828.419584335337;6835.453163746888;6175.980963141121;6269;6274.968625156845;6294.464970477515;6294.65379404128;6711.932880671131;6885.793426197222;6958.234594194634;6973.686184121625;7011.544249623758;6426.710798014007;6494.155759751202;6514.446272066927;6515.7393704252245;6597.60414375139;6807.829804254834;6838.350356716104;6844.21291551468;6854.862902741917;6868.656567171722;6448.709808058648;6709.027191057269;6725.233869033259;6727.9327206728285;6731.49513786468;6493.285738568754;6597.8350541235895;6636.767713130017;6701.0953521274005;6723.63020033893;5368.91946620801;5375.301210842644;5377.139657654757;5383.892322153535;5392.811251606175;4779.235322348477;4780.091782561302;4784.234522476387;4791.353167322437;4803.375561177025;6160.44555989963;6235.532335074888;6291.591046581958;6303.2751233607805;6325.746970121236;6852;6884.967254911803;6895.241523432519;6900.7929762107215;6927.549709268881;6320.4311611955145;6353.650549219745;6370.74517019314;6385.808425747235;6397.328280530571;5898.115282707542;5986.401359864066;6034.094885767082;6086.330503644595;6119.5410344224365;6968.024476573284;7002.089728335284;7018.75434359795;7020.964895175492;7032.507724459443;6820.3861652451515;6864.725410983505;6881.759138430131;6891.27641597625;6893.620850853251;6768.153980752406;6861.965690171717;6920.169579650475;7019.122609673791;7037.577745335297;5277.630565860353;6414.684989577032;6749.898751518829;6772.288909664414;6775.390214880685;6820.045193672989;6893.689783959633;6898.310168983062;6900.206476255295;6912.239653638197;6418.711157997808;6473.093766872736;6563.507736919654;6640.867182656223;6655.600663960178;4961.82633607822;6630.038644396439;6647.069410282661;6682.164729408824;6682.766103186365;6469.223693156663;6514.250861151028;6523.67381630907;6528.477721782341;6535.836604084936;6390.52071094679;6484.157059582224;6559.8032059038305;6596.57122287069;6665.733370665119;6439.549231553746;6475.740970361133;6477.255115661571;6487.675616219076;6513.055606937062 10:17:07 INFO - PID 1655 | |1;ext.html;742.3467348733114;760.6881178716724;826.813966857457;1259.3073809404825;1328.8338957630297;38581.03547411316;39572.604273957324;39682.40476392856;39689.80155099228;39737.20525589486;39718.801405993;40174;40274.7986260069;40301;40347.19305613886;7927.643256053477;8028.879566806502;8360.122187170351;8548.78628034301;8590.324886647106;8497.107803680618;8526.062133165378;8619.525926074064;8622.267107295887;8630.050694423608;8519.446235994672;8548.529830859301;8574.142585741407;8603.870941935877;8628.050914399411;8580.39937204396;8600.311975041981;8609.655613775441;8611.569421528899;8636.481811091291;8408.32733381328;8413.95793021037;8427.915720842775;8474.322054235647;8481.872771908425;37217;37368.43947340792;37376.81311593452;37514;37611.62388376109;3599.6940260077927;3645.6172101929183;3646.1978364759975;3646.2889736501393;3646.453032045188;30455.847720761474;30856.228594285098;30876.073717788502;30945.845270773727;30950.845245773628;3095.4428202923464;3139.4035133324596;3150.6376766671897;3156.4160630283473;3169.8098114112995;240632;242556;281927;299814;300712;48484.757576211894;48570.27144592834;48809;48810.755946220044;48920.26619600709;51254.74372628113;51292.48707512952;51359;53352.733236333566;54414.45585544173;14629.049111807912;14672.3397447113;14730.779038314435;14756.778648320284;14773.33519991603;11748.471318790675;11845.940770296093;11846.644600661994;11848;11857.703557411134;13944.790828137586;14106.506272280421;14112.788308175386;14130.646733831736;14196.432142714382;6156.8768624627255;6161.784337548164;6183.598066125687;6215.627062376181;6223.377662233831;779.8986131802943;842.0358689300716;937.2854541655483;986.7483791633088;997.8453339732353;22725.204617838295;23540.882295588755;24077.879610601834;24463.877680611484;24827;1068.9305195162444;1077.52050337599;1131.145984781501;1131.8245671920865;1132.28665940457;9971.603975443444;11333.320000799813;11917.34454605005;12481.063920206021;12710.42803073864;321394;322478;323368;323630;324035;41570;54361.1845822313;54523.727381363635;54661.72669136708;54679.72660136753;1486.108334998992;1499.71505413971;1534.5856618712885;1538.0617823127884;1540.6302487402943;22890;22934.967926443016;23777.881110594335;24159.75840241575;24740.876295618407;38108.428373573865;38129.809350953066;38223.426648599736;38276.23447530978;38293;269400;272522;273162;273324;274090;30510.389792204474;33751.649934002366;35509;35679.92960211198;35884;42842.14315713606;44307.33538996853;46083.30875036944;46147.84630384267;46192.53807462017;29271.73108840344;29481.377839310688;30423.239419014702;31487.842560787965;31527.886842340722;30113.81454838469;30337.848310758305;31104.844475777478;31363.05910822679;31682.683173167974;36614.90155295345;37237.6276237245;37340.6265937348;37387.813060934524;37516.81241593775;27995.86002070058;31126.22184445407;31551.84224078865;32228.033159005266;32618.673813262514;20567.074481648662;24622;25157.11950081738;25244.368890777878;25257.62113568335 10:17:07 INFO - PID 1655 | |2;jquery.html;426.9837786069155;432.4076015858275;436.273604448593;447.6284683712517;449.77736020669767;14555.490557830479;15253.31360088796;21850.125994960203;22635.320940371774;22696.205632802834;20703.37889863303;23185.536289274205;23413.297601071954;23426.180083697072;23500.29499115025;6082.361352058037;6238.376162383748;6284.402981716729;6296.464800491941;6297.464715499188;5570.136628822519;5576.191452239423;5627.240322556443;5641.548676105902;5653.575981801361;5504.284443022401;5567.972160139194;5600.635958662694;6052.636841789485;6120.990036643971;6034.909476357858;6089.056196289582;6094.055421409666;6094.878102437949;6097.634141951497;5351.518363347317;5411.269478620376;5412.891742165135;5415.13357862744;5417.187421886709;21491.67762483564;25393.873030634728;27480.862595687093;27760.583591246148;28119.188081191718;2205.911763529457;2220.3893929169444;2251.7860803223666;2263.7170353705787;2309.9653505197434;19458.416247512596;19482.220711171536;19778;19848.603027939505;19859.21970924072;2014.1943222710886;2294.4608017115966;2302.481941563152;2354.7880690737743;2386.284114765581;163502;200404.99392509123;205239;208039;208541;24540.87729561341;24551.122381235044;24671.62992555113;25254.055134837698;25699.48601027969;42439;42682.78658606687;42798.28806847691;42895.498657546836;42900.3564946526;11166.60916867922;11311.94344028291;11322.433878306052;11340.376279304552;11460.598879039193;8970.147835955573;9039.63841446348;9080.663820959759;9138.406003609743;9152.90847091534;7717.8456430871065;11727;11856.88143118575;11979.401029948467;12042.518299268106;2258.9322320330416;2270.8069814065657;2279.669447930074;2283.372072680026;2467.6668649732414;829.4276948905236;922.294444749767;939.910708482693;992.3477410112082;999.6950929966284;15066.771102279143;17207.65584688324;19531;20204.595908081756;20345.796542034685;955.3121752338305;969.195567678839;978.6183388478499;1010.8584798128268;1017.6641708236265;10715.357078575154;11054.447277636085;11093;11104.833427498594;11268.830967535494;512965;516358;517839;528696;529940.350298246;146838;149846.25076874546;150941.24529377284;151236;152926;1148.3913525831322;1175.9000484958704;1175.905927525796;1186.833843261944;1192.7077865922904;18060.729089063938;18143.27638874305;19168.520786980436;21286.467838304165;23221.3033608992;69698.30301697121;69773.65113174402;71332;71407;71916.64041679759;87655.56172219098;92523;92734.53632731792;92988;93508;10077.294589378966;10209;10232.334898231591;10378.896211037792;10400.063994240554;13295.335233238493;14050;16986.725995550136;18185.909070454563;18211.726824097383;14229.786553201504;14770.926145369634;14849.851501484847;14852.702945940804;14902.850971490147;11137.944310278397;12540.05949553769;12857;13051.21692698441;13358.799618005543;14666.926665366605;14839.629009274855;14953.626159346104;15140.924295378452;15158.090514569161;9816.950915245377;10181.490925453845;10270.229732770222;10278.948605257225;10327.638532651321;13717.794233086312;14743.410263589336;14862;14960.326785294905;14965.775513367525 10:17:07 INFO - PID 1655 | |3;mootools.html;1210.642860356195;1443.9855601443983;1445.1618061524316;1455.7306898223828;1473.167660271946;11609.245399049067;11624;12049.638510844688;12118.878811211896;12393.25640461573;9808.85286720698;12044.51821927124;12068.69828254294;12232.877671223285;12243.938780306085;2552.8468291902545;2594.5589249827576;2596.1822026061823;2596.208156512263;2599.2462185966046;2513.8743062846784;2530.380056886056;2534.0497313507435;2550.477152183807;2553.297843093155;2501.137107697841;2512.321673148249;2522.798176145904;2530.8481491110592;2532.645429639843;2477.355887469262;2497.3382053755768;2507.849529028264;2511.1211076123395;2514.484530671208;2465.0632759551436;2484.86938443008;2491.5266099441046;2506.8245222834407;2556.0670355320176;11690.298582085017;11742.530298788039;11828.290302581872;11845.881541184564;11864;6458.773942911976;6670.299618540032;6753.561018533829;6759.662016899136;6783.966080169567;4097;4276.444062271885;4314.374415709736;4344.391785150082;4362.36745671879;1261.9621411357673;1265.7658333208385;1266.208619612742;1293.3016171267504;1296.2222666400085;15390.0765954042;15759.763603545956;16403.58991025222;16567.25447354872;16643.50069497917;5580.158547679256;5608.523275521545;5808.477237048686;5822;5830.271216097988;12190.2076365036;12266.877331226751;12443.813342799865;12463.065270104778;12485.126041177213;6504.5446818723185;6536.444402225864;6620.238672552622;6631.336866313427;6653.600783952881;6056.545759068087;6168.814935551941;6191.349908259658;6261.968690156611;6269.059641053787;4346.2828633275785;4398.64810815134;4432.246518091933;4465.173942820588;4466.151431228056;1278.2011242973142;1289.2908900104906;1293.6636474516692;1306.3664122900386;1310.351376068852;810.2140923304386;819.4632515702225;819.8358331169753;825.3644693585906;828.9129641387567;6375.4262116409745;6819.420349270268;7506.836440351754;7734.845303093906;7874.50384426957;830.9127541608165;943.8961714211431;982.5676702250987;989.6833013435631;994.3834822410151;4684.672072954929;4694.967107236403;4962.826301079517;4964.404271487445;5004.224345226496;14449.78325325121;14601.853981460263;15232.314545845464;15308.923455382874;15344.232788360761;14771.852281477262;15232.543023709306;15260.008099473498;15337.693246135015;15419.460318889009;8470.025947016173;8619.61211745473;8663.610137543825;8681.739547813575;8796.912030879737;6863.416609588241;7295.197528271779;7545.358644515278;7749.263819937097;7872.527648341118;12826.743465130832;12880;12896.161749486253;13018.869811301765;13028.28344441027;13314.267715275753;13810.516631917835;13909;14216.659085114783;14230.501932432315;9638.710838674851;9792.902070979198;9846.212303015885;9854.901450985684;9859.605615775288;10780.29928054674;10880.510377032893;10895.400752958347;10937.726556835825;10991.780164396507;9117.544122793966;9175.311851611023;9177.311701622271;9298.070192980647;9379.390339627902;9060.637574496946;9065.864012039956;9105.27157827359;9117.316201285063;9319.30105242096;8225.95887020561;8277.95861020691;8395.160483951557;8406.243438090602;8423.410361274651;6044.395560443921;6149.723262453111;6402.231732192166;6417.839554011187;6525.575837570543;5362.839114826562;6337.397947195009;6545.21457425112;6639.468842492685;6663.300353463011 10:17:07 INFO - PID 1655 | |4;prototype.html;380.5699559497769;398.47799382808523;399.89602703297135;406.87793661901424;407.7675724836843;6256.812295631127;6320.905186422196;6345.206849143857;6350.936490635092;6409.583377080492;6056.27324721033;6096.969515152429;6175.783847565336;6192.566520343577;6217.5025997920175;4574.794134263966;4583.8166473341025;4602.792874320664;4603.838865639704;4606.723596584199;3845.38473844185;4529.026259354238;4578.51925547816;4598.609118224938;4624;4547.681172459999;4554.724677090403;4555.134524440362;4579.267317229244;4584.472785629662;3918;4057.857974970862;4168.958310416887;4543.204939135638;4549.384968336242;3932.292187406266;3932.921341573181;3940.9605903941165;3945.8027098644952;3949.8025098745234;5428.91856622147;5708.828735137952;6128.203333566652;6150.938490615082;6163.2604087509335;5368.32895888014;5475.069238229471;5477.452254774531;5489.451054894478;5500.532454741352;6610.405063544256;6650.833729156761;6735.7642482513365;6742.2246441659345;6760.594364338155;3752.4934133891848;4462.553744625544;4465.575770301849;4466.82132714691;4475.641948644134;6670.666466676696;6751.628660423675;6756.662166891636;7010.404115650227;7171.641417929082;3718.2377612589894;4171.457710497616;4466.285394336891;4559.270516717375;4564.680472366902;4632.467266264355;4636.768161591975;4639.443266807937;4649.883752906137;4652.95347046532;5263.368395792453;5411.648242864201;5426.158945363475;5451.754671039813;5456.563474921997;6585.275619681831;6646.169228846395;6704.195496540349;6704.765333213314;6730.4952128589575;1443.3577058208996;1477.5372381342393;1577.8106627204809;1586.3416682076809;1594.2347673116737;1013.5388398278766;1042.103790739963;1049.7480604654827;1054.1672079057612;1060.753614502956;6332.968335158294;6358.872822543523;6376.776812811529;6381.468447572549;6402.519811014099;6130.63216207029;6454.935450645527;6935.479839012094;6993.685284162225;7032.8945065824055;5342.679439233659;6066.939330606726;6277.435030847246;6293.779717709858;6307.873842523124;3278.5246139309666;3280.3439312137666;3308.8749825059595;3617.692496137805;3631.5460567429072;6508.674566271668;6524.869502610016;7142.21435642079;7200.855982880313;7221.24176961422;7172.175199852084;7205.89191162133;7212.134543854771;7218.278172182845;7268.328284494596;5671.858203544944;6270.811875643738;7167.713291468387;7182.96408517954;7184.245654206337;5461.907618476241;6422.100905873088;6547.006326141145;6945.201301850352;6970.407515361251;5859.00396932514;6752.358525940224;6872.896906546506;6900.93099069023;6908.620025898423;6724.596524208563;7131.179914309921;7203.495755297288;7233.927660723325;7247.883410133693;4554.679143048479;4561.654311977908;4562.429696287964;4571.1771881060395;4582.1293954148605;6948.5483443576;6999.895001574879;7018.508704390643;7044.542104763174;7103.680334384861;6546.869062618625;6695.230048544479;6698.866022679616;6718.8320291993095;6779.35596118368;5239.26650268955;6110.808392363624;6565;6719;6768.2216545097945;5438.564914806885;5772.6536407815665;6477.352264773485;6552.215308493966;6599.967000164968;6472.676366181578;6604.877170880865;6690.431313338421;6695.397414232742;6739.764108256188;6481.8703625928165;6529.804105876831;6535.705893234911;6549.803505894831;6561.278259391368 10:17:07 INFO - PID 1655 | |5;yui.html;831.8835363049178;835.4360806455642;842.9452085614435;860.7605048729829;875.492214515581;14574.417023319054;15152.469663561778;16871.98768073915;17432.564185895342;17449.04030278334;16119.758203626925;17484.47546573605;17999.460016199504;18084.457466276;18140;822.1942496353577;976.2482888176118;999.5951639585979;1001.4141727089661;1001.419176877413;990.5889056041771;1000.2897942460861;1000.924930630202;1003.6085926488639;1012.7620009297809;892.7946572288378;951.6431338248157;1038.85456036155;1049.4385503755493;1062.9681109566725;1043.4991204221978;1049.171154787717;1050.5797680927615;1051.0225490294024;1053.531178625512;747.8317378589828;758.5221310574358;761.46316846623;763.412172627074;763.6372722956551;16870.746938795928;16948.745768813355;16980.66038679232;17029;17050.48848534546;17528.21123049453;17626.735598966025;17935.910320448314;17957.64084718298;17992.550186245317;14681.412743490248;14794.186319752409;14980.325885335185;14992.325345359484;15013.24933753308;3556.342076715816;3590.35373632746;3609.3864043112735;3614.981925090384;3616.782993020427;155210;157117;159009;159200.40799591973;159327.99999999884;13729.862701373058;13863.098898571561;13875.930620346833;13912.51306204278;14050.227237501831;26278.6058209127;27019.1894243173;27214.863925680642;27416.314592135357;27445.90216391322;6783.864322713617;7034.613096279753;7613.200613935568;7849.332806711379;7895.960520197362;3966.4446977423186;4041.8989525261513;4061.756294622332;4065.207284579538;4071;4218.936715949263;4290.957090429119;4313.202057619351;4321.848735294249;4328.307470804656;939.0984654731398;942.0767647705287;949.0556895888635;954.5227386306847;965.5654955270156;647.3267801486495;665.2416245480157;671.2884342596863;674.2246416620827;691.7198534593498;9479.857802132974;11291.3789741565;11794.764104717857;12142.45358958849;12570.497180112692;619.5477301569789;643.7939859244997;652.1326635574769;673.9595624262462;689.3588962265142;5461.726913654381;5516.834494965157;5744.396838331999;5745.224394706661;5878.676672783038;112936.30595541073;133306;136223.31888340495;142460;144954.82567761492;121499;121536.2303058491;124999;125466;126420.36789815992;110987.89012109775;110991.89008109977;111407.44296278467;111627;111730.88269117205;11068.889311107108;11268.605598804003;11415;11744.942955133738;11781.410929453321;46308;47024;47162.056758863946;47334.57996260118;47751.32870349521;62764.43088922631;63188;63402.36597633964;63572.364276358494;64492.355076448635;14302.642433939234;14352.138871667734;14800.407983680532;14812.62968425798;14852.183129927958;15645.217739113226;16482.34070637209;17468.388606398716;18383.080845957917;19018.334358297394;17262.309507620055;17465;17604.471865844047;17610.823891761433;17712.202950866984;16995.23521441563;17260.22328995173;17460.91269543644;17568.297268109643;17588.032658203923;14991.925040374728;16672.916635416746;16989.235484403478;17360.652786943938;18237.270509179485;11049.28179668319;11458.77082458363;11547.595834145764;12309.56916507918;12547.435365408395;12121.967265405381;12810.295433751233;12968.221906685629;13665.590032299047;13877.583672489842 10:17:07 INFO - PID 1655 | __end_tp_report 10:17:07 INFO - PID 1655 | __start_cc_report 10:17:07 INFO - PID 1655 | _x_x_mozilla_cycle_collect,454 10:17:07 INFO - PID 1655 | __end_cc_report 10:17:07 INFO - PID 1655 | __startTimestamp1496510227317__endTimestamp 10:17:07 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | ------- Summary: start ------- 10:17:07 INFO - PID 1655 | Number of tests: 6 10:17:07 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | [#0] dojo.html Cycles:165 Average:6190.08 Median:6448.71 stddev:787.46 (12.2%) stddev-sans-first:744.45 10:17:07 INFO - PID 1655 | Values: 2829.9 3088.4 3616.3 3621.5 3627.9 6129.9 6156.8 6211.7 6236.6 6243.2 5130.9 5144.7 6139.0 6180.2 6251.1 5296.9 5472.6 5483.6 5496.7 5534.0 5533.6 5596.2 5605.1 5611.0 5631.5 5335.3 5402.9 5467.8 5468.3 5571.4 5509.8 5521.8 5522.5 5537.4 5546.8 5439.8 5443.1 5483.5 5520.1 5536.3 4585.6 6133.3 6197.8 6242.9 6264.3 6626.8 6737.7 6741.9 6757.0 6762.3 6756.9 6792.7 6817.0 6828.4 6835.5 6176.0 6269.0 6275.0 6294.5 6294.7 6711.9 6885.8 6958.2 6973.7 7011.5 6426.7 6494.2 6514.4 6515.7 6597.6 6807.8 6838.4 6844.2 6854.9 6868.7 6448.7 6709.0 6725.2 6727.9 6731.5 6493.3 6597.8 6636.8 6701.1 6723.6 5368.9 5375.3 5377.1 5383.9 5392.8 4779.2 4780.1 4784.2 4791.4 4803.4 6160.4 6235.5 6291.6 6303.3 6325.7 6852.0 6885.0 6895.2 6900.8 6927.5 6320.4 6353.7 6370.7 6385.8 6397.3 5898.1 5986.4 6034.1 6086.3 6119.5 6968.0 7002.1 7018.8 7021.0 7032.5 6820.4 6864.7 6881.8 6891.3 6893.6 6768.2 6862.0 6920.2 7019.1 7037.6 5277.6 6414.7 6749.9 6772.3 6775.4 6820.0 6893.7 6898.3 6900.2 6912.2 6418.7 6473.1 6563.5 6640.9 6655.6 4961.8 6630.0 6647.1 6682.2 6682.8 6469.2 6514.3 6523.7 6528.5 6535.8 6390.5 6484.2 6559.8 6596.6 6665.7 6439.5 6475.7 6477.3 6487.7 6513.1 10:17:07 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | [#1] ext.html Cycles:180 Average:44467.90 Median:19741.85 stddev:76189.99 (385.9%) stddev-sans-first:76332.99 10:17:07 INFO - PID 1655 | Values: 742.3 760.7 826.8 1259.3 1328.8 38581.0 39572.6 39682.4 39689.8 39737.2 39718.8 40174.0 40274.8 40301.0 40347.2 7927.6 8028.9 8360.1 8548.8 8590.3 8497.1 8526.1 8619.5 8622.3 8630.1 8519.4 8548.5 8574.1 8603.9 8628.1 8580.4 8600.3 8609.7 8611.6 8636.5 8408.3 8414.0 8427.9 8474.3 8481.9 37217.0 37368.4 37376.8 37514.0 37611.6 3599.7 3645.6 3646.2 3646.3 3646.5 30455.8 30856.2 30876.1 30945.8 30950.8 3095.4 3139.4 3150.6 3156.4 3169.8 240632.0 242556.0 281927.0 299814.0 300712.0 48484.8 48570.3 48809.0 48810.8 48920.3 51254.7 51292.5 51359.0 53352.7 54414.5 14629.0 14672.3 14730.8 14756.8 14773.3 11748.5 11845.9 11846.6 11848.0 11857.7 13944.8 14106.5 14112.8 14130.6 14196.4 6156.9 6161.8 6183.6 6215.6 6223.4 779.9 842.0 937.3 986.7 997.8 22725.2 23540.9 24077.9 24463.9 24827.0 1068.9 1077.5 1131.1 1131.8 1132.3 9971.6 11333.3 11917.3 12481.1 12710.4 321394.0 322478.0 323368.0 323630.0 324035.0 41570.0 54361.2 54523.7 54661.7 54679.7 1486.1 1499.7 1534.6 1538.1 1540.6 22890.0 22935.0 23777.9 24159.8 24740.9 38108.4 38129.8 38223.4 38276.2 38293.0 269400.0 272522.0 273162.0 273324.0 274090.0 30510.4 33751.6 35509.0 35679.9 35884.0 42842.1 44307.3 46083.3 46147.8 46192.5 29271.7 29481.4 30423.2 31487.8 31527.9 30113.8 30337.8 31104.8 31363.1 31682.7 36614.9 37237.6 37340.6 37387.8 37516.8 27995.9 31126.2 31551.8 32228.0 32618.7 20567.1 24622.0 25157.1 25244.4 25257.6 10:17:07 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | [#2] jquery.html Cycles:180 Average:38915.56 Median:22665.76 stddev:91463.30 (403.5%) stddev-sans-first:91674.22 10:17:07 INFO - PID 1655 | Values: 427.0 432.4 436.3 447.6 449.8 14555.5 15253.3 21850.1 22635.3 22696.2 20703.4 23185.5 23413.3 23426.2 23500.3 6082.4 6238.4 6284.4 6296.5 6297.5 5570.1 5576.2 5627.2 5641.5 5653.6 5504.3 5568.0 5600.6 6052.6 6121.0 6034.9 6089.1 6094.1 6094.9 6097.6 5351.5 5411.3 5412.9 5415.1 5417.2 21491.7 25393.9 27480.9 27760.6 28119.2 2205.9 2220.4 2251.8 2263.7 2310.0 19458.4 19482.2 19778.0 19848.6 19859.2 2014.2 2294.5 2302.5 2354.8 2386.3 163502.0 200405.0 205239.0 208039.0 208541.0 24540.9 24551.1 24671.6 25254.1 25699.5 42439.0 42682.8 42798.3 42895.5 42900.4 11166.6 11311.9 11322.4 11340.4 11460.6 8970.1 9039.6 9080.7 9138.4 9152.9 7717.8 11727.0 11856.9 11979.4 12042.5 2258.9 2270.8 2279.7 2283.4 2467.7 829.4 922.3 939.9 992.3 999.7 15066.8 17207.7 19531.0 20204.6 20345.8 955.3 969.2 978.6 1010.9 1017.7 10715.4 11054.4 11093.0 11104.8 11268.8 512965.0 516358.0 517839.0 528696.0 529940.4 146838.0 149846.3 150941.2 151236.0 152926.0 1148.4 1175.9 1175.9 1186.8 1192.7 18060.7 18143.3 19168.5 21286.5 23221.3 69698.3 69773.7 71332.0 71407.0 71916.6 87655.6 92523.0 92734.5 92988.0 93508.0 10077.3 10209.0 10232.3 10378.9 10400.1 13295.3 14050.0 16986.7 18185.9 18211.7 14229.8 14770.9 14849.9 14852.7 14902.9 11137.9 12540.1 12857.0 13051.2 13358.8 14666.9 14839.6 14953.6 15140.9 15158.1 9817.0 10181.5 10270.2 10278.9 10327.6 13717.8 14743.4 14862.0 14960.3 14965.8 10:17:07 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | [#3] mootools.html Cycles:180 Average:7264.44 Median:4295.41 stddev:4505.15 (104.9%) stddev-sans-first:4494.81 10:17:07 INFO - PID 1655 | Values: 1210.6 1444.0 1445.2 1455.7 1473.2 11609.2 11624.0 12049.6 12118.9 12393.3 9808.9 12044.5 12068.7 12232.9 12243.9 2552.8 2594.6 2596.2 2596.2 2599.2 2513.9 2530.4 2534.0 2550.5 2553.3 2501.1 2512.3 2522.8 2530.8 2532.6 2477.4 2497.3 2507.8 2511.1 2514.5 2465.1 2484.9 2491.5 2506.8 2556.1 11690.3 11742.5 11828.3 11845.9 11864.0 6458.8 6670.3 6753.6 6759.7 6784.0 4097.0 4276.4 4314.4 4344.4 4362.4 1262.0 1265.8 1266.2 1293.3 1296.2 15390.1 15759.8 16403.6 16567.3 16643.5 5580.2 5608.5 5808.5 5822.0 5830.3 12190.2 12266.9 12443.8 12463.1 12485.1 6504.5 6536.4 6620.2 6631.3 6653.6 6056.5 6168.8 6191.3 6262.0 6269.1 4346.3 4398.6 4432.2 4465.2 4466.2 1278.2 1289.3 1293.7 1306.4 1310.4 810.2 819.5 819.8 825.4 828.9 6375.4 6819.4 7506.8 7734.8 7874.5 830.9 943.9 982.6 989.7 994.4 4684.7 4695.0 4962.8 4964.4 5004.2 14449.8 14601.9 15232.3 15308.9 15344.2 14771.9 15232.5 15260.0 15337.7 15419.5 8470.0 8619.6 8663.6 8681.7 8796.9 6863.4 7295.2 7545.4 7749.3 7872.5 12826.7 12880.0 12896.2 13018.9 13028.3 13314.3 13810.5 13909.0 14216.7 14230.5 9638.7 9792.9 9846.2 9854.9 9859.6 10780.3 10880.5 10895.4 10937.7 10991.8 9117.5 9175.3 9177.3 9298.1 9379.4 9060.6 9065.9 9105.3 9117.3 9319.3 8226.0 8278.0 8395.2 8406.2 8423.4 6044.4 6149.7 6402.2 6417.8 6525.6 5362.8 6337.4 6545.2 6639.5 6663.3 10:17:07 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | [#4] prototype.html Cycles:180 Average:5418.34 Median:6157.10 stddev:1722.52 (28.0%) stddev-sans-first:1685.34 10:17:07 INFO - PID 1655 | Values: 380.6 398.5 399.9 406.9 407.8 6256.8 6320.9 6345.2 6350.9 6409.6 6056.3 6097.0 6175.8 6192.6 6217.5 4574.8 4583.8 4602.8 4603.8 4606.7 3845.4 4529.0 4578.5 4598.6 4624.0 4547.7 4554.7 4555.1 4579.3 4584.5 3918.0 4057.9 4169.0 4543.2 4549.4 3932.3 3932.9 3941.0 3945.8 3949.8 5428.9 5708.8 6128.2 6150.9 6163.3 5368.3 5475.1 5477.5 5489.5 5500.5 6610.4 6650.8 6735.8 6742.2 6760.6 3752.5 4462.6 4465.6 4466.8 4475.6 6670.7 6751.6 6756.7 7010.4 7171.6 3718.2 4171.5 4466.3 4559.3 4564.7 4632.5 4636.8 4639.4 4649.9 4653.0 5263.4 5411.6 5426.2 5451.8 5456.6 6585.3 6646.2 6704.2 6704.8 6730.5 1443.4 1477.5 1577.8 1586.3 1594.2 1013.5 1042.1 1049.7 1054.2 1060.8 6333.0 6358.9 6376.8 6381.5 6402.5 6130.6 6454.9 6935.5 6993.7 7032.9 5342.7 6066.9 6277.4 6293.8 6307.9 3278.5 3280.3 3308.9 3617.7 3631.5 6508.7 6524.9 7142.2 7200.9 7221.2 7172.2 7205.9 7212.1 7218.3 7268.3 5671.9 6270.8 7167.7 7183.0 7184.2 5461.9 6422.1 6547.0 6945.2 6970.4 5859.0 6752.4 6872.9 6900.9 6908.6 6724.6 7131.2 7203.5 7233.9 7247.9 4554.7 4561.7 4562.4 4571.2 4582.1 6948.5 6999.9 7018.5 7044.5 7103.7 6546.9 6695.2 6698.9 6718.8 6779.4 5239.3 6110.8 6565.0 6719.0 6768.2 5438.6 5772.7 6477.4 6552.2 6600.0 6472.7 6604.9 6690.4 6695.4 6739.8 6481.9 6529.8 6535.7 6549.8 6561.3 10:17:07 INFO - PID 1655 | 10:17:07 INFO - PID 1655 | [#5] yui.html Cycles:180 Average:25746.57 Median:17476.43 stddev:40131.23 (229.6%) stddev-sans-first:40200.21 10:17:07 INFO - PID 1655 | Values: 831.9 835.4 842.9 860.8 875.5 14574.4 15152.5 16872.0 17432.6 17449.0 16119.8 17484.5 17999.5 18084.5 18140.0 822.2 976.2 999.6 1001.4 1001.4 990.6 1000.3 1000.9 1003.6 1012.8 892.8 951.6 1038.9 1049.4 1063.0 1043.5 1049.2 1050.6 1051.0 1053.5 747.8 758.5 761.5 763.4 763.6 16870.7 16948.7 16980.7 17029.0 17050.5 17528.2 17626.7 17935.9 17957.6 17992.6 14681.4 14794.2 14980.3 14992.3 15013.2 3556.3 3590.4 3609.4 3615.0 3616.8 155210.0 157117.0 159009.0 159200.4 159328.0 13729.9 13863.1 13875.9 13912.5 14050.2 26278.6 27019.2 27214.9 27416.3 27445.9 6783.9 7034.6 7613.2 7849.3 7896.0 3966.4 4041.9 4061.8 4065.2 4071.0 4218.9 4291.0 4313.2 4321.8 4328.3 939.1 942.1 949.1 954.5 965.6 647.3 665.2 671.3 674.2 691.7 9479.9 11291.4 11794.8 12142.5 12570.5 619.5 643.8 652.1 674.0 689.4 5461.7 5516.8 5744.4 5745.2 5878.7 112936.3 133306.0 136223.3 142460.0 144954.8 121499.0 121536.2 124999.0 125466.0 126420.4 110987.9 110991.9 111407.4 111627.0 111730.9 11068.9 11268.6 11415.0 11744.9 11781.4 46308.0 47024.0 47162.1 47334.6 47751.3 62764.4 63188.0 63402.4 63572.4 64492.4 14302.6 14352.1 14800.4 14812.6 14852.2 15645.2 16482.3 17468.4 18383.1 19018.3 17262.3 17465.0 17604.5 17610.8 17712.2 16995.2 17260.2 17460.9 17568.3 17588.0 14991.9 16672.9 16989.2 17360.7 18237.3 11049.3 11458.8 11547.6 12309.6 12547.4 12122.0 12810.3 12968.2 13665.6 13877.6 10:17:07 INFO - PID 1655 | -------- Summary: end -------- 10:17:07 INFO - PID 1655 | 10:17:07 INFO - TEST-INFO | 1655: exit 0 10:17:07 INFO - TEST-OK | dromaeo_css | took 1142994ms 10:17:07 INFO - TEST-START | kraken 10:17:07 INFO - operating with platform_type : mac_ 10:17:07 INFO - Initialising browser for kraken test... 10:17:07 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmplU7fDw/profile 10:17:07 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 10:17:41 INFO - TEST-INFO | 1664: killed by SIGTERM 10:17:41 INFO - Browser initialized. 10:17:41 INFO - Running cycle 1/1 for kraken test... 10:17:41 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmplU7fDw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:43 INFO - PID 1668 | 2017-06-03 10:17:43.914 plugin-container[1669:11535] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 10:17:43 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:17:43 INFO - PID 1668 | 2017-06-03 10:17:43.915 plugin-container[1669:11535] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 10:17:43 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:17:43 INFO - PID 1668 | 2017-06-03 10:17:43.915 plugin-container[1669:11535] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:17:44 INFO - PID 1668 | 2017-06-03 10:17:44.469 plugin-container[1670:11582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963b, name = 'com.apple.tsm.portname' 10:17:44 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:17:44 INFO - PID 1668 | 2017-06-03 10:17:44.469 plugin-container[1670:11582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 10:17:44 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:17:44 INFO - PID 1668 | 2017-06-03 10:17:44.469 plugin-container[1670:11582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:17:53 INFO - PID 1668 | RSS: Main: 268771328 10:17:53 INFO - PID 1668 | 10:17:56 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 10:17:57 INFO - PID 1668 | RSS: Main: 276422656 10:17:57 INFO - PID 1668 | 10:17:59 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 10:18:00 INFO - PID 1668 | RSS: Main: 284237824 10:18:00 INFO - PID 1668 | 10:18:03 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 10:18:04 INFO - PID 1668 | RSS: Main: 281800704 10:18:04 INFO - PID 1668 | 10:18:06 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 10:18:07 INFO - PID 1668 | RSS: Main: 285687808 10:18:07 INFO - PID 1668 | 10:18:09 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:18:09 INFO - PID 1668 | RSS: Main: 286650368 10:18:09 INFO - PID 1668 | 10:18:12 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 10:18:13 INFO - PID 1668 | RSS: Main: 291258368 10:18:13 INFO - PID 1668 | 10:18:16 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 10:18:17 INFO - PID 1668 | RSS: Main: 294223872 10:18:17 INFO - PID 1668 | 10:18:20 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 10:18:20 INFO - PID 1668 | RSS: Main: 293285888 10:18:20 INFO - PID 1668 | 10:18:22 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:23 INFO - PID 1668 | RSS: Main: 292921344 10:18:23 INFO - PID 1668 | 10:18:24 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:18:25 INFO - PID 1668 | RSS: Main: 292872192 10:18:25 INFO - PID 1668 | 10:18:27 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:18:28 INFO - PID 1668 | RSS: Main: 292794368 10:18:28 INFO - PID 1668 | 10:18:31 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:18:32 INFO - PID 1668 | RSS: Main: 292573184 10:18:32 INFO - PID 1668 | 10:18:35 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:18:35 INFO - PID 1668 | RSS: Main: 292474880 10:18:35 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 10:18:37 INFO - PID 1668 | RSS: Main: 294199296 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | __start_tp_report 10:18:37 INFO - PID 1668 | _x_x_mozilla_page_load 10:18:37 INFO - PID 1668 | _x_x_mozilla_page_load_details 10:18:37 INFO - PID 1668 | |i|pagename|runs| 10:18:37 INFO - PID 1668 | |0;ai-astar;102;114;114;116;115;116;114;116;114;114 10:18:37 INFO - PID 1668 | |1;audio-beat-detection;137;108;113;167;142;114;112;116;133;110 10:18:37 INFO - PID 1668 | |2;audio-dft;194;199;195;203;208;219;213;205;235;272 10:18:37 INFO - PID 1668 | |3;audio-fft;78;58;59;87;58;62;59;58;80;59 10:18:37 INFO - PID 1668 | |4;audio-oscillator;76;68;82;72;74;72;100;58;96;118 10:18:37 INFO - PID 1668 | |5;imaging-gaussian-blur;91;91;91;90;92;91;90;91;98;92 10:18:37 INFO - PID 1668 | |6;imaging-darkroom;98;138;140;155;161;98;98;98;130;97 10:18:37 INFO - PID 1668 | |7;imaging-desaturate;130;94;147;135;92;91;90;91;90;90 10:18:37 INFO - PID 1668 | |8;json-parse-financial;53;50;49;51;53;52;52;51;50;50 10:18:37 INFO - PID 1668 | |9;json-stringify-tinderbox;58;56;64;74;76;59;56;57;57;57 10:18:37 INFO - PID 1668 | |10;stanford-crypto-aes;87;87;85;86;89;88;87;87;122;92 10:18:37 INFO - PID 1668 | |11;stanford-crypto-ccm;137;138;147;142;151;136;137;147;155;139 10:18:37 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;185;184;171;233;236;213;187;183;210;207 10:18:37 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;69;64;66;66;66;67;65;65;69;70 10:18:37 INFO - PID 1668 | __end_tp_report 10:18:37 INFO - PID 1668 | __start_cc_report 10:18:37 INFO - PID 1668 | _x_x_mozilla_cycle_collect,1226 10:18:37 INFO - PID 1668 | __end_cc_report 10:18:37 INFO - PID 1668 | __startTimestamp1496510317743__endTimestamp 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | ------- Summary: start ------- 10:18:37 INFO - PID 1668 | Number of tests: 14 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:113.50 Median:114.50 stddev:4.14 (3.6%) stddev-sans-first:0.97 10:18:37 INFO - PID 1668 | Values: 102.0 114.0 114.0 116.0 115.0 116.0 114.0 116.0 114.0 114.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:125.20 Median:124.50 stddev:19.12 (15.4%) stddev-sans-first:19.80 10:18:37 INFO - PID 1668 | Values: 137.0 108.0 113.0 167.0 142.0 114.0 112.0 116.0 133.0 110.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:214.30 Median:210.50 stddev:23.70 (11.3%) stddev-sans-first:23.97 10:18:37 INFO - PID 1668 | Values: 194.0 199.0 195.0 203.0 208.0 219.0 213.0 205.0 235.0 272.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:65.80 Median:60.50 stddev:11.23 (18.6%) stddev-sans-first:11.01 10:18:37 INFO - PID 1668 | Values: 78.0 58.0 59.0 87.0 58.0 62.0 59.0 58.0 80.0 59.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:73.00 stddev:17.91 (24.5%) stddev-sans-first:18.88 10:18:37 INFO - PID 1668 | Values: 76.0 68.0 82.0 72.0 74.0 72.0 100.0 58.0 96.0 118.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:91.00 stddev:2.31 (2.5%) stddev-sans-first:2.44 10:18:37 INFO - PID 1668 | Values: 91.0 91.0 91.0 90.0 92.0 91.0 90.0 91.0 98.0 92.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:121.30 Median:97.50 stddev:26.20 (26.9%) stddev-sans-first:26.40 10:18:37 INFO - PID 1668 | Values: 98.0 138.0 140.0 155.0 161.0 98.0 98.0 98.0 130.0 97.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:105.00 Median:90.50 stddev:22.72 (25.1%) stddev-sans-first:22.22 10:18:37 INFO - PID 1668 | Values: 130.0 94.0 147.0 135.0 92.0 91.0 90.0 91.0 90.0 90.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:1.37 (2.7%) stddev-sans-first:1.27 10:18:37 INFO - PID 1668 | Values: 53.0 50.0 49.0 51.0 53.0 52.0 52.0 51.0 50.0 50.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:58.50 stddev:7.55 (12.9%) stddev-sans-first:7.90 10:18:37 INFO - PID 1668 | Values: 58.0 56.0 64.0 74.0 76.0 59.0 56.0 57.0 57.0 57.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:91.00 Median:87.00 stddev:11.06 (12.7%) stddev-sans-first:11.63 10:18:37 INFO - PID 1668 | Values: 87.0 87.0 85.0 86.0 89.0 88.0 87.0 87.0 122.0 92.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:142.90 Median:144.50 stddev:6.69 (4.6%) stddev-sans-first:6.75 10:18:37 INFO - PID 1668 | Values: 137.0 138.0 147.0 142.0 151.0 136.0 137.0 147.0 155.0 139.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.90 Median:208.50 stddev:22.30 (10.7%) stddev-sans-first:22.90 10:18:37 INFO - PID 1668 | Values: 185.0 184.0 171.0 233.0 236.0 213.0 187.0 183.0 210.0 207.0 10:18:37 INFO - PID 1668 | 10:18:37 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:66.50 stddev:2.00 (3.0%) stddev-sans-first:1.94 10:18:37 INFO - PID 1668 | Values: 69.0 64.0 66.0 66.0 66.0 67.0 65.0 65.0 69.0 70.0 10:18:37 INFO - PID 1668 | -------- Summary: end -------- 10:18:37 INFO - PID 1668 | 10:18:38 INFO - TEST-INFO | 1668: exit 0 10:18:38 INFO - TEST-OK | kraken | took 90739ms 10:18:38 INFO - SUITE-END | took 1233s 10:18:38 INFO - Completed test suite (00:20:34) 10:18:38 INFO - javascript benchmark 10:18:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8170.977459519139, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2829.8585070746462, 3088.3668847886183, 3616.312900548894, 3621.5473065866768, 3627.9455808162866, 6129.878419756035, 6156.846078848031, 6211.658358790265, 6236.5946213496145, 6243.219597550306, 5130.948690513093, 5144.691318520891, 6139.032198454753, 6180.196574445327, 6251.09359142924, 5296.92054619181, 5472.616916815835, 5483.588730845183, 5496.697681627529, 5533.972330138364, 5533.557315414777, 5596.244506991565, 5605.075162598187, 5610.971945140269, 5631.465010823965, 5335.279737235482, 5402.91895621566, 5467.753951072212, 5468.28912241408, 5571.359293681238, 5509.752061157217, 5521.834344969658, 5522.475364840334, 5537.363203231599, 5546.805861794858, 5439.782408703687, 5443.074677304828, 5483.533899618497, 5520.1443776214755, 5536.335639723218, 4585.564371384713, 6133.304060594571, 6197.752089916398, 6242.938700420894, 6264.27960784511, 6626.768063117768, 6737.6968036438475, 6741.898871516931, 6756.966215168942, 6762.289959554225, 6756.93243067568, 6792.694328755218, 6817.011533327689, 6828.419584335337, 6835.453163746888, 6175.980963141121, 6269.0, 6274.968625156845, 6294.464970477515, 6294.65379404128, 6711.932880671131, 6885.793426197222, 6958.234594194634, 6973.686184121625, 7011.544249623758, 6426.710798014007, 6494.155759751202, 6514.446272066927, 6515.7393704252245, 6597.60414375139, 6807.829804254834, 6838.350356716104, 6844.21291551468, 6854.862902741917, 6868.656567171722, 6448.709808058648, 6709.027191057269, 6725.233869033259, 6727.9327206728285, 6731.49513786468, 6493.285738568754, 6597.8350541235895, 6636.767713130017, 6701.0953521274005, 6723.63020033893, 5368.91946620801, 5375.301210842644, 5377.139657654757, 5383.892322153535, 5392.811251606175, 4779.235322348477, 4780.091782561302, 4784.234522476387, 4791.353167322437, 4803.375561177025, 6160.44555989963, 6235.532335074888, 6291.591046581958, 6303.2751233607805, 6325.746970121236, 6852.0, 6884.967254911803, 6895.241523432519, 6900.7929762107215, 6927.549709268881, 6320.4311611955145, 6353.650549219745, 6370.74517019314, 6385.808425747235, 6397.328280530571, 5898.115282707542, 5986.401359864066, 6034.094885767082, 6086.330503644595, 6119.5410344224365, 6968.024476573284, 7002.089728335284, 7018.75434359795, 7020.964895175492, 7032.507724459443, 6820.3861652451515, 6864.725410983505, 6881.759138430131, 6891.27641597625, 6893.620850853251, 6768.153980752406, 6861.965690171717, 6920.169579650475, 7019.122609673791, 7037.577745335297, 5277.630565860353, 6414.684989577032, 6749.898751518829, 6772.288909664414, 6775.390214880685, 6820.045193672989, 6893.689783959633, 6898.310168983062, 6900.206476255295, 6912.239653638197, 6418.711157997808, 6473.093766872736, 6563.507736919654, 6640.867182656223, 6655.600663960178, 4961.82633607822, 6630.038644396439, 6647.069410282661, 6682.164729408824, 6682.766103186365, 6469.223693156663, 6514.250861151028, 6523.67381630907, 6528.477721782341, 6535.836604084936, 6390.52071094679, 6484.157059582224, 6559.8032059038305, 6596.57122287069, 6665.733370665119, 6439.549231553746, 6475.740970361133, 6477.255115661571, 6487.675616219076, 6513.055606937062], "value": 6134.699279268964, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.3467348733114, 760.6881178716724, 826.813966857457, 1259.3073809404825, 1328.8338957630297, 38581.03547411316, 39572.604273957324, 39682.40476392856, 39689.80155099228, 39737.20525589486, 39718.801405993, 40174.0, 40274.7986260069, 40301.0, 40347.19305613886, 7927.643256053477, 8028.879566806502, 8360.122187170351, 8548.78628034301, 8590.324886647106, 8497.107803680618, 8526.062133165378, 8619.525926074064, 8622.267107295887, 8630.050694423608, 8519.446235994672, 8548.529830859301, 8574.142585741407, 8603.870941935877, 8628.050914399411, 8580.39937204396, 8600.311975041981, 8609.655613775441, 8611.569421528899, 8636.481811091291, 8408.32733381328, 8413.95793021037, 8427.915720842775, 8474.322054235647, 8481.872771908425, 37217.0, 37368.43947340792, 37376.81311593452, 37514.0, 37611.62388376109, 3599.6940260077927, 3645.6172101929183, 3646.1978364759975, 3646.2889736501393, 3646.453032045188, 30455.847720761474, 30856.228594285098, 30876.073717788502, 30945.845270773727, 30950.845245773628, 3095.4428202923464, 3139.4035133324596, 3150.6376766671897, 3156.4160630283473, 3169.8098114112995, 240632.0, 242556.0, 281927.0, 299814.0, 300712.0, 48484.757576211894, 48570.27144592834, 48809.0, 48810.755946220044, 48920.26619600709, 51254.74372628113, 51292.48707512952, 51359.0, 53352.733236333566, 54414.45585544173, 14629.049111807912, 14672.3397447113, 14730.779038314435, 14756.778648320284, 14773.33519991603, 11748.471318790675, 11845.940770296093, 11846.644600661994, 11848.0, 11857.703557411134, 13944.790828137586, 14106.506272280421, 14112.788308175386, 14130.646733831736, 14196.432142714382, 6156.8768624627255, 6161.784337548164, 6183.598066125687, 6215.627062376181, 6223.377662233831, 779.8986131802943, 842.0358689300716, 937.2854541655483, 986.7483791633088, 997.8453339732353, 22725.204617838295, 23540.882295588755, 24077.879610601834, 24463.877680611484, 24827.0, 1068.9305195162444, 1077.52050337599, 1131.145984781501, 1131.8245671920865, 1132.28665940457, 9971.603975443444, 11333.320000799813, 11917.34454605005, 12481.063920206021, 12710.42803073864, 321394.0, 322478.0, 323368.0, 323630.0, 324035.0, 41570.0, 54361.1845822313, 54523.727381363635, 54661.72669136708, 54679.72660136753, 1486.108334998992, 1499.71505413971, 1534.5856618712885, 1538.0617823127884, 1540.6302487402943, 22890.0, 22934.967926443016, 23777.881110594335, 24159.75840241575, 24740.876295618407, 38108.428373573865, 38129.809350953066, 38223.426648599736, 38276.23447530978, 38293.0, 269400.0, 272522.0, 273162.0, 273324.0, 274090.0, 30510.389792204474, 33751.649934002366, 35509.0, 35679.92960211198, 35884.0, 42842.14315713606, 44307.33538996853, 46083.30875036944, 46147.84630384267, 46192.53807462017, 29271.73108840344, 29481.377839310688, 30423.239419014702, 31487.842560787965, 31527.886842340722, 30113.81454838469, 30337.848310758305, 31104.844475777478, 31363.05910822679, 31682.683173167974, 36614.90155295345, 37237.6276237245, 37340.6265937348, 37387.813060934524, 37516.81241593775, 27995.86002070058, 31126.22184445407, 31551.84224078865, 32228.033159005266, 32618.673813262514, 20567.074481648662, 24622.0, 25157.11950081738, 25244.368890777878, 25257.62113568335], "value": 17605.324950102484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.9837786069155, 432.4076015858275, 436.273604448593, 447.6284683712517, 449.77736020669767, 14555.490557830479, 15253.31360088796, 21850.125994960203, 22635.320940371774, 22696.205632802834, 20703.37889863303, 23185.536289274205, 23413.297601071954, 23426.180083697072, 23500.29499115025, 6082.361352058037, 6238.376162383748, 6284.402981716729, 6296.464800491941, 6297.464715499188, 5570.136628822519, 5576.191452239423, 5627.240322556443, 5641.548676105902, 5653.575981801361, 5504.284443022401, 5567.972160139194, 5600.635958662694, 6052.636841789485, 6120.990036643971, 6034.909476357858, 6089.056196289582, 6094.055421409666, 6094.878102437949, 6097.634141951497, 5351.518363347317, 5411.269478620376, 5412.891742165135, 5415.13357862744, 5417.187421886709, 21491.67762483564, 25393.873030634728, 27480.862595687093, 27760.583591246148, 28119.188081191718, 2205.911763529457, 2220.3893929169444, 2251.7860803223666, 2263.7170353705787, 2309.9653505197434, 19458.416247512596, 19482.220711171536, 19778.0, 19848.603027939505, 19859.21970924072, 2014.1943222710886, 2294.4608017115966, 2302.481941563152, 2354.7880690737743, 2386.284114765581, 163502.0, 200404.99392509123, 205239.0, 208039.0, 208541.0, 24540.87729561341, 24551.122381235044, 24671.62992555113, 25254.055134837698, 25699.48601027969, 42439.0, 42682.78658606687, 42798.28806847691, 42895.498657546836, 42900.3564946526, 11166.60916867922, 11311.94344028291, 11322.433878306052, 11340.376279304552, 11460.598879039193, 8970.147835955573, 9039.63841446348, 9080.663820959759, 9138.406003609743, 9152.90847091534, 7717.8456430871065, 11727.0, 11856.88143118575, 11979.401029948467, 12042.518299268106, 2258.9322320330416, 2270.8069814065657, 2279.669447930074, 2283.372072680026, 2467.6668649732414, 829.4276948905236, 922.294444749767, 939.910708482693, 992.3477410112082, 999.6950929966284, 15066.771102279143, 17207.65584688324, 19531.0, 20204.595908081756, 20345.796542034685, 955.3121752338305, 969.195567678839, 978.6183388478499, 1010.8584798128268, 1017.6641708236265, 10715.357078575154, 11054.447277636085, 11093.0, 11104.833427498594, 11268.830967535494, 512965.0, 516358.0, 517839.0, 528696.0, 529940.350298246, 146838.0, 149846.25076874546, 150941.24529377284, 151236.0, 152926.0, 1148.3913525831322, 1175.9000484958704, 1175.905927525796, 1186.833843261944, 1192.7077865922904, 18060.729089063938, 18143.27638874305, 19168.520786980436, 21286.467838304165, 23221.3033608992, 69698.30301697121, 69773.65113174402, 71332.0, 71407.0, 71916.64041679759, 87655.56172219098, 92523.0, 92734.53632731792, 92988.0, 93508.0, 10077.294589378966, 10209.0, 10232.334898231591, 10378.896211037792, 10400.063994240554, 13295.335233238493, 14050.0, 16986.725995550136, 18185.909070454563, 18211.726824097383, 14229.786553201504, 14770.926145369634, 14849.851501484847, 14852.702945940804, 14902.850971490147, 11137.944310278397, 12540.05949553769, 12857.0, 13051.21692698441, 13358.799618005543, 14666.926665366605, 14839.629009274855, 14953.626159346104, 15140.924295378452, 15158.090514569161, 9816.950915245377, 10181.490925453845, 10270.229732770222, 10278.948605257225, 10327.638532651321, 13717.794233086312, 14743.410263589336, 14862.0, 14960.326785294905, 14965.775513367525], "value": 11706.092304116548, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1210.642860356195, 1443.9855601443983, 1445.1618061524316, 1455.7306898223828, 1473.167660271946, 11609.245399049067, 11624.0, 12049.638510844688, 12118.878811211896, 12393.25640461573, 9808.85286720698, 12044.51821927124, 12068.69828254294, 12232.877671223285, 12243.938780306085, 2552.8468291902545, 2594.5589249827576, 2596.1822026061823, 2596.208156512263, 2599.2462185966046, 2513.8743062846784, 2530.380056886056, 2534.0497313507435, 2550.477152183807, 2553.297843093155, 2501.137107697841, 2512.321673148249, 2522.798176145904, 2530.8481491110592, 2532.645429639843, 2477.355887469262, 2497.3382053755768, 2507.849529028264, 2511.1211076123395, 2514.484530671208, 2465.0632759551436, 2484.86938443008, 2491.5266099441046, 2506.8245222834407, 2556.0670355320176, 11690.298582085017, 11742.530298788039, 11828.290302581872, 11845.881541184564, 11864.0, 6458.773942911976, 6670.299618540032, 6753.561018533829, 6759.662016899136, 6783.966080169567, 4097.0, 4276.444062271885, 4314.374415709736, 4344.391785150082, 4362.36745671879, 1261.9621411357673, 1265.7658333208385, 1266.208619612742, 1293.3016171267504, 1296.2222666400085, 15390.0765954042, 15759.763603545956, 16403.58991025222, 16567.25447354872, 16643.50069497917, 5580.158547679256, 5608.523275521545, 5808.477237048686, 5822.0, 5830.271216097988, 12190.2076365036, 12266.877331226751, 12443.813342799865, 12463.065270104778, 12485.126041177213, 6504.5446818723185, 6536.444402225864, 6620.238672552622, 6631.336866313427, 6653.600783952881, 6056.545759068087, 6168.814935551941, 6191.349908259658, 6261.968690156611, 6269.059641053787, 4346.2828633275785, 4398.64810815134, 4432.246518091933, 4465.173942820588, 4466.151431228056, 1278.2011242973142, 1289.2908900104906, 1293.6636474516692, 1306.3664122900386, 1310.351376068852, 810.2140923304386, 819.4632515702225, 819.8358331169753, 825.3644693585906, 828.9129641387567, 6375.4262116409745, 6819.420349270268, 7506.836440351754, 7734.845303093906, 7874.50384426957, 830.9127541608165, 943.8961714211431, 982.5676702250987, 989.6833013435631, 994.3834822410151, 4684.672072954929, 4694.967107236403, 4962.826301079517, 4964.404271487445, 5004.224345226496, 14449.78325325121, 14601.853981460263, 15232.314545845464, 15308.923455382874, 15344.232788360761, 14771.852281477262, 15232.543023709306, 15260.008099473498, 15337.693246135015, 15419.460318889009, 8470.025947016173, 8619.61211745473, 8663.610137543825, 8681.739547813575, 8796.912030879737, 6863.416609588241, 7295.197528271779, 7545.358644515278, 7749.263819937097, 7872.527648341118, 12826.743465130832, 12880.0, 12896.161749486253, 13018.869811301765, 13028.28344441027, 13314.267715275753, 13810.516631917835, 13909.0, 14216.659085114783, 14230.501932432315, 9638.710838674851, 9792.902070979198, 9846.212303015885, 9854.901450985684, 9859.605615775288, 10780.29928054674, 10880.510377032893, 10895.400752958347, 10937.726556835825, 10991.780164396507, 9117.544122793966, 9175.311851611023, 9177.311701622271, 9298.070192980647, 9379.390339627902, 9060.637574496946, 9065.864012039956, 9105.27157827359, 9117.316201285063, 9319.30105242096, 8225.95887020561, 8277.95861020691, 8395.160483951557, 8406.243438090602, 8423.410361274651, 6044.395560443921, 6149.723262453111, 6402.231732192166, 6417.839554011187, 6525.575837570543, 5362.839114826562, 6337.397947195009, 6545.21457425112, 6639.468842492685, 6663.300353463011], "value": 5519.210558570495, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.5699559497769, 398.47799382808523, 399.89602703297135, 406.87793661901424, 407.7675724836843, 6256.812295631127, 6320.905186422196, 6345.206849143857, 6350.936490635092, 6409.583377080492, 6056.27324721033, 6096.969515152429, 6175.783847565336, 6192.566520343577, 6217.5025997920175, 4574.794134263966, 4583.8166473341025, 4602.792874320664, 4603.838865639704, 4606.723596584199, 3845.38473844185, 4529.026259354238, 4578.51925547816, 4598.609118224938, 4624.0, 4547.681172459999, 4554.724677090403, 4555.134524440362, 4579.267317229244, 4584.472785629662, 3918.0, 4057.857974970862, 4168.958310416887, 4543.204939135638, 4549.384968336242, 3932.292187406266, 3932.921341573181, 3940.9605903941165, 3945.8027098644952, 3949.8025098745234, 5428.91856622147, 5708.828735137952, 6128.203333566652, 6150.938490615082, 6163.2604087509335, 5368.32895888014, 5475.069238229471, 5477.452254774531, 5489.451054894478, 5500.532454741352, 6610.405063544256, 6650.833729156761, 6735.7642482513365, 6742.2246441659345, 6760.594364338155, 3752.4934133891848, 4462.553744625544, 4465.575770301849, 4466.82132714691, 4475.641948644134, 6670.666466676696, 6751.628660423675, 6756.662166891636, 7010.404115650227, 7171.641417929082, 3718.2377612589894, 4171.457710497616, 4466.285394336891, 4559.270516717375, 4564.680472366902, 4632.467266264355, 4636.768161591975, 4639.443266807937, 4649.883752906137, 4652.95347046532, 5263.368395792453, 5411.648242864201, 5426.158945363475, 5451.754671039813, 5456.563474921997, 6585.275619681831, 6646.169228846395, 6704.195496540349, 6704.765333213314, 6730.4952128589575, 1443.3577058208996, 1477.5372381342393, 1577.8106627204809, 1586.3416682076809, 1594.2347673116737, 1013.5388398278766, 1042.103790739963, 1049.7480604654827, 1054.1672079057612, 1060.753614502956, 6332.968335158294, 6358.872822543523, 6376.776812811529, 6381.468447572549, 6402.519811014099, 6130.63216207029, 6454.935450645527, 6935.479839012094, 6993.685284162225, 7032.8945065824055, 5342.679439233659, 6066.939330606726, 6277.435030847246, 6293.779717709858, 6307.873842523124, 3278.5246139309666, 3280.3439312137666, 3308.8749825059595, 3617.692496137805, 3631.5460567429072, 6508.674566271668, 6524.869502610016, 7142.21435642079, 7200.855982880313, 7221.24176961422, 7172.175199852084, 7205.89191162133, 7212.134543854771, 7218.278172182845, 7268.328284494596, 5671.858203544944, 6270.811875643738, 7167.713291468387, 7182.96408517954, 7184.245654206337, 5461.907618476241, 6422.100905873088, 6547.006326141145, 6945.201301850352, 6970.407515361251, 5859.00396932514, 6752.358525940224, 6872.896906546506, 6900.93099069023, 6908.620025898423, 6724.596524208563, 7131.179914309921, 7203.495755297288, 7233.927660723325, 7247.883410133693, 4554.679143048479, 4561.654311977908, 4562.429696287964, 4571.1771881060395, 4582.1293954148605, 6948.5483443576, 6999.895001574879, 7018.508704390643, 7044.542104763174, 7103.680334384861, 6546.869062618625, 6695.230048544479, 6698.866022679616, 6718.8320291993095, 6779.35596118368, 5239.26650268955, 6110.808392363624, 6565.0, 6719.0, 6768.2216545097945, 5438.564914806885, 5772.6536407815665, 6477.352264773485, 6552.215308493966, 6599.967000164968, 6472.676366181578, 6604.877170880865, 6690.431313338421, 6695.397414232742, 6739.764108256188, 6481.8703625928165, 6529.804105876831, 6535.705893234911, 6549.803505894831, 6561.278259391368], "value": 4875.730877711823, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.8835363049178, 835.4360806455642, 842.9452085614435, 860.7605048729829, 875.492214515581, 14574.417023319054, 15152.469663561778, 16871.98768073915, 17432.564185895342, 17449.04030278334, 16119.758203626925, 17484.47546573605, 17999.460016199504, 18084.457466276, 18140.0, 822.1942496353577, 976.2482888176118, 999.5951639585979, 1001.4141727089661, 1001.419176877413, 990.5889056041771, 1000.2897942460861, 1000.924930630202, 1003.6085926488639, 1012.7620009297809, 892.7946572288378, 951.6431338248157, 1038.85456036155, 1049.4385503755493, 1062.9681109566725, 1043.4991204221978, 1049.171154787717, 1050.5797680927615, 1051.0225490294024, 1053.531178625512, 747.8317378589828, 758.5221310574358, 761.46316846623, 763.412172627074, 763.6372722956551, 16870.746938795928, 16948.745768813355, 16980.66038679232, 17029.0, 17050.48848534546, 17528.21123049453, 17626.735598966025, 17935.910320448314, 17957.64084718298, 17992.550186245317, 14681.412743490248, 14794.186319752409, 14980.325885335185, 14992.325345359484, 15013.24933753308, 3556.342076715816, 3590.35373632746, 3609.3864043112735, 3614.981925090384, 3616.782993020427, 155210.0, 157117.0, 159009.0, 159200.40799591973, 159327.99999999884, 13729.862701373058, 13863.098898571561, 13875.930620346833, 13912.51306204278, 14050.227237501831, 26278.6058209127, 27019.1894243173, 27214.863925680642, 27416.314592135357, 27445.90216391322, 6783.864322713617, 7034.613096279753, 7613.200613935568, 7849.332806711379, 7895.960520197362, 3966.4446977423186, 4041.8989525261513, 4061.756294622332, 4065.207284579538, 4071.0, 4218.936715949263, 4290.957090429119, 4313.202057619351, 4321.848735294249, 4328.307470804656, 939.0984654731398, 942.0767647705287, 949.0556895888635, 954.5227386306847, 965.5654955270156, 647.3267801486495, 665.2416245480157, 671.2884342596863, 674.2246416620827, 691.7198534593498, 9479.857802132974, 11291.3789741565, 11794.764104717857, 12142.45358958849, 12570.497180112692, 619.5477301569789, 643.7939859244997, 652.1326635574769, 673.9595624262462, 689.3588962265142, 5461.726913654381, 5516.834494965157, 5744.396838331999, 5745.224394706661, 5878.676672783038, 112936.30595541073, 133306.0, 136223.31888340495, 142460.0, 144954.82567761492, 121499.0, 121536.2303058491, 124999.0, 125466.0, 126420.36789815992, 110987.89012109775, 110991.89008109977, 111407.44296278467, 111627.0, 111730.88269117205, 11068.889311107108, 11268.605598804003, 11415.0, 11744.942955133738, 11781.410929453321, 46308.0, 47024.0, 47162.056758863946, 47334.57996260118, 47751.32870349521, 62764.43088922631, 63188.0, 63402.36597633964, 63572.364276358494, 64492.355076448635, 14302.642433939234, 14352.138871667734, 14800.407983680532, 14812.62968425798, 14852.183129927958, 15645.217739113226, 16482.34070637209, 17468.388606398716, 18383.080845957917, 19018.334358297394, 17262.309507620055, 17465.0, 17604.471865844047, 17610.823891761433, 17712.202950866984, 16995.23521441563, 17260.22328995173, 17460.91269543644, 17568.297268109643, 17588.032658203923, 14991.925040374728, 16672.916635416746, 16989.235484403478, 17360.652786943938, 18237.270509179485, 11049.28179668319, 11458.77082458363, 11547.595834145764, 12309.56916507918, 12547.435365408395, 12121.967265405381, 12810.295433751233, 12968.221906685629, 13665.590032299047, 13877.583672489842], "value": 8746.75690160726, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1532.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 114.0, 116.0, 115.0, 116.0, 114.0, 116.0, 114.0, 114.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 108.0, 113.0, 167.0, 142.0, 114.0, 112.0, 116.0, 133.0, 110.0], "value": 125.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 195.0, 203.0, 208.0, 219.0, 213.0, 205.0, 235.0, 272.0], "value": 214.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 58.0, 59.0, 87.0, 58.0, 62.0, 59.0, 58.0, 80.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 68.0, 82.0, 72.0, 74.0, 72.0, 100.0, 58.0, 96.0, 118.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 90.0, 92.0, 91.0, 90.0, 91.0, 98.0, 92.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 138.0, 140.0, 155.0, 161.0, 98.0, 98.0, 98.0, 130.0, 97.0], "value": 121.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 94.0, 147.0, 135.0, 92.0, 91.0, 90.0, 91.0, 90.0, 90.0], "value": 105.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 49.0, 51.0, 53.0, 52.0, 52.0, 51.0, 50.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 64.0, 74.0, 76.0, 59.0, 56.0, 57.0, 57.0, 57.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 85.0, 86.0, 89.0, 88.0, 87.0, 87.0, 122.0, 92.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 138.0, 147.0, 142.0, 151.0, 136.0, 137.0, 147.0, 155.0, 139.0], "value": 142.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 171.0, 233.0, 236.0, 213.0, 187.0, 183.0, 210.0, 207.0], "value": 200.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 64.0, 66.0, 66.0, 66.0, 67.0, 65.0, 65.0, 69.0, 70.0], "value": 66.7, "unit": "score"}]}]} 10:18:39 INFO - Return code: 0 10:18:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:18:39 INFO - # TBPL SUCCESS # 10:18:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:18:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:18:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:18:39 INFO - 2017-06-03 10:18:39.165 screenresolution[1676:11978] starting screenresolution argv=screenresolution get 10:18:39 INFO - 2017-06-03 10:18:39.168 screenresolution[1676:11978] Display 0: 1600x1200x32@60 10:18:39 INFO - 2017-06-03 10:18:39.173 screenresolution[1677:11981] starting screenresolution argv=screenresolution list 10:18:39 INFO - Available Modes on Display 0 10:18:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:18:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:18:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:18:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:18:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:18:39 INFO - Intel Iris: 10:18:39 INFO - Chipset Model: Intel Iris 10:18:39 INFO - Type: GPU 10:18:39 INFO - Bus: Built-In 10:18:39 INFO - VRAM (Dynamic, Max): 1536 MB 10:18:39 INFO - Vendor: Intel (0x8086) 10:18:39 INFO - Device ID: 0x0a2e 10:18:39 INFO - Revision ID: 0x0009 10:18:39 INFO - Displays: 10:18:39 INFO - Display: 10:18:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:18:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:18:39 INFO - Display Serial Number: 1600x1200 60 10:18:39 INFO - Main Display: Yes 10:18:39 INFO - Mirror: Off 10:18:39 INFO - Online: Yes 10:18:39 INFO - Rotation: Supported 10:18:39 INFO - Return code: 0 10:18:39 INFO - Running post-action listener: _resource_record_post_action 10:18:39 INFO - [mozharness: 2017-06-03 17:18:39.411998Z] Finished run-tests step (success) 10:18:39 INFO - Running post-run listener: _resource_record_post_run 10:18:39 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 35008000; Write bytes: 568654336; Read time: 229; Write time: 1745 10:18:39 INFO - TinderboxPrint: CPU usage
53.3% 10:18:39 INFO - TinderboxPrint: I/O read bytes / time
35,008,000 / 229 10:18:39 INFO - TinderboxPrint: I/O write bytes / time
568,654,336 / 1,745 10:18:39 INFO - TinderboxPrint: CPU idle
2,346.3 (46.7%) 10:18:39 INFO - TinderboxPrint: CPU system
1,013.7 (20.2%) 10:18:39 INFO - TinderboxPrint: CPU user
1,665.8 (33.1%) 10:18:39 INFO - TinderboxPrint: Swap in / out
277,676,032 / 0 10:18:39 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 241525248; Write bytes: 236217856; Read time: 18035; Write time: 512 10:18:39 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:18:39 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 32914944; Write bytes: 318034944; Read time: 209; Write time: 1189 10:18:39 INFO - Running post-run listener: _upload_blobber_files 10:18:39 INFO - Blob upload gear active. 10:18:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:39 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:18:39 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:18:39 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:18:40 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:18:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:18:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:40 INFO - (blobuploader) - INFO - Done attempting. 10:18:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:18:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:18:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:41 INFO - (blobuploader) - INFO - Done attempting. 10:18:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:18:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:42 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:18:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:42 INFO - (blobuploader) - INFO - Done attempting. 10:18:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:44 INFO - (blobuploader) - INFO - Done attempting. 10:18:44 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:44 INFO - Return code: 0 10:18:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b33cad30921a1fee7aedcc504e1cb63963ac2ad4837dbb68dd4b42a7b9b890d2d17400319c2a72e2112b42ddc53a51b8822e6b2d02af866279d7baff09eb93b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c0c2d73b692c6c98387924ee4c8e695c16eaeec5af9520c3c3aa1babfcd4df26b013c4b1de674fee17042083db8e154d55de0da533718e29eb675cd78b850c1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d2b665007db962ff152a46c01700fd13d80d8201205dab09c5e1696aadacba1ec0e96e9c167ef75dcdfdfa4593db928fd29eaa385912ab7eb4c1603910f96f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c066bae63927acba9ac36da053e9047e966b4f88bd61e6ea480028514dc629077b474d8b9b9c8cb5fcb3bb0dd597f5f56f24ea0aa026d6b9e474bbe3351f194"} 10:18:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:44 INFO - Contents: 10:18:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b33cad30921a1fee7aedcc504e1cb63963ac2ad4837dbb68dd4b42a7b9b890d2d17400319c2a72e2112b42ddc53a51b8822e6b2d02af866279d7baff09eb93b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c0c2d73b692c6c98387924ee4c8e695c16eaeec5af9520c3c3aa1babfcd4df26b013c4b1de674fee17042083db8e154d55de0da533718e29eb675cd78b850c1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d2b665007db962ff152a46c01700fd13d80d8201205dab09c5e1696aadacba1ec0e96e9c167ef75dcdfdfa4593db928fd29eaa385912ab7eb4c1603910f96f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c066bae63927acba9ac36da053e9047e966b4f88bd61e6ea480028514dc629077b474d8b9b9c8cb5fcb3bb0dd597f5f56f24ea0aa026d6b9e474bbe3351f194"} 10:18:44 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:44 INFO - Copying logs to upload dir... 10:18:44 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.922576 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-03 10:18:44.964902) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-03 10:18:44.968201) ========= 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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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/6b33cad30921a1fee7aedcc504e1cb63963ac2ad4837dbb68dd4b42a7b9b890d2d17400319c2a72e2112b42ddc53a51b8822e6b2d02af866279d7baff09eb93b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c0c2d73b692c6c98387924ee4c8e695c16eaeec5af9520c3c3aa1babfcd4df26b013c4b1de674fee17042083db8e154d55de0da533718e29eb675cd78b850c1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d2b665007db962ff152a46c01700fd13d80d8201205dab09c5e1696aadacba1ec0e96e9c167ef75dcdfdfa4593db928fd29eaa385912ab7eb4c1603910f96f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c066bae63927acba9ac36da053e9047e966b4f88bd61e6ea480028514dc629077b474d8b9b9c8cb5fcb3bb0dd597f5f56f24ea0aa026d6b9e474bbe3351f194"} build_url:https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011099 build_url: 'https://queue.taskcluster.net/v1/task/X_JOMqD8Sae-3frvDhChAA/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/6b33cad30921a1fee7aedcc504e1cb63963ac2ad4837dbb68dd4b42a7b9b890d2d17400319c2a72e2112b42ddc53a51b8822e6b2d02af866279d7baff09eb93b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9c0c2d73b692c6c98387924ee4c8e695c16eaeec5af9520c3c3aa1babfcd4df26b013c4b1de674fee17042083db8e154d55de0da533718e29eb675cd78b850c1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d2b665007db962ff152a46c01700fd13d80d8201205dab09c5e1696aadacba1ec0e96e9c167ef75dcdfdfa4593db928fd29eaa385912ab7eb4c1603910f96f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c066bae63927acba9ac36da053e9047e966b4f88bd61e6ea480028514dc629077b474d8b9b9c8cb5fcb3bb0dd597f5f56f24ea0aa026d6b9e474bbe3351f194"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-03 10:18:45.001308) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 10:18:45.001642) ========= 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.IRr1fie3Rt/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.fL9GzsL5Qw/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.005182 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 10:18:45.050755) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-03 10:18:45.051077) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-03 10:18:45.051400) ========= ========= Total master_lag: 0.17 =========