builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0037 starttime: 1495988715.22 results: success (0) revision: 701e0ebc2b4b7ae57248e44fd06278e5309e1a05 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:15.215921) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:15.216440) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2017-05-28 09:25:15.216965) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2017-05-28 09:25:18.161825) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:18.162245) ========= 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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-28 09:25:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 190M=0s 2017-05-28 09:25:18 (190 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118079 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:18.303994) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:18.304367) ========= 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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.027181 ========= master_lag: 0.13 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:18.464101) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:18.464526) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 701e0ebc2b4b7ae57248e44fd06278e5309e1a05 --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 701e0ebc2b4b7ae57248e44fd06278e5309e1a05 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-28 09:25:18,558 truncating revision to first 12 chars 2017-05-28 09:25:18,558 Setting DEBUG logging. 2017-05-28 09:25:18,558 attempt 1/10 2017-05-28 09:25:18,558 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/701e0ebc2b4b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-28 09:25:18,868 unpacking tar archive at: mozilla-central-701e0ebc2b4b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.589682 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:19.073696) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:19.074054) ========= script_repo_revision: 701e0ebc2b4b7ae57248e44fd06278e5309e1a05 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:19.074463) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:19.074765) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-28 09:25:19.093383) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-05-28 09:25:19.093822) ========= /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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:25:19 INFO - MultiFileLogger online at 20170528 09:25:19 in /builds/slave/test 09:25:19 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:25:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:25:19 INFO - {'append_to_log': False, 09:25:19 INFO - 'base_work_dir': '/builds/slave/test', 09:25:19 INFO - 'blob_upload_branch': 'Firefox', 09:25:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:25:19 INFO - 'branch': 'Firefox', 09:25:19 INFO - 'buildbot_json_path': 'buildprops.json', 09:25:19 INFO - 'config_files': ('talos/mac_config.py',), 09:25:19 INFO - 'default_actions': ('clobber', 09:25:19 INFO - 'read-buildbot-config', 09:25:19 INFO - 'download-and-extract', 09:25:19 INFO - 'populate-webroot', 09:25:19 INFO - 'create-virtualenv', 09:25:19 INFO - 'install', 09:25:19 INFO - 'setup-mitmproxy', 09:25:19 INFO - 'run-tests'), 09:25:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:25:19 INFO - 'download_minidump_stackwalk': True, 09:25:19 INFO - 'download_symbols': 'ondemand', 09:25:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:25:19 INFO - 'tooltool.py': '/tools/tooltool.py', 09:25:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:25:19 INFO - '/tools/misc-python/virtualenv.py')}, 09:25:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:25:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:25:19 INFO - 'gecko_profile': False, 09:25:19 INFO - 'gecko_profile_interval': 0, 09:25:19 INFO - 'installer_path': 'installer.exe', 09:25:19 INFO - 'log_level': 'info', 09:25:19 INFO - 'log_name': 'talos', 09:25:19 INFO - 'log_to_console': True, 09:25:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:25:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:25:19 INFO - 'opt_config_files': (), 09:25:19 INFO - 'pip_index': False, 09:25:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:25:19 INFO - 'cmd': ('bash', 09:25:19 INFO - '-c', 09:25:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:25:19 INFO - 'enabled': True, 09:25:19 INFO - 'halt_on_failure': False, 09:25:19 INFO - 'name': 'check_screen_resolution'},), 09:25:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:25:19 INFO - 'cmd': ('bash', 09:25:19 INFO - '-c', 09:25:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:25:19 INFO - 'enabled': True, 09:25:19 INFO - 'halt_on_failure': False, 09:25:19 INFO - 'name': 'check_screen_resolution'},), 09:25:19 INFO - 'run_cmd_checks_enabled': True, 09:25:19 INFO - 'suite': 'dromaeojs-e10s', 09:25:19 INFO - 'system_bits': '32', 09:25:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:25:19 INFO - 'title': 't-yosemite-r7-0037', 09:25:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:25:19 INFO - 'use_talos_json': True, 09:25:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:25:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:25:19 INFO - 'work_dir': 'build'} 09:25:19 INFO - [mozharness: 2017-05-28 16:25:19.295465Z] Running clobber step. 09:25:19 INFO - Running pre-action listener: _resource_record_pre_action 09:25:19 INFO - Running main action method: clobber 09:25:19 INFO - rmtree: /builds/slave/test/build 09:25:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:25:21 INFO - Running post-action listener: _resource_record_post_action 09:25:21 INFO - [mozharness: 2017-05-28 16:25:21.608264Z] Finished clobber step (success) 09:25:21 INFO - [mozharness: 2017-05-28 16:25:21.608477Z] Running read-buildbot-config step. 09:25:21 INFO - Running pre-action listener: _resource_record_pre_action 09:25:21 INFO - Running main action method: read_buildbot_config 09:25:21 INFO - Using buildbot properties: 09:25:21 INFO - { 09:25:21 INFO - "project": "", 09:25:21 INFO - "product": "firefox", 09:25:21 INFO - "who": "ffxbld@noreply.mozilla.org", 09:25:21 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:25:21 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:25:21 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:25:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:25:21 INFO - "stage_platform": "macosx64", 09:25:21 INFO - "basedir": "/builds/slave/test", 09:25:21 INFO - "buildnumber": 82, 09:25:21 INFO - "platform": "yosemite_r7", 09:25:21 INFO - "parent_task_id": "foqfDGpxTa-VOVDciqNQkg", 09:25:21 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 09:25:21 INFO - "slavebuilddir": "test", 09:25:21 INFO - "taskId": "beeS3YVPSFWU-0s-nj2aHw", 09:25:21 INFO - "branch": "mozilla-central", 09:25:21 INFO - "script_repo_revision": "production", 09:25:21 INFO - "revision": "701e0ebc2b4b7ae57248e44fd06278e5309e1a05", 09:25:21 INFO - "slavename": "t-yosemite-r7-0037", 09:25:21 INFO - "repo_path": "mozilla-central" 09:25:21 INFO - } 09:25:21 INFO - Finding installer, test and symbols from parent task. 09:25:21 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/beeS3YVPSFWU-0s-nj2aHw'}, attempt #1 09:25:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/beeS3YVPSFWU-0s-nj2aHw'}, attempt #1 09:25:22 INFO - Task dependencies: Tl2c23L9QEGTGlb8rG1Y7w foqfDGpxTa-VOVDciqNQkg 09:25:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg'}, attempt #1 09:25:22 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:25:22 INFO - Set test_url: https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/test_packages.json 09:25:22 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/target.crashreporter-symbols.zip 09:25:22 INFO - Running post-action listener: _resource_record_post_action 09:25:22 INFO - [mozharness: 2017-05-28 16:25:22.975878Z] Finished read-buildbot-config step (success) 09:25:22 INFO - [mozharness: 2017-05-28 16:25:22.975973Z] Running download-and-extract step. 09:25:22 INFO - Running pre-action listener: _resource_record_pre_action 09:25:22 INFO - Running main action method: download_and_extract 09:25:22 INFO - mkdir: /builds/slave/test/build/tests 09:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:25:22 INFO - trying https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:25:22 INFO - Downloading https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/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:25:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/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:25:23 INFO - Downloaded 1325 bytes. 09:25:23 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:25:23 INFO - Using the following test package requirements: 09:25:23 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:25:23 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:25:23 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:25:23 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:25:23 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'jsshell-mac.zip'], 09:25:23 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:25:23 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:25:23 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:25:23 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:25:23 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:25:23 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:25:23 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:25:23 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:25:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:25:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:25:23 INFO - Fetch https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:25:25 INFO - Content-Length response header: 57633804 09:25:25 INFO - Bytes received: 57633804 09:25:44 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:25:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:25:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:25:44 INFO - Fetch https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:25:46 INFO - Content-Length response header: 57633804 09:25:46 INFO - Bytes received: 57633804 09:26:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:26:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:26:03 INFO - Fetch https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:26:04 INFO - Content-Length response header: 14447425 09:26:04 INFO - Bytes received: 14447425 09:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:05 INFO - trying https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:26:05 INFO - Downloading https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:26:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/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:26:07 INFO - Downloaded 67394093 bytes. 09:26:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:26:07 INFO - mkdir: /builds/slave/test/properties 09:26:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:26:07 INFO - Writing to file /builds/slave/test/properties/build_url 09:26:07 INFO - Contents: 09:26:07 INFO - build_url:https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:26:07 INFO - Running post-action listener: _resource_record_post_action 09:26:07 INFO - Running post-action listener: set_extra_try_arguments 09:26:07 INFO - [mozharness: 2017-05-28 16:26:07.302831Z] Finished download-and-extract step (success) 09:26:07 INFO - [mozharness: 2017-05-28 16:26:07.302949Z] Running populate-webroot step. 09:26:07 INFO - Running pre-action listener: _resource_record_pre_action 09:26:07 INFO - Running main action method: populate_webroot 09:26:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:26:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:26:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:26:07 INFO - '--tppagecycles', 09:26:07 INFO - '7'], 09:26:07 INFO - 'tests': ['tsvgm']}}, 09:26:07 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:26:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:26:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:26:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:26:07 INFO - 'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:26:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:26:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:26:07 INFO - 'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['damp', 'tps']}, 09:26:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:26:07 INFO - 'tests': ['damp', 'tps']}, 09:26:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['dromaeo_dom']}, 09:26:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:26:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:26:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:26:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:26:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:26:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['a11yr', 09:26:07 INFO - 'ts_paint', 09:26:07 INFO - 'tpaint', 09:26:07 INFO - 'sessionrestore', 09:26:07 INFO - 'sessionrestore_no_auto_restore', 09:26:07 INFO - 'tabpaint']}, 09:26:07 INFO - 'other-e10s': {'tests': ['a11yr', 09:26:07 INFO - 'ts_paint', 09:26:07 INFO - 'tpaint', 09:26:07 INFO - 'sessionrestore', 09:26:07 INFO - 'sessionrestore_no_auto_restore', 09:26:07 INFO - 'tabpaint']}, 09:26:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:26:07 INFO - 'ts_paint', 09:26:07 INFO - 'tpaint', 09:26:07 INFO - 'sessionrestore', 09:26:07 INFO - 'sessionrestore_no_auto_restore', 09:26:07 INFO - 'tabpaint']}, 09:26:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:26:07 INFO - 'ts_paint', 09:26:07 INFO - 'tpaint', 09:26:07 INFO - 'sessionrestore', 09:26:07 INFO - 'sessionrestore_no_auto_restore', 09:26:07 INFO - 'tabpaint']}, 09:26:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['a11yr', 09:26:07 INFO - 'ts_paint', 09:26:07 INFO - 'tpaint', 09:26:07 INFO - 'sessionrestore', 09:26:07 INFO - 'sessionrestore_no_auto_restore', 09:26:07 INFO - 'tabpaint']}, 09:26:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['a11yr', 09:26:07 INFO - 'ts_paint', 09:26:07 INFO - 'tpaint', 09:26:07 INFO - 'sessionrestore', 09:26:07 INFO - 'sessionrestore_no_auto_restore', 09:26:07 INFO - 'tabpaint']}, 09:26:07 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:26:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:26:07 INFO - 'bloom_basic_ref']}, 09:26:07 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:26:07 INFO - 'talos_options': ['--mitmproxy', 09:26:07 INFO - 'mitmproxy-recording-1.mp', 09:26:07 INFO - '--firstNonBlankPaint'], 09:26:07 INFO - 'tests': ['Quantum_1']}, 09:26:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['tsvgx', 09:26:07 INFO - 'tsvgr_opacity', 09:26:07 INFO - 'tart', 09:26:07 INFO - 'tscrollx', 09:26:07 INFO - 'cart', 09:26:07 INFO - 'tsvg_static']}, 09:26:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:26:07 INFO - 'tsvgr_opacity', 09:26:07 INFO - 'tart', 09:26:07 INFO - 'tscrollx', 09:26:07 INFO - 'cart', 09:26:07 INFO - 'tsvg_static']}, 09:26:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:26:07 INFO - 'talos_options': ['--disable-e10s'], 09:26:07 INFO - 'tests': ['tp5o']}, 09:26:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:26:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:26:07 INFO - 'talos_options': ['--disable-e10s', 09:26:07 INFO - '--xperf_path', 09:26:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:07 INFO - 'tests': ['tp5n']}, 09:26:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:26:07 INFO - 'talos_options': ['--xperf_path', 09:26:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:26:07 INFO - 'tests': ['tp5n']}}, 09:26:07 INFO - 'talos.zip': {'path': '', 09:26:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:26:07 INFO - Running post-action listener: _resource_record_post_action 09:26:07 INFO - [mozharness: 2017-05-28 16:26:07.325307Z] Finished populate-webroot step (success) 09:26:07 INFO - [mozharness: 2017-05-28 16:26:07.325447Z] Running create-virtualenv step. 09:26:07 INFO - Running pre-action listener: _resource_record_pre_action 09:26:07 INFO - Running main action method: create_virtualenv 09:26:07 INFO - Creating virtualenv /builds/slave/test/build/venv 09:26:07 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:26:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:26:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:26:07 INFO - Using real prefix '/tools/python27' 09:26:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:26:08 INFO - Installing distribute.............................................................................................................................................................................................done. 09:26:11 INFO - Installing pip.................done. 09:26:11 INFO - Return code: 0 09:26:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:26:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:26:11 INFO - Reading from file tmpfile_stdout 09:26:11 INFO - Output received: 09:26:11 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:26:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:12 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:12 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:26:12 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:26:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:12 INFO - 'HOME': '/Users/cltbld', 09:26:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:12 INFO - 'LOGNAME': 'cltbld', 09:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:12 INFO - 'MOZ_NO_REMOTE': '1', 09:26:12 INFO - 'NO_EM_RESTART': '1', 09:26:12 INFO - 'PAGER': '/bin/cat', 09:26:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:12 INFO - 'PWD': '/builds/slave/test', 09:26:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:12 INFO - 'SHELL': '/bin/bash', 09:26:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:12 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:12 INFO - 'USER': 'cltbld', 09:26:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:12 INFO - 'XPC_FLAGS': '0x0', 09:26:12 INFO - 'XPC_SERVICE_NAME': '0', 09:26:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:12 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:26:12 INFO - Cleaning up... 09:26:12 INFO - Return code: 0 09:26:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:12 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:12 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:26:12 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:26:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:12 INFO - 'HOME': '/Users/cltbld', 09:26:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:12 INFO - 'LOGNAME': 'cltbld', 09:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:12 INFO - 'MOZ_NO_REMOTE': '1', 09:26:12 INFO - 'NO_EM_RESTART': '1', 09:26:12 INFO - 'PAGER': '/bin/cat', 09:26:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:12 INFO - 'PWD': '/builds/slave/test', 09:26:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:12 INFO - 'SHELL': '/bin/bash', 09:26:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:12 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:12 INFO - 'USER': 'cltbld', 09:26:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:12 INFO - 'XPC_FLAGS': '0x0', 09:26:12 INFO - 'XPC_SERVICE_NAME': '0', 09:26:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:13 INFO - Downloading/unpacking psutil>=3.1.1 09:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:26:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:15 INFO - Installing collected packages: psutil 09:26:15 INFO - Running setup.py install for psutil 09:26:15 INFO - building 'psutil._psutil_osx' extension 09:26:15 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:26:16 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:26:16 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:26:16 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:26:16 INFO - building 'psutil._psutil_posix' extension 09:26:16 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:26:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:26:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:26:16 INFO - ^ 09:26:16 INFO - 1 warning generated. 09:26:16 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:26:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:26:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:26:16 INFO - Successfully installed psutil 09:26:16 INFO - Cleaning up... 09:26:16 INFO - Return code: 0 09:26:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:16 INFO - 'HOME': '/Users/cltbld', 09:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:16 INFO - 'LOGNAME': 'cltbld', 09:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:16 INFO - 'MOZ_NO_REMOTE': '1', 09:26:16 INFO - 'NO_EM_RESTART': '1', 09:26:16 INFO - 'PAGER': '/bin/cat', 09:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:16 INFO - 'PWD': '/builds/slave/test', 09:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:16 INFO - 'SHELL': '/bin/bash', 09:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:16 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:16 INFO - 'USER': 'cltbld', 09:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:16 INFO - 'XPC_FLAGS': '0x0', 09:26:16 INFO - 'XPC_SERVICE_NAME': '0', 09:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:26:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:26:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:26:18 INFO - Installing collected packages: mozsystemmonitor 09:26:18 INFO - Running setup.py install for mozsystemmonitor 09:26:18 INFO - Successfully installed mozsystemmonitor 09:26:18 INFO - Cleaning up... 09:26:18 INFO - Return code: 0 09:26:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:18 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:18 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:26:18 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:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:18 INFO - 'HOME': '/Users/cltbld', 09:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:18 INFO - 'LOGNAME': 'cltbld', 09:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:18 INFO - 'MOZ_NO_REMOTE': '1', 09:26:18 INFO - 'NO_EM_RESTART': '1', 09:26:18 INFO - 'PAGER': '/bin/cat', 09:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:18 INFO - 'PWD': '/builds/slave/test', 09:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:18 INFO - 'SHELL': '/bin/bash', 09:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:18 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:18 INFO - 'USER': 'cltbld', 09:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:18 INFO - 'XPC_FLAGS': '0x0', 09:26:18 INFO - 'XPC_SERVICE_NAME': '0', 09:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:18 INFO - Downloading/unpacking jsonschema==2.5.1 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:26:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:20 INFO - Downloading functools32-3.2.3-2.tar.gz 09:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:26:20 INFO - warning: no files found matching '*.txt' 09:26:20 INFO - no previously-included directories found matching 'build' 09:26:20 INFO - no previously-included directories found matching 'dist' 09:26:20 INFO - no previously-included directories found matching '.git*' 09:26:20 INFO - Installing collected packages: jsonschema, functools32 09:26:20 INFO - Running setup.py install for functools32 09:26:20 INFO - warning: no files found matching '*.txt' 09:26:20 INFO - no previously-included directories found matching 'build' 09:26:20 INFO - no previously-included directories found matching 'dist' 09:26:20 INFO - no previously-included directories found matching '.git*' 09:26:20 INFO - Successfully installed jsonschema functools32 09:26:20 INFO - Cleaning up... 09:26:20 INFO - Return code: 0 09:26:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:20 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:20 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:26:20 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:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:20 INFO - 'HOME': '/Users/cltbld', 09:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:20 INFO - 'LOGNAME': 'cltbld', 09:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:20 INFO - 'MOZ_NO_REMOTE': '1', 09:26:20 INFO - 'NO_EM_RESTART': '1', 09:26:20 INFO - 'PAGER': '/bin/cat', 09:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:20 INFO - 'PWD': '/builds/slave/test', 09:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:20 INFO - 'SHELL': '/bin/bash', 09:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:20 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:20 INFO - 'USER': 'cltbld', 09:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:20 INFO - 'XPC_FLAGS': '0x0', 09:26:20 INFO - 'XPC_SERVICE_NAME': '0', 09:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:26:21 INFO - Cleaning up... 09:26:21 INFO - Return code: 0 09:26:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:21 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:21 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:26:21 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:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:21 INFO - 'HOME': '/Users/cltbld', 09:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:21 INFO - 'LOGNAME': 'cltbld', 09:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:21 INFO - 'MOZ_NO_REMOTE': '1', 09:26:21 INFO - 'NO_EM_RESTART': '1', 09:26:21 INFO - 'PAGER': '/bin/cat', 09:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:21 INFO - 'PWD': '/builds/slave/test', 09:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:21 INFO - 'SHELL': '/bin/bash', 09:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:21 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:21 INFO - 'USER': 'cltbld', 09:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:21 INFO - 'XPC_FLAGS': '0x0', 09:26:21 INFO - 'XPC_SERVICE_NAME': '0', 09:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:21 INFO - Downloading/unpacking blobuploader==1.2.4 09:26:21 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:26:21 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:26:21 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:26:21 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:26:22 INFO - Downloading blobuploader-1.2.4.tar.gz 09:26:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:26:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:26:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:26: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:26: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:26: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:26: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:26:23 INFO - Downloading docopt-0.6.1.tar.gz 09:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:26:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:26:23 INFO - Running setup.py install for blobuploader 09:26:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:26:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:26:23 INFO - Running setup.py install for requests 09:26:24 INFO - Running setup.py install for docopt 09:26:24 INFO - Successfully installed blobuploader requests docopt 09:26:24 INFO - Cleaning up... 09:26:24 INFO - Return code: 0 09:26:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:24 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:24 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:26:24 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:26:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:24 INFO - 'HOME': '/Users/cltbld', 09:26:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:24 INFO - 'LOGNAME': 'cltbld', 09:26:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:24 INFO - 'MOZ_NO_REMOTE': '1', 09:26:24 INFO - 'NO_EM_RESTART': '1', 09:26:24 INFO - 'PAGER': '/bin/cat', 09:26:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:24 INFO - 'PWD': '/builds/slave/test', 09:26:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:24 INFO - 'SHELL': '/bin/bash', 09:26:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:24 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:24 INFO - 'USER': 'cltbld', 09:26:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:24 INFO - 'XPC_FLAGS': '0x0', 09:26:24 INFO - 'XPC_SERVICE_NAME': '0', 09:26:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-NYuD6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:24 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-oJICve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-SnoAAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-z9pAEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-_HrGTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-5n0YFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-jYioBY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-fmQFcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-qzT7Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-v43I4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:25 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-PK5P7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-CGWrin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-M7Lvpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-qb4zs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-_UCmMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-NF9Qj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:26 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-2cMji8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:26:26 INFO - Running setup.py install for manifestparser 09:26:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:26:26 INFO - Running setup.py install for mozcrash 09:26:26 INFO - Running setup.py install for mozdebug 09:26:27 INFO - Running setup.py install for mozdevice 09:26:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:26:27 INFO - Running setup.py install for mozfile 09:26:27 INFO - Running setup.py install for mozhttpd 09:26:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:26:27 INFO - Running setup.py install for mozinfo 09:26:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:26:27 INFO - Running setup.py install for mozInstall 09:26:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:26:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:26:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:26:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:26:27 INFO - Running setup.py install for mozleak 09:26:27 INFO - Running setup.py install for mozlog 09:26:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:26:28 INFO - Running setup.py install for moznetwork 09:26:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:26:28 INFO - Running setup.py install for mozprocess 09:26:28 INFO - Running setup.py install for mozprofile 09:26:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:26:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:26:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:26:28 INFO - Running setup.py install for mozrunner 09:26:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:26:28 INFO - Running setup.py install for mozscreenshot 09:26:28 INFO - Running setup.py install for moztest 09:26:29 INFO - Running setup.py install for mozversion 09:26:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:26:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:26:29 INFO - Cleaning up... 09:26:29 INFO - Return code: 0 09:26:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:26:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:29 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:29 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:26:29 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:26:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:29 INFO - 'HOME': '/Users/cltbld', 09:26:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:29 INFO - 'LOGNAME': 'cltbld', 09:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:29 INFO - 'MOZ_NO_REMOTE': '1', 09:26:29 INFO - 'NO_EM_RESTART': '1', 09:26:29 INFO - 'PAGER': '/bin/cat', 09:26:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:29 INFO - 'PWD': '/builds/slave/test', 09:26:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:29 INFO - 'SHELL': '/bin/bash', 09:26:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:29 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:29 INFO - 'USER': 'cltbld', 09:26:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:29 INFO - 'XPC_FLAGS': '0x0', 09:26:29 INFO - 'XPC_SERVICE_NAME': '0', 09:26:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ITLfjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:26:29 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:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-FcZTeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:26:29 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:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-zvMJSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:26:29 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:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ahEBY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:26:29 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:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:26:29 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-3bmjlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-ckWWOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-sB9t69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-3NQlEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-S_CD3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-xoZuou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Cd48iG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-Nm4V8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-p4vayc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:26:30 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:26:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:26:30 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-6mtV9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:31 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-EiNIbo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:26:31 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-PKy6fX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:26:31 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:26:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:26:31 INFO - Running setup.py (path:/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/pip-qabOnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:26:31 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:26:31 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:26:31 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:26:31 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:26:31 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:26:31 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:26:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:26:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:26:32 INFO - Downloading blessings-1.6.tar.gz 09:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:26:32 INFO - Installing collected packages: blessings 09:26:32 INFO - Running setup.py install for blessings 09:26:33 INFO - Successfully installed blessings 09:26:33 INFO - Cleaning up... 09:26:33 INFO - Return code: 0 09:26:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:26:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:33 INFO - Reading from file tmpfile_stdout 09:26:33 INFO - Current package versions: 09:26:33 INFO - blessings == 1.6 09:26:33 INFO - blobuploader == 1.2.4 09:26:33 INFO - docopt == 0.6.1 09:26:33 INFO - functools32 == 3.2.3-2 09:26:33 INFO - jsonschema == 2.5.1 09:26:33 INFO - manifestparser == 1.1 09:26:33 INFO - mozInstall == 1.12 09:26:33 INFO - mozcrash == 1.0 09:26:33 INFO - mozdebug == 0.1 09:26:33 INFO - mozdevice == 0.51 09:26:33 INFO - mozfile == 1.2 09:26:33 INFO - mozhttpd == 0.7 09:26:33 INFO - mozinfo == 0.9 09:26:33 INFO - mozleak == 0.1 09:26:33 INFO - mozlog == 3.4 09:26:33 INFO - moznetwork == 0.27 09:26:33 INFO - mozprocess == 0.25 09:26:33 INFO - mozprofile == 0.28 09:26:33 INFO - mozrunner == 6.13 09:26:33 INFO - mozscreenshot == 0.1 09:26:33 INFO - mozsystemmonitor == 0.3 09:26:33 INFO - moztest == 0.8 09:26:33 INFO - mozversion == 1.4 09:26:33 INFO - psutil == 3.1.1 09:26:33 INFO - requests == 1.2.3 09:26:33 INFO - wsgiref == 0.1.2 09:26:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:26:33 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:26:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26:33 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:26:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:33 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:33 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:26:33 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:26:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:33 INFO - 'HOME': '/Users/cltbld', 09:26:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:33 INFO - 'LOGNAME': 'cltbld', 09:26:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:33 INFO - 'MOZ_NO_REMOTE': '1', 09:26:33 INFO - 'NO_EM_RESTART': '1', 09:26:33 INFO - 'PAGER': '/bin/cat', 09:26:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:33 INFO - 'PWD': '/builds/slave/test', 09:26:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:33 INFO - 'SHELL': '/bin/bash', 09:26:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:33 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:33 INFO - 'USER': 'cltbld', 09:26:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:33 INFO - 'XPC_FLAGS': '0x0', 09:26:33 INFO - 'XPC_SERVICE_NAME': '0', 09:26:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:33 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:26:33 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:26:33 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:26:33 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:26:33 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:26:33 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:26:33 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:26:33 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:26:33 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:26:33 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:26:33 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:26:33 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:26:33 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:26:33 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:26:35 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:26:35 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:26:35 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:26:35 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:26:35 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:26:35 INFO - Installing collected packages: marionette-driver 09:26:35 INFO - Running setup.py install for marionette-driver 09:26:35 INFO - Successfully installed marionette-driver 09:26:35 INFO - Cleaning up... 09:26:35 INFO - Return code: 0 09:26:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:26: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:26:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:26: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:26:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:26:35 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 0x10aa19c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa1d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7ff158bd40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, '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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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:26:35 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:26:35 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:26:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:35 INFO - 'HOME': '/Users/cltbld', 09:26:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:35 INFO - 'LOGNAME': 'cltbld', 09:26:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:35 INFO - 'MOZ_NO_REMOTE': '1', 09:26:35 INFO - 'NO_EM_RESTART': '1', 09:26:35 INFO - 'PAGER': '/bin/cat', 09:26:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:35 INFO - 'PWD': '/builds/slave/test', 09:26:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:35 INFO - 'SHELL': '/bin/bash', 09:26:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:35 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:35 INFO - 'USER': 'cltbld', 09:26:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:35 INFO - 'XPC_FLAGS': '0x0', 09:26:35 INFO - 'XPC_SERVICE_NAME': '0', 09:26:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:26:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:26:35 INFO - Cleaning up... 09:26:35 INFO - Return code: 0 09:26:35 INFO - Running post-action listener: _resource_record_post_action 09:26:35 INFO - Running post-action listener: _start_resource_monitoring 09:26:35 INFO - Starting resource monitoring. 09:26:35 INFO - [mozharness: 2017-05-28 16:26:35.720239Z] Finished create-virtualenv step (success) 09:26:35 INFO - [mozharness: 2017-05-28 16:26:35.721384Z] Running install step. 09:26:35 INFO - Running pre-action listener: _resource_record_pre_action 09:26:35 INFO - Running main action method: install 09:26:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:26:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:26:36 INFO - Reading from file tmpfile_stdout 09:26:36 INFO - Detecting whether we're running mozinstall >=1.0... 09:26:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:26:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:26:36 INFO - Reading from file tmpfile_stdout 09:26:36 INFO - Output received: 09:26:36 INFO - Usage: mozinstall [options] installer 09:26:36 INFO - Options: 09:26:36 INFO - -h, --help show this help message and exit 09:26:36 INFO - -d DEST, --destination=DEST 09:26:36 INFO - Directory to install application into. [default: 09:26:36 INFO - "/builds/slave/test"] 09:26:36 INFO - --app=APP Application being installed. [default: firefox] 09:26:36 INFO - mkdir: /builds/slave/test/build/application 09:26:36 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:26:36 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:26:57 INFO - Reading from file tmpfile_stdout 09:26:57 INFO - Output received: 09:26:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:26:57 INFO - Running post-action listener: _resource_record_post_action 09:26:57 INFO - [mozharness: 2017-05-28 16:26:57.900739Z] Finished install step (success) 09:26:57 INFO - [mozharness: 2017-05-28 16:26:57.900903Z] Running setup-mitmproxy step. 09:26:57 INFO - Running pre-action listener: _resource_record_pre_action 09:26:57 INFO - Running main action method: setup_mitmproxy 09:26:57 INFO - Skipping: mitmproxy is not required 09:26:57 INFO - Running post-action listener: _resource_record_post_action 09:26:57 INFO - [mozharness: 2017-05-28 16:26:57.901578Z] Finished setup-mitmproxy step (success) 09:26:57 INFO - [mozharness: 2017-05-28 16:26:57.901692Z] Running run-tests step. 09:26:57 INFO - Running pre-action listener: _resource_record_pre_action 09:26:57 INFO - Running main action method: run_tests 09:26:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:26:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:26:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:26:57 INFO - 2017-05-28 09:26:57.916 screenresolution[1626:7453] starting screenresolution argv=screenresolution get 09:26:57 INFO - 2017-05-28 09:26:57.919 screenresolution[1626:7453] Display 0: 1600x1200x32@60 09:26:57 INFO - 2017-05-28 09:26:57.925 screenresolution[1627:7456] starting screenresolution argv=screenresolution list 09:26:57 INFO - Available Modes on Display 0 09:26:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:26:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:26:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:26:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:26:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:26:58 INFO - Intel Iris: 09:26:58 INFO - Chipset Model: Intel Iris 09:26:58 INFO - Type: GPU 09:26:58 INFO - Bus: Built-In 09:26:58 INFO - VRAM (Dynamic, Max): 1536 MB 09:26:58 INFO - Vendor: Intel (0x8086) 09:26:58 INFO - Device ID: 0x0a2e 09:26:58 INFO - Revision ID: 0x0009 09:26:58 INFO - Displays: 09:26:58 INFO - Display: 09:26:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:26:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:26:58 INFO - Display Serial Number: 1600x1200 60 09:26:58 INFO - Main Display: Yes 09:26:58 INFO - Mirror: Off 09:26:58 INFO - Online: Yes 09:26:58 INFO - Rotation: Supported 09:26:58 INFO - Return code: 0 09:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:26:58 INFO - Python 2.7.3 09:26:58 INFO - Return code: 0 09:26:58 INFO - grabbing minidump binary from tooltool 09:26:58 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:26:58 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 0x10a9a2c60>, '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 0x10a737cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a760be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:26:58 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:26:58 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:26:58 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:26:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:26:58 INFO - Return code: 0 09:26:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:26:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:26:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:26:58 INFO - ENV: RUST_BACKTRACE is now 1 09:26:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:26:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:26:58 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/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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:26:58 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/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0037 --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:26:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.F7rvW0HrIQ/Render', 09:26:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:26:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:26:58 INFO - 'HOME': '/Users/cltbld', 09:26:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:26:58 INFO - 'LOGNAME': 'cltbld', 09:26:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:26:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:26:58 INFO - 'MOZ_NO_REMOTE': '1', 09:26:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:26:58 INFO - 'NO_EM_RESTART': '1', 09:26:58 INFO - 'PAGER': '/bin/cat', 09:26:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:26:58 INFO - 'PWD': '/builds/slave/test', 09:26:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:26:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:26:58 INFO - 'RUST_BACKTRACE': '1', 09:26:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:26:58 INFO - 'SHELL': '/bin/bash', 09:26:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tQmA4ehhI1/Listeners', 09:26:58 INFO - 'TMPDIR': '/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/', 09:26:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:26:58 INFO - 'USER': 'cltbld', 09:26:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:26:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:26:58 INFO - 'XPC_FLAGS': '0x0', 09:26:58 INFO - 'XPC_SERVICE_NAME': '0', 09:26:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:26:58 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/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0037', '--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:26:58 INFO - mozversion application_buildid: 20170528081755 09:26:58 INFO - mozversion application_changeset: 701e0ebc2b4b7ae57248e44fd06278e5309e1a05 09:26:58 INFO - mozversion application_display_name: Nightly 09:26:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:26:58 INFO - mozversion application_name: Firefox 09:26:58 INFO - mozversion application_remotingname: firefox 09:26:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:26:58 INFO - mozversion application_vendor: Mozilla 09:26:58 INFO - mozversion application_version: 55.0a1 09:26:58 INFO - mozversion platform_buildid: 20170528081755 09:26:58 INFO - mozversion platform_changeset: 701e0ebc2b4b7ae57248e44fd06278e5309e1a05 09:26:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:26:58 INFO - mozversion platform_version: 55.0a1 09:26:58 INFO - using testdate: 1495988818 09:26:58 INFO - actual date: 1495988818 09:26:58 INFO - starting webserver on 'localhost:49228' 09:26:58 INFO - SUITE-START | Running 2 tests 09:26:58 INFO - TEST-START | dromaeo_css 09:26:58 INFO - operating with platform_type : mac_ 09:26:58 INFO - Initialising browser for dromaeo_css test... 09:26:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmp2mrEGp/profile 09:26:58 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 09:27:32 INFO - TEST-INFO | 1637: killed by SIGTERM 09:27:32 INFO - Browser initialized. 09:27:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:27:32 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmp2mrEGp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:35 INFO - PID 1644 | 2017-05-28 09:27:35.282 plugin-container[1645:7937] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994f, name = 'com.apple.tsm.portname' 09:27:35 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:35 INFO - PID 1644 | 2017-05-28 09:27:35.283 plugin-container[1645:7937] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4913, name = 'com.apple.CFPasteboardClient' 09:27:35 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:35 INFO - PID 1644 | 2017-05-28 09:27:35.283 plugin-container[1645:7937] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:35 INFO - PID 1644 | 2017-05-28 09:27:35.712 plugin-container[1646:7983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 09:27:35 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:35 INFO - PID 1644 | 2017-05-28 09:27:35.712 plugin-container[1646:7983] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 09:27:35 INFO - PID 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:27:35 INFO - PID 1644 | 2017-05-28 09:27:35.712 plugin-container[1646:7983] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:27:45 INFO - PID 1644 | RSS: Main: 262483968 09:27:45 INFO - PID 1644 | 09:30:47 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 09:30:49 INFO - PID 1644 | RSS: Main: 269377536 09:30:49 INFO - PID 1644 | 09:33:50 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 09:33:51 INFO - PID 1644 | RSS: Main: 281075712 09:33:51 INFO - PID 1644 | 09:36:53 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 09:36:53 INFO - PID 1644 | RSS: Main: 282619904 09:36:53 INFO - PID 1644 | 09:39:55 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 09:39:56 INFO - PID 1644 | RSS: Main: 286294016 09:39:56 INFO - PID 1644 | 09:42:58 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 09:42:59 INFO - PID 1644 | RSS: Main: 286466048 09:42:59 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 09:46:01 INFO - PID 1644 | RSS: Main: 286490624 09:46:01 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | __start_tp_report 09:46:01 INFO - PID 1644 | _x_x_mozilla_page_load 09:46:01 INFO - PID 1644 | _x_x_mozilla_page_load_details 09:46:01 INFO - PID 1644 | |i|pagename|runs| 09:46:01 INFO - PID 1644 | |0;dojo.html;3605.621409751976;3607.3146102240576;3630.146915474861;3637.2907283079794;3651.470536772167;6137.223770008885;6152.107944348072;6186.226721659793;6203.937960620392;6245.9063114053215;6176.660283684396;6180.907286390697;6217.37826217378;6264.185655864742;6288.717007734652;5052.9242061369105;5484.040292948738;5498.862528436781;5526.861828454301;5559.249501317331;5554.305711786027;5587.469190426903;5620.57845661575;5631.549476041927;5652.378238393773;4036.6568841648495;5194.986977539382;5291.761870715827;5420.024395608789;5513.09034009386;5511.338639363262;5525.557955363602;5534.335879694422;5549.278593782823;5565.499105080562;5418.566514678816;5449.237106805011;5455.8363249102595;5470.671759694391;5537.390887002426;5197.58419326453;6125.17440701618;6171.166892469549;6204.379562043805;6217.409346112112;6697.095892054559;6733.562318449285;6752.189737231563;6760;6764.4926630502905;6848.554843935129;6857.759978400732;6858.177018757781;6862.176538815324;6878.105846240007;5120.334356533627;5835.824925252249;6200.596961197508;6249.78125765596;6316.147320111818;6946.305369463013;6976.093107895941;6978.685959131852;6998.6150761708595;7008.123984501937;5336.893262134736;6433;6442.226932768098;6548.1814773153355;6627.4366678831875;6696.933030669728;6797.830054248683;6805.557638753564;6814.727410903607;6815.897761533581;6263.686815659199;6404.615723056631;6435.195600549931;6710.83222919431;6740.9662951685905;6409.583377080475;6588.901166482507;6635.734570617218;6649.567778094409;6698.095757072733;5401.378841433207;5412.621116521885;5413.296271484761;5413.566914646819;5418.512333889968;3896.5518965318784;4773.95226047742;4777.880552986203;4798.088363210979;4875.780589873464;5117.283580298761;6177.907331390033;6179.938200617937;6214.875702486015;6244.718987645567;6825.55633883796;6826.761063362758;6830.590164590041;6836.555623884531;6848.246692863781;6242.75028998844;6251.093591429218;6254.499640028877;6264.060390941304;6285.811425657238;5877.794277200363;5921.259842519685;6001.309849367379;6003.759849606055;6126.08108783686;6931.2375638679705;6982.266861979419;7003.964980175067;7026.543274687129;7036.542624729376;5644;6833.555818871758;6836.760713375008;6859.5541289818;6873.828154296182;6686.3313668632745;6793.864122717419;6957.478189135936;6979.336962988508;6984.685689144102;6586.209654841449;6702.726481968411;6721.596704197764;6731.46148308144;6732.596044237362;6838.692258848463;6844.041834143124;6844.486663500158;6846.726130954905;6885.827854303682;6436.517261205522;6485.156929599122;6530.118434011348;6558.344165583594;6596.944488881756;6606.207255129415;6610.8016759497295;6613.966930165511;6624.370684784938;6645.800625981228;5435.429279925551;6446.774362897276;6481.4166724994975;6486.05952136928;6496.058071579689;6375.808725738235;6458.192725909262;6495.837604059936;6582;6613.636250006103;6366.140571022853;6415.679216039272;6429.646369449725;6430.871382572229;6435.163428754232 09:46:01 INFO - PID 1644 | |1;ext.html;773.9110002349728;808.9757307280781;831.1771346367094;1175.8946590205212;1248.082659245455;35567.82216088919;38771.612283877155;38781.030474238156;38785;38801.22397552047;31319;38411.42382864259;38915.80542097292;38928.80535597325;38951.61048389522;7765.339946104574;7933.603319834014;8101.311388531982;8286;8307;7230.566166030055;8249.463784853995;8393.034800997872;8398.03422606402;8408.621612027473;6951.339622735832;7261.237570055147;8339.624716887723;8350.83298334033;8413.831723365529;7244.094488188976;8302.83394332113;8380.329573634095;8382.497050176948;8395.118512556155;8171.7548473545885;8175.836483270361;8204.261616454547;8206.466579672302;8247.051589067274;36407.453888191696;36435.089122772144;36619.63380366189;36724.08189795277;36897;3062.892798752033;3574.803385813779;3591.892243232707;3592.209713862946;3595.856165753367;29093;29344.853275733698;29459.558106628418;29612.851935740182;29706.25734356637;3064.7241748242764;3070.1096681962204;3101.72084512395;3109.300407408338;3110.5178697301953;248165;252232;276176;302064;302574;47395.76302118467;47422.76288618535;47615.04769904583;47674.76162619165;47701.284480732815;49820.75089624601;50011.74994125079;50073.49926500688;50111.489965903245;50189.49810501848;14020.929895350459;14111.858881411259;14122.50571230002;14148.504802331869;14159.504417345344;11524.827127593093;11526.250793698382;11529.827052594217;11532.481038353295;11614.941925290319;13568.525101621395;13578.592642220749;13589.456421743218;13621.455141794217;13633.659158520917;6124.6325220486915;6145.600535965148;6187.783427580013;6189.659568723673;6221.626702397871;921.4517362169498;938.1978408460668;988.9159421449258;1014.1582486536157;1057.7408534908843;22076.88961555182;24245.272641820775;24563.38591535226;24671.3832154194;24860.50278994446;1106.607154460155;1117.9944100279608;1130.9491072901574;1141.937193454368;1146.6101525481376;12455.75088498225;12521.874781252252;12610.180338277982;12622.116451848282;12710.936445317715;302192;303524;304623.4768826186;305229;306438;53565.92868142615;53887.19169212465;54207;54214.728926355114;54302.72848635811;1498.6178524476406;1511.2217208137818;1541.4373753579862;1551.4880089570415;1551.5267843307674;18007.729884052012;23050;24036.158734444212;24164.63753043741;24264.51470970606;37504;37534.8123259382;37627.2474550502;37671.434928475544;37676.2464750709;215576;259382.40617593582;259690;259750;259910;27594.44811103807;28109.859450702617;30289.09132726022;34305.79929702448;34442;38691.613083868804;39564.60435395609;40309.39535906905;40350;40555.59444405518;27577.310567235978;28312;28558.42883142367;28956.855215723786;29044.27389315284;28428.43143137167;28795.712042879302;29024.854875725487;29164.7083529162;29207.561886572144;34297.82851085729;34328.82835585906;34359.65640343565;34370.96887093391;34385.82807085948;27087.051953181544;27346;27736.02923897654;29135.562966555095;29429.85285073561;20877.791222087584;22970.19604313841;23049.308520744406;23102;23114.88442557844 09:46:01 INFO - PID 1644 | |2;jquery.html;388.5609261534466;415.4619767401217;424.33379594037376;435.48830124603603;449.6919610067104;20114.89368084755;21674.566508669835;21727.91360431976;21771.564568708618;21800.345989620317;22520.324390268317;23128.959196836142;23366.182183623576;23435.765642343573;23758;5892.793752218662;6152.538559608025;6158.322584515699;6180.474659653913;6202.534175393732;5841;5968.820935371946;6019;6121.387861213888;6129.601575897553;5999.850003749898;6007.729652165663;6012.669303188323;6016.879662406749;6027.126066720323;5104.285400043997;5136.8972620547565;5167.044096842059;5791.160281759121;6008.48927841134;5380.704061276629;5383.892322153574;5387.568994480433;5399.83800485986;5400.91898621521;26336.946522139093;27208.45583088327;27227;27280.727192728016;27536;1945.8151475609795;2092.267706302782;2176.216562037665;2179.063002908749;2186.6829309750156;13625.727485450334;14271.500497482642;16530.173491325386;18116.818831811644;18289.542761430937;1960.7745109312327;2085.301424022954;2196.7254093238344;2199.131343119459;2199.8790066546335;150884;151438;151653.2417337917;151747;152356;12491.93754031224;12717.745645087232;12871.22772633645;12979.545715899898;13033.674158146123;16309.755353669705;16428.260728267258;16437.58906027359;16443.917780411262;16450.095244761415;7498.4001279897475;8794;8940.240079593177;9007.954960225288;9101.81796364069;8462.746117616482;8466.068732439428;8467.322614190973;8573.657053717907;8610.87083693745;7012.614306213105;7879.960600197077;8034.357251419872;8048.879266811003;8120.959395202986;2130.3289463819156;2148.387709502784;2156.1914282144194;2156.870587764739;2171.055590817999;975.9804803903883;993.1806259835664;995.1441760086465;1023.0587062196186;1038.5689938675516;19395.3211637592;20555.97220139017;21225.893870530857;21227.189100492207;21416.143354265965;962.4225464721251;964.7940074906367;1036.9222308326905;1057.7091299892438;1062.1184416933927;8346.374021948379;10575.682729518127;10743.462826858626;11017.724556886142;11037.39294338803;420132;546089;546293;550137;559157;122623;125403.3729831345;126524;128581;131207.34396327956;1120.0093986802706;1175.0716933622514;1187.3944288412972;1244.166408506303;1263.1915574032625;15873.761893571374;18016.279348826425;21150.365489035354;21284.78715212828;22795.430114247276;59579;59757.701211493666;59777.804443910005;59837.70081149566;60588.99999999824;81698;81762.59118704368;82256.17743822724;82696;83318.58340708258;8735.606897689491;8852.336074794288;8915.955420223117;8936.704177894298;8963.551822408723;11349.943250284026;12490.813372729575;12674;13058.542950996669;13357.265350405822;10876.619318323821;11011;11475.540978360772;11632.825507617223;11839.171258011856;9837;10581.788364232827;10764.40795756241;11139.944300278446;11146;11398.08815294791;11454.541818327174;12036.75926481483;12066.879331206575;12072.094592905743;8636.309095272489;8729.694460693845;8796.80079980005;8831;8840.646374144962;11518.884811152117;11611.941940290244;11681.591144309908;11868.10989175798;11876.643700688994 09:46:01 INFO - PID 1644 | |3;mootools.html;1178.505027888287;1403.4035534897673;1409.3516982188194;1422.8506006869272;1423.4946593959141;9037.367384283103;11586.478608462628;12033.51865925363;12104.939475302634;12296.446659900304;11919.344436056013;11981.940090299537;12096.213746106498;12133.393330333492;12134.15060945732;2520.9873950630267;2561.308446719385;2561.743825617433;2563.3335332813417;2565.7690807827294;2487.9502409951792;2508.7240403555593;2516.798656107507;2526.090607381341;2527.2418274517663;2452.435939733862;2492.0530198524534;2499.662545556345;2499.8750062496892;2501.224620367692;2448.1431498975307;2469.4567195217114;2482.2925466242123;2484.167803785734;2485.1053620696716;2458.1027924807495;2459.569575324329;2470.8641024743633;2472.2336075816374;2566.268613445159;11567.652970410902;11630.883691163064;11631.243969141979;11648;11661.825072623917;5694.288213973254;6437.903431448532;6638.601683898982;6664.86670266592;6682.264950855402;3860.768353898747;4038.374052021941;4106.856260030884;4107.527634322031;4145.523264824554;1229.1580267516779;1232.12519111431;1251.386820457978;1253.1729058821224;1274.910756247063;14818.555443336609;15203.695926081527;16316.428924987687;16408.83591164085;16422.68618510516;5454.0182767101505;5611.579131565149;5625.549956003591;5628.887422251614;5648.237487939158;9959.651412200537;11523.732389437322;11681.88318116825;11728;11730.824037639442;6369.299377068519;6388.520860935449;6404.71178796946;6410.134631824737;6465.482761379172;5957.046872500379;6119.479844213202;6136.895358835363;6186.969065154645;6197.81406557804;4209.305464598307;4398.274284743045;4445.377647129404;4477.664175186874;4485.730856148576;1257.3021972805182;1275.253976423784;1277.3804704718204;1288.716482373895;1288.7177258627726;757.7953952432812;770.2643975003858;793.9166387529342;795.8527672380628;803.0363563723555;6238;6581.840716456128;6675.966620166868;6930.3762661360715;6990.405815505637;911.3483859040817;955.4697143085655;959.429139661914;960.6493629825063;973.2943615878573;4121.91756164881;4174.895627609334;4325.52419233884;4380.627646650007;4416.403785488982;14482.710345793024;14534.854651453561;14615.561533154023;14671.706565868837;14714.19071951053;12625.989920806314;13701.177929324273;14561.271936403138;14829.703405931821;14841.628959275888;8147.633356498972;8280.089190189074;8284.171582841787;8306.127856575093;8324.29243514296;6250.718717657807;6585.80242592723;6925.411340036153;6938.791836244921;7103.467239956921;13496;13825.165841002772;13853.930730346283;13930.955178361866;13960;12240.571579994657;13930.303484825921;14201.928990354982;14322.57032289033;14420.423183072979;9169.083091690778;9439.056094390506;9446.574904129468;9455.14903658674;9458.101480359355;10621.309614875008;10691.572337106429;10744.19418543628;10745.247832651952;10758.977897099763;8934.195922366758;9191.402558833654;9257.537123143951;9271.953640231755;9392.342536022414;8918.37571369998;8935.597898094731;9010.96373917008;9027.142421470213;9157.038510956254;8046.994125734283;8122.878156827771;8188.549629770419;8212.83574328522;8235.38234632393;5458.727063646722;5491.697956612425;5525.4474552544425;5681.545476361964;6067.302260240129;6038.184845046038;6165.290991535851;6200.542872424886;6317.557770955989;6334.683265836782 09:46:01 INFO - PID 1644 | |4;prototype.html;396.52615124925717;400.6634427081251;401.98191081401336;402.2678724721009;404;6486.545941784077;6508.153939987807;6521.543491955565;6528.804135875919;6539.313372095933;6066.484348830344;6131;6303.432691057804;6392.264889537704;6393.616383017011;3891.9416208756893;4750.76246187691;4785.186518291899;4788.425388953313;4809.27860820878;3982.800859957005;4434.490033646123;4807.42310922688;4808.495108013661;4812.614990800745;3999.8000099994883;4532;4584.679072464929;4588.931166032512;4615.630749540045;4550.5904468597655;4551.453825540923;4562.156001139783;4564.931526027112;4603.930941035887;3355.1947532592244;3588.372034893883;3848.7113466489845;3922.8626998054933;3928.7839168845703;5978.611390259619;6096.603720758137;6218.689065546704;6239.750409983595;6240.719167637421;4792.784324705397;5178.177893048298;5488.639884849513;5491;5496.532794712454;6463.418292353711;6730.427913627299;6805.727770889159;6844.384005439484;6846.965765171142;4110.568390319033;4472.485664148632;4476.6418686505;4477.582105662938;4485.932711009337;6476.546641735127;6843.247242803288;7223.205447400782;7244.203137654854;7285.380742636882;3967.4643923070016;3982.980085099556;4392.780360981938;4524.140413321458;4526.79629416677;4589.885252868705;4614.192516309632;4614.930776038362;4620.399348084795;4624.583787459145;4467.664925130628;5405.405405405402;5466.617336786467;5503.862403439946;5509.724513774296;6308.810735677937;6625.204975402982;6830.316968303229;6861.279565645636;6880.449564034965;1456.4829485532484;1503.2934520775218;1551.1313664348002;1570.1835045776133;1570.84291570842;876.8202518483696;974.5030034682363;1015.5836107196162;1026.035526604989;1027.8406846938738;6036.49087728068;6299.5196128909665;6361.840953976188;6368.713407896656;6418.775342862978;6293.181886354744;7078.716851325992;7079.265579932847;7094.53885497441;7096.219415864251;5582.832515024475;6155.322914479404;6182.196314479179;6353.936460635427;6378.202724659418;3034.4689679306034;3383.7141886083623;3652.7990960496895;3710.54918925402;3713.424419215026;5941.316748573883;5994.550408719363;6676.065350850885;7075.893861592081;7192.964035179791;6580.256232098498;6727.260001399842;7063.293670632998;7322.304381083789;7325.7802265932105;6448.774292899726;6576.210854697467;6943.47923905699;7066.116735408074;7228;6532.346765323429;6808.795736127924;6927.965360173167;7137.2149063604;7137.430525010888;5812.738426770721;6024.1265016573225;6283.748650053946;6939.201991771011;6989.344049261248;6600.438962688226;6991.706534291173;7094.042304288855;7295.8540829184185;7307.9634601828775;4468.687191896666;4493.674366061954;4496.685232033727;4496.853302407865;4526.88682782933;6696.928491441346;7011.719531218694;7105;7110.217876033529;7145.17830449505;6638.701258443285;6831.795046148623;6845.079006659304;6847.349501797321;6884.208316043519;5684.630499017716;6717.764878229434;6837.863242735217;6874.2094659112845;6902.827429314308;6579.703913324008;6686.531942764154;6712.127423434922;6721.353268449261;6736.797896063126;6341.302456729848;6783.491238157059;6855.725770969106;6855.862882742417;6877.312268773083;5508.889822203453;5924.140999555127;6481.310256243794;6546.443552298108;6618.7352505899225 09:46:01 INFO - PID 1644 | |5;yui.html;731.8938753880685;815.9755207343775;819.0458116294517;850.0352100366086;860.8579584368578;17068.82931170688;17322.393716219936;17453.389131380405;19539.511512212168;20130.798692013075;16292;17834.108294585283;18293.7255941161;18405.263789448407;18674.719879201824;915.5696822493418;937.3625934364663;938.6339327662217;941.9387739796891;944.7118628818208;983.3804703037076;999.3254553176613;1001.0190013786494;1001.5292812378207;1002.9598816047386;1031.334789061055;1050.396022287184;1052.5053224984283;1052.5684469367561;1054.0039662518923;1032.2206733915882;1046.5709059285657;1047.7537778622018;1048.1038711901328;1049.0453687144661;762.9160792312841;763.7059732003163;772.2702046565997;774.446270916292;776.8718161503346;16928.576785580335;17284.30862765488;17705.29178832845;17752.644947100987;18211;17279.136043197916;17861.553461163447;18034.819651803577;18180.45458636243;18266.452006439697;14682.412703491847;14777;14858.628534286729;14971.92514037423;14976;3488.8081155536433;3612.837422316002;3625.111847597329;3637.163452405935;3645.270945810822;149908;150821;150827;150956;153232.70150947856;13540.255285959367;13551.25468099264;13587.456501740018;13720.588382348546;13770.862291377158;25229;25744.48511029769;26059.739402606112;26189.083382081535;26453;7766.26220509062;7940.682372705027;7947.483413578099;7954.2841144296135;7971.561564113914;3796.2976849282973;3840.8847734568008;3889.533256009297;3922.195949830278;3944.980275098606;4373.2565463871215;4451.132029254264;4465.553444655508;4468.642508599304;4480.641548676098;905.7192270396198;943.7829299261208;947.0860619502075;951.0442005784281;954.2556805691572;633.8003528888386;634.0995785983914;660.5838321857199;688.5971706551624;688.6625553478759;11128.276662017104;12193.512259509698;12302.446389912475;12484.250944943333;12670.556530521388;652.725855140842;655.4353538458548;655.639398330916;685.8032732881154;688.4182865478663;4915.90168196634;5168.638195326291;5527.115661494142;5708.514776243983;5745.023346031185;142706;143001.5699843009;145385;148857;149347.50652493336;98053;116228.99999999831;127023;128562;128743.06885396727;74234.6288268566;103572.48213758883;103598;103742;107868.92131078745;11032.676078870587;11239.831402528805;11874.703132421413;12088.637340879788;12184.878151218374;35270.11824704403;43319.56680433281;43683.56316436922;44278.55721442873;45101.77449112733;56562;57040;82668;86836;87282;17636.294548217928;17951.01269430194;17999.910000449912;18007.81992180114;18041.819581804015;18162.818371816113;18522.907385462986;18984.050797459797;19176.520586985436;19178.328758493386;14967.401303948156;16828.242729076977;16909.74635380495;17936.923784572966;18116.456506304832;17253.82746172522;18075.909620451814;18214.908925455817;18348.899066056078;18353.082345882918;17650.470485885446;17874.46376608704;17913.731294030862;17993.2802687891;18371.816281837546;11864;11999.460024298753;12195.451204695988;12286.385680716106;12681.365931703194;11273.492692829006;13029.697030296895;13870.167789932637;14068.226247556482;14131.010829241854 09:46:01 INFO - PID 1644 | __end_tp_report 09:46:01 INFO - PID 1644 | __start_cc_report 09:46:01 INFO - PID 1644 | _x_x_mozilla_cycle_collect,456 09:46:01 INFO - PID 1644 | __end_cc_report 09:46:01 INFO - PID 1644 | __startTimestamp1495989961445__endTimestamp 09:46:01 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | ------- Summary: start ------- 09:46:01 INFO - PID 1644 | Number of tests: 6 09:46:01 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | [#0] dojo.html Cycles:165 Average:6161.06 Median:6409.58 stddev:770.53 (12.0%) stddev-sans-first:746.36 09:46:01 INFO - PID 1644 | Values: 3605.6 3607.3 3630.1 3637.3 3651.5 6137.2 6152.1 6186.2 6203.9 6245.9 6176.7 6180.9 6217.4 6264.2 6288.7 5052.9 5484.0 5498.9 5526.9 5559.2 5554.3 5587.5 5620.6 5631.5 5652.4 4036.7 5195.0 5291.8 5420.0 5513.1 5511.3 5525.6 5534.3 5549.3 5565.5 5418.6 5449.2 5455.8 5470.7 5537.4 5197.6 6125.2 6171.2 6204.4 6217.4 6697.1 6733.6 6752.2 6760.0 6764.5 6848.6 6857.8 6858.2 6862.2 6878.1 5120.3 5835.8 6200.6 6249.8 6316.1 6946.3 6976.1 6978.7 6998.6 7008.1 5336.9 6433.0 6442.2 6548.2 6627.4 6696.9 6797.8 6805.6 6814.7 6815.9 6263.7 6404.6 6435.2 6710.8 6741.0 6409.6 6588.9 6635.7 6649.6 6698.1 5401.4 5412.6 5413.3 5413.6 5418.5 3896.6 4774.0 4777.9 4798.1 4875.8 5117.3 6177.9 6179.9 6214.9 6244.7 6825.6 6826.8 6830.6 6836.6 6848.2 6242.8 6251.1 6254.5 6264.1 6285.8 5877.8 5921.3 6001.3 6003.8 6126.1 6931.2 6982.3 7004.0 7026.5 7036.5 5644.0 6833.6 6836.8 6859.6 6873.8 6686.3 6793.9 6957.5 6979.3 6984.7 6586.2 6702.7 6721.6 6731.5 6732.6 6838.7 6844.0 6844.5 6846.7 6885.8 6436.5 6485.2 6530.1 6558.3 6596.9 6606.2 6610.8 6614.0 6624.4 6645.8 5435.4 6446.8 6481.4 6486.1 6496.1 6375.8 6458.2 6495.8 6582.0 6613.6 6366.1 6415.7 6429.6 6430.9 6435.2 09:46:01 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | [#1] ext.html Cycles:180 Average:42556.59 Median:3105.51 stddev:72964.07 (2349.5%) stddev-sans-first:73101.31 09:46:01 INFO - PID 1644 | Values: 773.9 809.0 831.2 1175.9 1248.1 35567.8 38771.6 38781.0 38785.0 38801.2 31319.0 38411.4 38915.8 38928.8 38951.6 7765.3 7933.6 8101.3 8286.0 8307.0 7230.6 8249.5 8393.0 8398.0 8408.6 6951.3 7261.2 8339.6 8350.8 8413.8 7244.1 8302.8 8380.3 8382.5 8395.1 8171.8 8175.8 8204.3 8206.5 8247.1 36407.5 36435.1 36619.6 36724.1 36897.0 3062.9 3574.8 3591.9 3592.2 3595.9 29093.0 29344.9 29459.6 29612.9 29706.3 3064.7 3070.1 3101.7 3109.3 3110.5 248165.0 252232.0 276176.0 302064.0 302574.0 47395.8 47422.8 47615.0 47674.8 47701.3 49820.8 50011.7 50073.5 50111.5 50189.5 14020.9 14111.9 14122.5 14148.5 14159.5 11524.8 11526.3 11529.8 11532.5 11614.9 13568.5 13578.6 13589.5 13621.5 13633.7 6124.6 6145.6 6187.8 6189.7 6221.6 921.5 938.2 988.9 1014.2 1057.7 22076.9 24245.3 24563.4 24671.4 24860.5 1106.6 1118.0 1130.9 1141.9 1146.6 12455.8 12521.9 12610.2 12622.1 12710.9 302192.0 303524.0 304623.5 305229.0 306438.0 53565.9 53887.2 54207.0 54214.7 54302.7 1498.6 1511.2 1541.4 1551.5 1551.5 18007.7 23050.0 24036.2 24164.6 24264.5 37504.0 37534.8 37627.2 37671.4 37676.2 215576.0 259382.4 259690.0 259750.0 259910.0 27594.4 28109.9 30289.1 34305.8 34442.0 38691.6 39564.6 40309.4 40350.0 40555.6 27577.3 28312.0 28558.4 28956.9 29044.3 28428.4 28795.7 29024.9 29164.7 29207.6 34297.8 34328.8 34359.7 34371.0 34385.8 27087.1 27346.0 27736.0 29135.6 29429.9 20877.8 22970.2 23049.3 23102.0 23114.9 09:46:01 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | [#2] jquery.html Cycles:180 Average:34806.74 Median:2199.51 stddev:89714.68 (4078.9%) stddev-sans-first:89929.13 09:46:01 INFO - PID 1644 | Values: 388.6 415.5 424.3 435.5 449.7 20114.9 21674.6 21727.9 21771.6 21800.3 22520.3 23129.0 23366.2 23435.8 23758.0 5892.8 6152.5 6158.3 6180.5 6202.5 5841.0 5968.8 6019.0 6121.4 6129.6 5999.9 6007.7 6012.7 6016.9 6027.1 5104.3 5136.9 5167.0 5791.2 6008.5 5380.7 5383.9 5387.6 5399.8 5400.9 26336.9 27208.5 27227.0 27280.7 27536.0 1945.8 2092.3 2176.2 2179.1 2186.7 13625.7 14271.5 16530.2 18116.8 18289.5 1960.8 2085.3 2196.7 2199.1 2199.9 150884.0 151438.0 151653.2 151747.0 152356.0 12491.9 12717.7 12871.2 12979.5 13033.7 16309.8 16428.3 16437.6 16443.9 16450.1 7498.4 8794.0 8940.2 9008.0 9101.8 8462.7 8466.1 8467.3 8573.7 8610.9 7012.6 7880.0 8034.4 8048.9 8121.0 2130.3 2148.4 2156.2 2156.9 2171.1 976.0 993.2 995.1 1023.1 1038.6 19395.3 20556.0 21225.9 21227.2 21416.1 962.4 964.8 1036.9 1057.7 1062.1 8346.4 10575.7 10743.5 11017.7 11037.4 420132.0 546089.0 546293.0 550137.0 559157.0 122623.0 125403.4 126524.0 128581.0 131207.3 1120.0 1175.1 1187.4 1244.2 1263.2 15873.8 18016.3 21150.4 21284.8 22795.4 59579.0 59757.7 59777.8 59837.7 60589.0 81698.0 81762.6 82256.2 82696.0 83318.6 8735.6 8852.3 8916.0 8936.7 8963.6 11349.9 12490.8 12674.0 13058.5 13357.3 10876.6 11011.0 11475.5 11632.8 11839.2 9837.0 10581.8 10764.4 11139.9 11146.0 11398.1 11454.5 12036.8 12066.9 12072.1 8636.3 8729.7 8796.8 8831.0 8840.6 11518.9 11611.9 11681.6 11868.1 11876.6 09:46:01 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | [#3] mootools.html Cycles:180 Average:7058.00 Median:4107.19 stddev:4430.88 (107.9%) stddev-sans-first:4421.28 09:46:01 INFO - PID 1644 | Values: 1178.5 1403.4 1409.4 1422.9 1423.5 9037.4 11586.5 12033.5 12104.9 12296.4 11919.3 11981.9 12096.2 12133.4 12134.2 2521.0 2561.3 2561.7 2563.3 2565.8 2488.0 2508.7 2516.8 2526.1 2527.2 2452.4 2492.1 2499.7 2499.9 2501.2 2448.1 2469.5 2482.3 2484.2 2485.1 2458.1 2459.6 2470.9 2472.2 2566.3 11567.7 11630.9 11631.2 11648.0 11661.8 5694.3 6437.9 6638.6 6664.9 6682.3 3860.8 4038.4 4106.9 4107.5 4145.5 1229.2 1232.1 1251.4 1253.2 1274.9 14818.6 15203.7 16316.4 16408.8 16422.7 5454.0 5611.6 5625.5 5628.9 5648.2 9959.7 11523.7 11681.9 11728.0 11730.8 6369.3 6388.5 6404.7 6410.1 6465.5 5957.0 6119.5 6136.9 6187.0 6197.8 4209.3 4398.3 4445.4 4477.7 4485.7 1257.3 1275.3 1277.4 1288.7 1288.7 757.8 770.3 793.9 795.9 803.0 6238.0 6581.8 6676.0 6930.4 6990.4 911.3 955.5 959.4 960.6 973.3 4121.9 4174.9 4325.5 4380.6 4416.4 14482.7 14534.9 14615.6 14671.7 14714.2 12626.0 13701.2 14561.3 14829.7 14841.6 8147.6 8280.1 8284.2 8306.1 8324.3 6250.7 6585.8 6925.4 6938.8 7103.5 13496.0 13825.2 13853.9 13931.0 13960.0 12240.6 13930.3 14201.9 14322.6 14420.4 9169.1 9439.1 9446.6 9455.1 9458.1 10621.3 10691.6 10744.2 10745.2 10759.0 8934.2 9191.4 9257.5 9272.0 9392.3 8918.4 8935.6 9011.0 9027.1 9157.0 8047.0 8122.9 8188.5 8212.8 8235.4 5458.7 5491.7 5525.4 5681.5 6067.3 6038.2 6165.3 6200.5 6317.6 6334.7 09:46:01 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | [#4] prototype.html Cycles:180 Average:5453.39 Median:6262.23 stddev:1747.95 (27.9%) stddev-sans-first:1711.15 09:46:01 INFO - PID 1644 | Values: 396.5 400.7 402.0 402.3 404.0 6486.5 6508.2 6521.5 6528.8 6539.3 6066.5 6131.0 6303.4 6392.3 6393.6 3891.9 4750.8 4785.2 4788.4 4809.3 3982.8 4434.5 4807.4 4808.5 4812.6 3999.8 4532.0 4584.7 4588.9 4615.6 4550.6 4551.5 4562.2 4564.9 4603.9 3355.2 3588.4 3848.7 3922.9 3928.8 5978.6 6096.6 6218.7 6239.8 6240.7 4792.8 5178.2 5488.6 5491.0 5496.5 6463.4 6730.4 6805.7 6844.4 6847.0 4110.6 4472.5 4476.6 4477.6 4485.9 6476.5 6843.2 7223.2 7244.2 7285.4 3967.5 3983.0 4392.8 4524.1 4526.8 4589.9 4614.2 4614.9 4620.4 4624.6 4467.7 5405.4 5466.6 5503.9 5509.7 6308.8 6625.2 6830.3 6861.3 6880.4 1456.5 1503.3 1551.1 1570.2 1570.8 876.8 974.5 1015.6 1026.0 1027.8 6036.5 6299.5 6361.8 6368.7 6418.8 6293.2 7078.7 7079.3 7094.5 7096.2 5582.8 6155.3 6182.2 6353.9 6378.2 3034.5 3383.7 3652.8 3710.5 3713.4 5941.3 5994.6 6676.1 7075.9 7193.0 6580.3 6727.3 7063.3 7322.3 7325.8 6448.8 6576.2 6943.5 7066.1 7228.0 6532.3 6808.8 6928.0 7137.2 7137.4 5812.7 6024.1 6283.7 6939.2 6989.3 6600.4 6991.7 7094.0 7295.9 7308.0 4468.7 4493.7 4496.7 4496.9 4526.9 6696.9 7011.7 7105.0 7110.2 7145.2 6638.7 6831.8 6845.1 6847.3 6884.2 5684.6 6717.8 6837.9 6874.2 6902.8 6579.7 6686.5 6712.1 6721.4 6736.8 6341.3 6783.5 6855.7 6855.9 6877.3 5508.9 5924.1 6481.3 6546.4 6618.7 09:46:01 INFO - PID 1644 | 09:46:01 INFO - PID 1644 | [#5] yui.html Cycles:180 Average:25833.90 Median:18195.73 stddev:39790.67 (218.7%) stddev-sans-first:39857.66 09:46:01 INFO - PID 1644 | Values: 731.9 816.0 819.0 850.0 860.9 17068.8 17322.4 17453.4 19539.5 20130.8 16292.0 17834.1 18293.7 18405.3 18674.7 915.6 937.4 938.6 941.9 944.7 983.4 999.3 1001.0 1001.5 1003.0 1031.3 1050.4 1052.5 1052.6 1054.0 1032.2 1046.6 1047.8 1048.1 1049.0 762.9 763.7 772.3 774.4 776.9 16928.6 17284.3 17705.3 17752.6 18211.0 17279.1 17861.6 18034.8 18180.5 18266.5 14682.4 14777.0 14858.6 14971.9 14976.0 3488.8 3612.8 3625.1 3637.2 3645.3 149908.0 150821.0 150827.0 150956.0 153232.7 13540.3 13551.3 13587.5 13720.6 13770.9 25229.0 25744.5 26059.7 26189.1 26453.0 7766.3 7940.7 7947.5 7954.3 7971.6 3796.3 3840.9 3889.5 3922.2 3945.0 4373.3 4451.1 4465.6 4468.6 4480.6 905.7 943.8 947.1 951.0 954.3 633.8 634.1 660.6 688.6 688.7 11128.3 12193.5 12302.4 12484.3 12670.6 652.7 655.4 655.6 685.8 688.4 4915.9 5168.6 5527.1 5708.5 5745.0 142706.0 143001.6 145385.0 148857.0 149347.5 98053.0 116229.0 127023.0 128562.0 128743.1 74234.6 103572.5 103598.0 103742.0 107868.9 11032.7 11239.8 11874.7 12088.6 12184.9 35270.1 43319.6 43683.6 44278.6 45101.8 56562.0 57040.0 82668.0 86836.0 87282.0 17636.3 17951.0 17999.9 18007.8 18041.8 18162.8 18522.9 18984.1 19176.5 19178.3 14967.4 16828.2 16909.7 17936.9 18116.5 17253.8 18075.9 18214.9 18348.9 18353.1 17650.5 17874.5 17913.7 17993.3 18371.8 11864.0 11999.5 12195.5 12286.4 12681.4 11273.5 13029.7 13870.2 14068.2 14131.0 09:46:01 INFO - PID 1644 | -------- Summary: end -------- 09:46:01 INFO - PID 1644 | 09:46:02 INFO - TEST-INFO | 1644: exit 0 09:46:02 INFO - TEST-OK | dromaeo_css | took 1143495ms 09:46:02 INFO - TEST-START | kraken 09:46:02 INFO - operating with platform_type : mac_ 09:46:02 INFO - Initialising browser for kraken test... 09:46:02 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpqqFq6h/profile 09:46:02 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 09:46:35 INFO - TEST-INFO | 1656: killed by SIGTERM 09:46:35 INFO - Browser initialized. 09:46:35 INFO - Running cycle 1/1 for kraken test... 09:46:35 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/T/tmpqqFq6h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:38 INFO - PID 1660 | 2017-05-28 09:46:38.319 plugin-container[1661:10629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 09:46:38 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:38 INFO - PID 1660 | 2017-05-28 09:46:38.320 plugin-container[1661:10629] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a07, name = 'com.apple.CFPasteboardClient' 09:46:38 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:38 INFO - PID 1660 | 2017-05-28 09:46:38.320 plugin-container[1661:10629] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:38 INFO - PID 1660 | 2017-05-28 09:46:38.879 plugin-container[1662:10670] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 09:46:38 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:38 INFO - PID 1660 | 2017-05-28 09:46:38.879 plugin-container[1662:10670] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4723, name = 'com.apple.CFPasteboardClient' 09:46:38 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:46:38 INFO - PID 1660 | 2017-05-28 09:46:38.879 plugin-container[1662:10670] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:46:48 INFO - PID 1660 | RSS: Main: 263114752 09:46:48 INFO - PID 1660 | 09:46:50 INFO - PID 1660 | 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) 09:46:51 INFO - PID 1660 | RSS: Main: 272900096 09:46:51 INFO - PID 1660 | 09:46:54 INFO - PID 1660 | 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) 09:46:55 INFO - PID 1660 | RSS: Main: 280588288 09:46:55 INFO - PID 1660 | 09:46:58 INFO - PID 1660 | 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) 09:46:59 INFO - PID 1660 | RSS: Main: 282886144 09:46:59 INFO - PID 1660 | 09:47:01 INFO - PID 1660 | 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) 09:47:01 INFO - PID 1660 | RSS: Main: 284180480 09:47:01 INFO - PID 1660 | 09:47:03 INFO - PID 1660 | 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) 09:47:04 INFO - PID 1660 | RSS: Main: 284168192 09:47:04 INFO - PID 1660 | 09:47:07 INFO - PID 1660 | 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) 09:47:08 INFO - PID 1660 | RSS: Main: 294354944 09:47:08 INFO - PID 1660 | 09:47:11 INFO - PID 1660 | 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) 09:47:12 INFO - PID 1660 | RSS: Main: 293134336 09:47:12 INFO - PID 1660 | 09:47:15 INFO - PID 1660 | 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) 09:47:15 INFO - PID 1660 | RSS: Main: 291553280 09:47:15 INFO - PID 1660 | 09:47:17 INFO - PID 1660 | 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) 09:47:18 INFO - PID 1660 | RSS: Main: 291405824 09:47:18 INFO - PID 1660 | 09:47:19 INFO - PID 1660 | 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) 09:47:20 INFO - PID 1660 | RSS: Main: 291135488 09:47:20 INFO - PID 1660 | 09:47:22 INFO - PID 1660 | 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) 09:47:23 INFO - PID 1660 | RSS: Main: 290705408 09:47:23 INFO - PID 1660 | 09:47:26 INFO - PID 1660 | 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) 09:47:27 INFO - PID 1660 | RSS: Main: 291266560 09:47:27 INFO - PID 1660 | 09:47:30 INFO - PID 1660 | 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) 09:47:30 INFO - PID 1660 | RSS: Main: 291143680 09:47:30 INFO - PID 1660 | 09:47:32 INFO - PID 1660 | 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) 09:47:33 INFO - PID 1660 | RSS: Main: 292958208 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | __start_tp_report 09:47:33 INFO - PID 1660 | _x_x_mozilla_page_load 09:47:33 INFO - PID 1660 | _x_x_mozilla_page_load_details 09:47:33 INFO - PID 1660 | |i|pagename|runs| 09:47:33 INFO - PID 1660 | |0;ai-astar;101;114;115;115;115;115;115;115;117;116 09:47:33 INFO - PID 1660 | |1;audio-beat-detection;150;126;142;166;108;117;99;140;164;141 09:47:33 INFO - PID 1660 | |2;audio-dft;200;206;207;220;236;253;197;201;194;196 09:47:33 INFO - PID 1660 | |3;audio-fft;92;50;62;93;82;64;89;90;77;57 09:47:33 INFO - PID 1660 | |4;audio-oscillator;88;67;83;72;70;69;92;70;70;93 09:47:33 INFO - PID 1660 | |5;imaging-gaussian-blur;90;92;91;113;91;127;135;92;136;135 09:47:33 INFO - PID 1660 | |6;imaging-darkroom;114;150;99;99;98;98;100;106;119;101 09:47:33 INFO - PID 1660 | |7;imaging-desaturate;94;110;91;92;97;139;85;143;144;91 09:47:33 INFO - PID 1660 | |8;json-parse-financial;52;52;52;54;52;52;53;53;52;50 09:47:33 INFO - PID 1660 | |9;json-stringify-tinderbox;57;63;63;65;65;68;68;46;60;49 09:47:33 INFO - PID 1660 | |10;stanford-crypto-aes;84;88;111;87;87;87;88;98;146;114 09:47:33 INFO - PID 1660 | |11;stanford-crypto-ccm;140;137;138;135;139;136;150;177;167;157 09:47:33 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;212;244;229;230;190;186;187;224;188;186 09:47:33 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;71;72;69;83;69;63;70;99;90;81 09:47:33 INFO - PID 1660 | __end_tp_report 09:47:33 INFO - PID 1660 | __start_cc_report 09:47:33 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1195 09:47:33 INFO - PID 1660 | __end_cc_report 09:47:33 INFO - PID 1660 | __startTimestamp1495990053037__endTimestamp 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | ------- Summary: start ------- 09:47:33 INFO - PID 1660 | Number of tests: 14 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:4.57 (4.0%) stddev-sans-first:0.83 09:47:33 INFO - PID 1660 | Values: 101.0 114.0 115.0 115.0 115.0 115.0 115.0 115.0 117.0 116.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:135.30 Median:146.00 stddev:22.52 (15.4%) stddev-sans-first:23.25 09:47:33 INFO - PID 1660 | Values: 150.0 126.0 142.0 166.0 108.0 117.0 99.0 140.0 164.0 141.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:211.00 Median:206.50 stddev:19.56 (9.5%) stddev-sans-first:20.33 09:47:33 INFO - PID 1660 | Values: 200.0 206.0 207.0 220.0 236.0 253.0 197.0 201.0 194.0 196.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:75.60 Median:85.50 stddev:16.06 (18.8%) stddev-sans-first:15.90 09:47:33 INFO - PID 1660 | Values: 92.0 50.0 62.0 93.0 82.0 64.0 89.0 90.0 77.0 57.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.50 stddev:10.39 (13.4%) stddev-sans-first:10.29 09:47:33 INFO - PID 1660 | Values: 88.0 67.0 83.0 72.0 70.0 69.0 92.0 70.0 70.0 93.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:110.20 Median:90.50 stddev:21.06 (23.3%) stddev-sans-first:21.04 09:47:33 INFO - PID 1660 | Values: 90.0 92.0 91.0 113.0 91.0 127.0 135.0 92.0 136.0 135.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:108.40 Median:124.00 stddev:16.32 (13.2%) stddev-sans-first:17.19 09:47:33 INFO - PID 1660 | Values: 114.0 150.0 99.0 99.0 98.0 98.0 100.0 106.0 119.0 101.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:91.00 stddev:23.95 (26.3%) stddev-sans-first:24.81 09:47:33 INFO - PID 1660 | Values: 94.0 110.0 91.0 92.0 97.0 139.0 85.0 143.0 144.0 91.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:52.20 Median:52.00 stddev:1.03 (2.0%) stddev-sans-first:1.09 09:47:33 INFO - PID 1660 | Values: 52.0 52.0 52.0 54.0 52.0 52.0 53.0 53.0 52.0 50.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:64.00 stddev:7.60 (11.9%) stddev-sans-first:7.97 09:47:33 INFO - PID 1660 | Values: 57.0 63.0 63.0 65.0 65.0 68.0 68.0 46.0 60.0 49.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:99.00 Median:87.00 stddev:19.66 (22.6%) stddev-sans-first:20.09 09:47:33 INFO - PID 1660 | Values: 84.0 88.0 111.0 87.0 87.0 87.0 88.0 98.0 146.0 114.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:145.00 stddev:14.77 (10.2%) stddev-sans-first:15.41 09:47:33 INFO - PID 1660 | Values: 140.0 137.0 138.0 135.0 139.0 136.0 150.0 177.0 167.0 157.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:207.60 Median:218.00 stddev:22.67 (10.4%) stddev-sans-first:23.99 09:47:33 INFO - PID 1660 | Values: 212.0 244.0 229.0 230.0 190.0 186.0 187.0 224.0 188.0 186.0 09:47:33 INFO - PID 1660 | 09:47:33 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.70 Median:76.50 stddev:11.25 (14.7%) stddev-sans-first:11.74 09:47:33 INFO - PID 1660 | Values: 71.0 72.0 69.0 83.0 69.0 63.0 70.0 99.0 90.0 81.0 09:47:33 INFO - PID 1660 | -------- Summary: end -------- 09:47:33 INFO - PID 1660 | 09:47:33 INFO - TEST-INFO | 1660: exit 0 09:47:33 INFO - TEST-OK | kraken | took 91780ms 09:47:33 INFO - SUITE-END | took 1235s 09:47:34 INFO - Completed test suite (00:20:35) 09:47:34 INFO - javascript benchmark 09:47:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7934.0083337182605, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3605.621409751976, 3607.3146102240576, 3630.146915474861, 3637.2907283079794, 3651.470536772167, 6137.223770008885, 6152.107944348072, 6186.226721659793, 6203.937960620392, 6245.9063114053215, 6176.660283684396, 6180.907286390697, 6217.37826217378, 6264.185655864742, 6288.717007734652, 5052.9242061369105, 5484.040292948738, 5498.862528436781, 5526.861828454301, 5559.249501317331, 5554.305711786027, 5587.469190426903, 5620.57845661575, 5631.549476041927, 5652.378238393773, 4036.6568841648495, 5194.986977539382, 5291.761870715827, 5420.024395608789, 5513.09034009386, 5511.338639363262, 5525.557955363602, 5534.335879694422, 5549.278593782823, 5565.499105080562, 5418.566514678816, 5449.237106805011, 5455.8363249102595, 5470.671759694391, 5537.390887002426, 5197.58419326453, 6125.17440701618, 6171.166892469549, 6204.379562043805, 6217.409346112112, 6697.095892054559, 6733.562318449285, 6752.189737231563, 6760.0, 6764.4926630502905, 6848.554843935129, 6857.759978400732, 6858.177018757781, 6862.176538815324, 6878.105846240007, 5120.334356533627, 5835.824925252249, 6200.596961197508, 6249.78125765596, 6316.147320111818, 6946.305369463013, 6976.093107895941, 6978.685959131852, 6998.6150761708595, 7008.123984501937, 5336.893262134736, 6433.0, 6442.226932768098, 6548.1814773153355, 6627.4366678831875, 6696.933030669728, 6797.830054248683, 6805.557638753564, 6814.727410903607, 6815.897761533581, 6263.686815659199, 6404.615723056631, 6435.195600549931, 6710.83222919431, 6740.9662951685905, 6409.583377080475, 6588.901166482507, 6635.734570617218, 6649.567778094409, 6698.095757072733, 5401.378841433207, 5412.621116521885, 5413.296271484761, 5413.566914646819, 5418.512333889968, 3896.5518965318784, 4773.95226047742, 4777.880552986203, 4798.088363210979, 4875.780589873464, 5117.283580298761, 6177.907331390033, 6179.938200617937, 6214.875702486015, 6244.718987645567, 6825.55633883796, 6826.761063362758, 6830.590164590041, 6836.555623884531, 6848.246692863781, 6242.75028998844, 6251.093591429218, 6254.499640028877, 6264.060390941304, 6285.811425657238, 5877.794277200363, 5921.259842519685, 6001.309849367379, 6003.759849606055, 6126.08108783686, 6931.2375638679705, 6982.266861979419, 7003.964980175067, 7026.543274687129, 7036.542624729376, 5644.0, 6833.555818871758, 6836.760713375008, 6859.5541289818, 6873.828154296182, 6686.3313668632745, 6793.864122717419, 6957.478189135936, 6979.336962988508, 6984.685689144102, 6586.209654841449, 6702.726481968411, 6721.596704197764, 6731.46148308144, 6732.596044237362, 6838.692258848463, 6844.041834143124, 6844.486663500158, 6846.726130954905, 6885.827854303682, 6436.517261205522, 6485.156929599122, 6530.118434011348, 6558.344165583594, 6596.944488881756, 6606.207255129415, 6610.8016759497295, 6613.966930165511, 6624.370684784938, 6645.800625981228, 5435.429279925551, 6446.774362897276, 6481.4166724994975, 6486.05952136928, 6496.058071579689, 6375.808725738235, 6458.192725909262, 6495.837604059936, 6582.0, 6613.636250006103, 6366.140571022853, 6415.679216039272, 6429.646369449725, 6430.871382572229, 6435.163428754232], "value": 6110.126927011232, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.9110002349728, 808.9757307280781, 831.1771346367094, 1175.8946590205212, 1248.082659245455, 35567.82216088919, 38771.612283877155, 38781.030474238156, 38785.0, 38801.22397552047, 31319.0, 38411.42382864259, 38915.80542097292, 38928.80535597325, 38951.61048389522, 7765.339946104574, 7933.603319834014, 8101.311388531982, 8286.0, 8307.0, 7230.566166030055, 8249.463784853995, 8393.034800997872, 8398.03422606402, 8408.621612027473, 6951.339622735832, 7261.237570055147, 8339.624716887723, 8350.83298334033, 8413.831723365529, 7244.094488188976, 8302.83394332113, 8380.329573634095, 8382.497050176948, 8395.118512556155, 8171.7548473545885, 8175.836483270361, 8204.261616454547, 8206.466579672302, 8247.051589067274, 36407.453888191696, 36435.089122772144, 36619.63380366189, 36724.08189795277, 36897.0, 3062.892798752033, 3574.803385813779, 3591.892243232707, 3592.209713862946, 3595.856165753367, 29093.0, 29344.853275733698, 29459.558106628418, 29612.851935740182, 29706.25734356637, 3064.7241748242764, 3070.1096681962204, 3101.72084512395, 3109.300407408338, 3110.5178697301953, 248165.0, 252232.0, 276176.0, 302064.0, 302574.0, 47395.76302118467, 47422.76288618535, 47615.04769904583, 47674.76162619165, 47701.284480732815, 49820.75089624601, 50011.74994125079, 50073.49926500688, 50111.489965903245, 50189.49810501848, 14020.929895350459, 14111.858881411259, 14122.50571230002, 14148.504802331869, 14159.504417345344, 11524.827127593093, 11526.250793698382, 11529.827052594217, 11532.481038353295, 11614.941925290319, 13568.525101621395, 13578.592642220749, 13589.456421743218, 13621.455141794217, 13633.659158520917, 6124.6325220486915, 6145.600535965148, 6187.783427580013, 6189.659568723673, 6221.626702397871, 921.4517362169498, 938.1978408460668, 988.9159421449258, 1014.1582486536157, 1057.7408534908843, 22076.88961555182, 24245.272641820775, 24563.38591535226, 24671.3832154194, 24860.50278994446, 1106.607154460155, 1117.9944100279608, 1130.9491072901574, 1141.937193454368, 1146.6101525481376, 12455.75088498225, 12521.874781252252, 12610.180338277982, 12622.116451848282, 12710.936445317715, 302192.0, 303524.0, 304623.4768826186, 305229.0, 306438.0, 53565.92868142615, 53887.19169212465, 54207.0, 54214.728926355114, 54302.72848635811, 1498.6178524476406, 1511.2217208137818, 1541.4373753579862, 1551.4880089570415, 1551.5267843307674, 18007.729884052012, 23050.0, 24036.158734444212, 24164.63753043741, 24264.51470970606, 37504.0, 37534.8123259382, 37627.2474550502, 37671.434928475544, 37676.2464750709, 215576.0, 259382.40617593582, 259690.0, 259750.0, 259910.0, 27594.44811103807, 28109.859450702617, 30289.09132726022, 34305.79929702448, 34442.0, 38691.613083868804, 39564.60435395609, 40309.39535906905, 40350.0, 40555.59444405518, 27577.310567235978, 28312.0, 28558.42883142367, 28956.855215723786, 29044.27389315284, 28428.43143137167, 28795.712042879302, 29024.854875725487, 29164.7083529162, 29207.561886572144, 34297.82851085729, 34328.82835585906, 34359.65640343565, 34370.96887093391, 34385.82807085948, 27087.051953181544, 27346.0, 27736.02923897654, 29135.562966555095, 29429.85285073561, 20877.791222087584, 22970.19604313841, 23049.308520744406, 23102.0, 23114.88442557844], "value": 17008.05514360005, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.5609261534466, 415.4619767401217, 424.33379594037376, 435.48830124603603, 449.6919610067104, 20114.89368084755, 21674.566508669835, 21727.91360431976, 21771.564568708618, 21800.345989620317, 22520.324390268317, 23128.959196836142, 23366.182183623576, 23435.765642343573, 23758.0, 5892.793752218662, 6152.538559608025, 6158.322584515699, 6180.474659653913, 6202.534175393732, 5841.0, 5968.820935371946, 6019.0, 6121.387861213888, 6129.601575897553, 5999.850003749898, 6007.729652165663, 6012.669303188323, 6016.879662406749, 6027.126066720323, 5104.285400043997, 5136.8972620547565, 5167.044096842059, 5791.160281759121, 6008.48927841134, 5380.704061276629, 5383.892322153574, 5387.568994480433, 5399.83800485986, 5400.91898621521, 26336.946522139093, 27208.45583088327, 27227.0, 27280.727192728016, 27536.0, 1945.8151475609795, 2092.267706302782, 2176.216562037665, 2179.063002908749, 2186.6829309750156, 13625.727485450334, 14271.500497482642, 16530.173491325386, 18116.818831811644, 18289.542761430937, 1960.7745109312327, 2085.301424022954, 2196.7254093238344, 2199.131343119459, 2199.8790066546335, 150884.0, 151438.0, 151653.2417337917, 151747.0, 152356.0, 12491.93754031224, 12717.745645087232, 12871.22772633645, 12979.545715899898, 13033.674158146123, 16309.755353669705, 16428.260728267258, 16437.58906027359, 16443.917780411262, 16450.095244761415, 7498.4001279897475, 8794.0, 8940.240079593177, 9007.954960225288, 9101.81796364069, 8462.746117616482, 8466.068732439428, 8467.322614190973, 8573.657053717907, 8610.87083693745, 7012.614306213105, 7879.960600197077, 8034.357251419872, 8048.879266811003, 8120.959395202986, 2130.3289463819156, 2148.387709502784, 2156.1914282144194, 2156.870587764739, 2171.055590817999, 975.9804803903883, 993.1806259835664, 995.1441760086465, 1023.0587062196186, 1038.5689938675516, 19395.3211637592, 20555.97220139017, 21225.893870530857, 21227.189100492207, 21416.143354265965, 962.4225464721251, 964.7940074906367, 1036.9222308326905, 1057.7091299892438, 1062.1184416933927, 8346.374021948379, 10575.682729518127, 10743.462826858626, 11017.724556886142, 11037.39294338803, 420132.0, 546089.0, 546293.0, 550137.0, 559157.0, 122623.0, 125403.3729831345, 126524.0, 128581.0, 131207.34396327956, 1120.0093986802706, 1175.0716933622514, 1187.3944288412972, 1244.166408506303, 1263.1915574032625, 15873.761893571374, 18016.279348826425, 21150.365489035354, 21284.78715212828, 22795.430114247276, 59579.0, 59757.701211493666, 59777.804443910005, 59837.70081149566, 60588.99999999824, 81698.0, 81762.59118704368, 82256.17743822724, 82696.0, 83318.58340708258, 8735.606897689491, 8852.336074794288, 8915.955420223117, 8936.704177894298, 8963.551822408723, 11349.943250284026, 12490.813372729575, 12674.0, 13058.542950996669, 13357.265350405822, 10876.619318323821, 11011.0, 11475.540978360772, 11632.825507617223, 11839.171258011856, 9837.0, 10581.788364232827, 10764.40795756241, 11139.944300278446, 11146.0, 11398.08815294791, 11454.541818327174, 12036.75926481483, 12066.879331206575, 12072.094592905743, 8636.309095272489, 8729.694460693845, 8796.80079980005, 8831.0, 8840.646374144962, 11518.884811152117, 11611.941940290244, 11681.591144309908, 11868.10989175798, 11876.643700688994], "value": 10312.610153735941, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1178.505027888287, 1403.4035534897673, 1409.3516982188194, 1422.8506006869272, 1423.4946593959141, 9037.367384283103, 11586.478608462628, 12033.51865925363, 12104.939475302634, 12296.446659900304, 11919.344436056013, 11981.940090299537, 12096.213746106498, 12133.393330333492, 12134.15060945732, 2520.9873950630267, 2561.308446719385, 2561.743825617433, 2563.3335332813417, 2565.7690807827294, 2487.9502409951792, 2508.7240403555593, 2516.798656107507, 2526.090607381341, 2527.2418274517663, 2452.435939733862, 2492.0530198524534, 2499.662545556345, 2499.8750062496892, 2501.224620367692, 2448.1431498975307, 2469.4567195217114, 2482.2925466242123, 2484.167803785734, 2485.1053620696716, 2458.1027924807495, 2459.569575324329, 2470.8641024743633, 2472.2336075816374, 2566.268613445159, 11567.652970410902, 11630.883691163064, 11631.243969141979, 11648.0, 11661.825072623917, 5694.288213973254, 6437.903431448532, 6638.601683898982, 6664.86670266592, 6682.264950855402, 3860.768353898747, 4038.374052021941, 4106.856260030884, 4107.527634322031, 4145.523264824554, 1229.1580267516779, 1232.12519111431, 1251.386820457978, 1253.1729058821224, 1274.910756247063, 14818.555443336609, 15203.695926081527, 16316.428924987687, 16408.83591164085, 16422.68618510516, 5454.0182767101505, 5611.579131565149, 5625.549956003591, 5628.887422251614, 5648.237487939158, 9959.651412200537, 11523.732389437322, 11681.88318116825, 11728.0, 11730.824037639442, 6369.299377068519, 6388.520860935449, 6404.71178796946, 6410.134631824737, 6465.482761379172, 5957.046872500379, 6119.479844213202, 6136.895358835363, 6186.969065154645, 6197.81406557804, 4209.305464598307, 4398.274284743045, 4445.377647129404, 4477.664175186874, 4485.730856148576, 1257.3021972805182, 1275.253976423784, 1277.3804704718204, 1288.716482373895, 1288.7177258627726, 757.7953952432812, 770.2643975003858, 793.9166387529342, 795.8527672380628, 803.0363563723555, 6238.0, 6581.840716456128, 6675.966620166868, 6930.3762661360715, 6990.405815505637, 911.3483859040817, 955.4697143085655, 959.429139661914, 960.6493629825063, 973.2943615878573, 4121.91756164881, 4174.895627609334, 4325.52419233884, 4380.627646650007, 4416.403785488982, 14482.710345793024, 14534.854651453561, 14615.561533154023, 14671.706565868837, 14714.19071951053, 12625.989920806314, 13701.177929324273, 14561.271936403138, 14829.703405931821, 14841.628959275888, 8147.633356498972, 8280.089190189074, 8284.171582841787, 8306.127856575093, 8324.29243514296, 6250.718717657807, 6585.80242592723, 6925.411340036153, 6938.791836244921, 7103.467239956921, 13496.0, 13825.165841002772, 13853.930730346283, 13930.955178361866, 13960.0, 12240.571579994657, 13930.303484825921, 14201.928990354982, 14322.57032289033, 14420.423183072979, 9169.083091690778, 9439.056094390506, 9446.574904129468, 9455.14903658674, 9458.101480359355, 10621.309614875008, 10691.572337106429, 10744.19418543628, 10745.247832651952, 10758.977897099763, 8934.195922366758, 9191.402558833654, 9257.537123143951, 9271.953640231755, 9392.342536022414, 8918.37571369998, 8935.597898094731, 9010.96373917008, 9027.142421470213, 9157.038510956254, 8046.994125734283, 8122.878156827771, 8188.549629770419, 8212.83574328522, 8235.38234632393, 5458.727063646722, 5491.697956612425, 5525.4474552544425, 5681.545476361964, 6067.302260240129, 6038.184845046038, 6165.290991535851, 6200.542872424886, 6317.557770955989, 6334.683265836782], "value": 5356.866673739415, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.52615124925717, 400.6634427081251, 401.98191081401336, 402.2678724721009, 404.0, 6486.545941784077, 6508.153939987807, 6521.543491955565, 6528.804135875919, 6539.313372095933, 6066.484348830344, 6131.0, 6303.432691057804, 6392.264889537704, 6393.616383017011, 3891.9416208756893, 4750.76246187691, 4785.186518291899, 4788.425388953313, 4809.27860820878, 3982.800859957005, 4434.490033646123, 4807.42310922688, 4808.495108013661, 4812.614990800745, 3999.8000099994883, 4532.0, 4584.679072464929, 4588.931166032512, 4615.630749540045, 4550.5904468597655, 4551.453825540923, 4562.156001139783, 4564.931526027112, 4603.930941035887, 3355.1947532592244, 3588.372034893883, 3848.7113466489845, 3922.8626998054933, 3928.7839168845703, 5978.611390259619, 6096.603720758137, 6218.689065546704, 6239.750409983595, 6240.719167637421, 4792.784324705397, 5178.177893048298, 5488.639884849513, 5491.0, 5496.532794712454, 6463.418292353711, 6730.427913627299, 6805.727770889159, 6844.384005439484, 6846.965765171142, 4110.568390319033, 4472.485664148632, 4476.6418686505, 4477.582105662938, 4485.932711009337, 6476.546641735127, 6843.247242803288, 7223.205447400782, 7244.203137654854, 7285.380742636882, 3967.4643923070016, 3982.980085099556, 4392.780360981938, 4524.140413321458, 4526.79629416677, 4589.885252868705, 4614.192516309632, 4614.930776038362, 4620.399348084795, 4624.583787459145, 4467.664925130628, 5405.405405405402, 5466.617336786467, 5503.862403439946, 5509.724513774296, 6308.810735677937, 6625.204975402982, 6830.316968303229, 6861.279565645636, 6880.449564034965, 1456.4829485532484, 1503.2934520775218, 1551.1313664348002, 1570.1835045776133, 1570.84291570842, 876.8202518483696, 974.5030034682363, 1015.5836107196162, 1026.035526604989, 1027.8406846938738, 6036.49087728068, 6299.5196128909665, 6361.840953976188, 6368.713407896656, 6418.775342862978, 6293.181886354744, 7078.716851325992, 7079.265579932847, 7094.53885497441, 7096.219415864251, 5582.832515024475, 6155.322914479404, 6182.196314479179, 6353.936460635427, 6378.202724659418, 3034.4689679306034, 3383.7141886083623, 3652.7990960496895, 3710.54918925402, 3713.424419215026, 5941.316748573883, 5994.550408719363, 6676.065350850885, 7075.893861592081, 7192.964035179791, 6580.256232098498, 6727.260001399842, 7063.293670632998, 7322.304381083789, 7325.7802265932105, 6448.774292899726, 6576.210854697467, 6943.47923905699, 7066.116735408074, 7228.0, 6532.346765323429, 6808.795736127924, 6927.965360173167, 7137.2149063604, 7137.430525010888, 5812.738426770721, 6024.1265016573225, 6283.748650053946, 6939.201991771011, 6989.344049261248, 6600.438962688226, 6991.706534291173, 7094.042304288855, 7295.8540829184185, 7307.9634601828775, 4468.687191896666, 4493.674366061954, 4496.685232033727, 4496.853302407865, 4526.88682782933, 6696.928491441346, 7011.719531218694, 7105.0, 7110.217876033529, 7145.17830449505, 6638.701258443285, 6831.795046148623, 6845.079006659304, 6847.349501797321, 6884.208316043519, 5684.630499017716, 6717.764878229434, 6837.863242735217, 6874.2094659112845, 6902.827429314308, 6579.703913324008, 6686.531942764154, 6712.127423434922, 6721.353268449261, 6736.797896063126, 6341.302456729848, 6783.491238157059, 6855.725770969106, 6855.862882742417, 6877.312268773083, 5508.889822203453, 5924.140999555127, 6481.310256243794, 6546.443552298108, 6618.7352505899225], "value": 4896.432103421236, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.8938753880685, 815.9755207343775, 819.0458116294517, 850.0352100366086, 860.8579584368578, 17068.82931170688, 17322.393716219936, 17453.389131380405, 19539.511512212168, 20130.798692013075, 16292.0, 17834.108294585283, 18293.7255941161, 18405.263789448407, 18674.719879201824, 915.5696822493418, 937.3625934364663, 938.6339327662217, 941.9387739796891, 944.7118628818208, 983.3804703037076, 999.3254553176613, 1001.0190013786494, 1001.5292812378207, 1002.9598816047386, 1031.334789061055, 1050.396022287184, 1052.5053224984283, 1052.5684469367561, 1054.0039662518923, 1032.2206733915882, 1046.5709059285657, 1047.7537778622018, 1048.1038711901328, 1049.0453687144661, 762.9160792312841, 763.7059732003163, 772.2702046565997, 774.446270916292, 776.8718161503346, 16928.576785580335, 17284.30862765488, 17705.29178832845, 17752.644947100987, 18211.0, 17279.136043197916, 17861.553461163447, 18034.819651803577, 18180.45458636243, 18266.452006439697, 14682.412703491847, 14777.0, 14858.628534286729, 14971.92514037423, 14976.0, 3488.8081155536433, 3612.837422316002, 3625.111847597329, 3637.163452405935, 3645.270945810822, 149908.0, 150821.0, 150827.0, 150956.0, 153232.70150947856, 13540.255285959367, 13551.25468099264, 13587.456501740018, 13720.588382348546, 13770.862291377158, 25229.0, 25744.48511029769, 26059.739402606112, 26189.083382081535, 26453.0, 7766.26220509062, 7940.682372705027, 7947.483413578099, 7954.2841144296135, 7971.561564113914, 3796.2976849282973, 3840.8847734568008, 3889.533256009297, 3922.195949830278, 3944.980275098606, 4373.2565463871215, 4451.132029254264, 4465.553444655508, 4468.642508599304, 4480.641548676098, 905.7192270396198, 943.7829299261208, 947.0860619502075, 951.0442005784281, 954.2556805691572, 633.8003528888386, 634.0995785983914, 660.5838321857199, 688.5971706551624, 688.6625553478759, 11128.276662017104, 12193.512259509698, 12302.446389912475, 12484.250944943333, 12670.556530521388, 652.725855140842, 655.4353538458548, 655.639398330916, 685.8032732881154, 688.4182865478663, 4915.90168196634, 5168.638195326291, 5527.115661494142, 5708.514776243983, 5745.023346031185, 142706.0, 143001.5699843009, 145385.0, 148857.0, 149347.50652493336, 98053.0, 116228.99999999831, 127023.0, 128562.0, 128743.06885396727, 74234.6288268566, 103572.48213758883, 103598.0, 103742.0, 107868.92131078745, 11032.676078870587, 11239.831402528805, 11874.703132421413, 12088.637340879788, 12184.878151218374, 35270.11824704403, 43319.56680433281, 43683.56316436922, 44278.55721442873, 45101.77449112733, 56562.0, 57040.0, 82668.0, 86836.0, 87282.0, 17636.294548217928, 17951.01269430194, 17999.910000449912, 18007.81992180114, 18041.819581804015, 18162.818371816113, 18522.907385462986, 18984.050797459797, 19176.520586985436, 19178.328758493386, 14967.401303948156, 16828.242729076977, 16909.74635380495, 17936.923784572966, 18116.456506304832, 17253.82746172522, 18075.909620451814, 18214.908925455817, 18348.899066056078, 18353.082345882918, 17650.470485885446, 17874.46376608704, 17913.731294030862, 17993.2802687891, 18371.816281837546, 11864.0, 11999.460024298753, 12195.451204695988, 12286.385680716106, 12681.365931703194, 11273.492692829006, 13029.697030296895, 13870.167789932637, 14068.226247556482, 14131.010829241854], "value": 8872.84405955659, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 115.0, 115.0, 115.0, 115.0, 115.0, 115.0, 117.0, 116.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 126.0, 142.0, 166.0, 108.0, 117.0, 99.0, 140.0, 164.0, 141.0], "value": 135.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 206.0, 207.0, 220.0, 236.0, 253.0, 197.0, 201.0, 194.0, 196.0], "value": 211.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 50.0, 62.0, 93.0, 82.0, 64.0, 89.0, 90.0, 77.0, 57.0], "value": 75.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 67.0, 83.0, 72.0, 70.0, 69.0, 92.0, 70.0, 70.0, 93.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 91.0, 113.0, 91.0, 127.0, 135.0, 92.0, 136.0, 135.0], "value": 110.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 150.0, 99.0, 99.0, 98.0, 98.0, 100.0, 106.0, 119.0, 101.0], "value": 108.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 110.0, 91.0, 92.0, 97.0, 139.0, 85.0, 143.0, 144.0, 91.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 52.0, 52.0, 54.0, 52.0, 52.0, 53.0, 53.0, 52.0, 50.0], "value": 52.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 63.0, 65.0, 65.0, 68.0, 68.0, 46.0, 60.0, 49.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 111.0, 87.0, 87.0, 87.0, 88.0, 98.0, 146.0, 114.0], "value": 99.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 138.0, 135.0, 139.0, 136.0, 150.0, 177.0, 167.0, 157.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 244.0, 229.0, 230.0, 190.0, 186.0, 187.0, 224.0, 188.0, 186.0], "value": 207.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 69.0, 83.0, 69.0, 63.0, 70.0, 99.0, 90.0, 81.0], "value": 76.7, "unit": "score"}]}]} 09:47:34 INFO - Return code: 0 09:47:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:47:34 INFO - # TBPL SUCCESS # 09:47:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:47:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:47:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:47:34 INFO - 2017-05-28 09:47:34.354 screenresolution[1667:11067] starting screenresolution argv=screenresolution get 09:47:34 INFO - 2017-05-28 09:47:34.357 screenresolution[1667:11067] Display 0: 1600x1200x32@60 09:47:34 INFO - 2017-05-28 09:47:34.362 screenresolution[1668:11070] starting screenresolution argv=screenresolution list 09:47:34 INFO - Available Modes on Display 0 09:47:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:47:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:47:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:47:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:47:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:47:34 INFO - Intel Iris: 09:47:34 INFO - Chipset Model: Intel Iris 09:47:34 INFO - Type: GPU 09:47:34 INFO - Bus: Built-In 09:47:34 INFO - VRAM (Dynamic, Max): 1536 MB 09:47:34 INFO - Vendor: Intel (0x8086) 09:47:34 INFO - Device ID: 0x0a2e 09:47:34 INFO - Revision ID: 0x0009 09:47:34 INFO - Displays: 09:47:34 INFO - Display: 09:47:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:47:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:47:34 INFO - Display Serial Number: 1600x1200 60 09:47:34 INFO - Main Display: Yes 09:47:34 INFO - Mirror: Off 09:47:34 INFO - Online: Yes 09:47:34 INFO - Rotation: Supported 09:47:34 INFO - Return code: 0 09:47:34 INFO - Running post-action listener: _resource_record_post_action 09:47:34 INFO - [mozharness: 2017-05-28 16:47:34.600999Z] Finished run-tests step (success) 09:47:34 INFO - Running post-run listener: _resource_record_post_run 09:47:34 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 53099520; Write bytes: 571398144; Read time: 619; Write time: 1849 09:47:34 INFO - TinderboxPrint: CPU usage
53.3% 09:47:34 INFO - TinderboxPrint: I/O read bytes / time
53,099,520 / 619 09:47:34 INFO - TinderboxPrint: I/O write bytes / time
571,398,144 / 1,849 09:47:34 INFO - TinderboxPrint: CPU idle
2,348.2 (46.7%) 09:47:34 INFO - TinderboxPrint: CPU system
1,021.0 (20.3%) 09:47:34 INFO - TinderboxPrint: CPU user
1,664.4 (33.1%) 09:47:34 INFO - TinderboxPrint: Swap in / out
285,679,616 / 0 09:47:34 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244070912; Write bytes: 238393344; Read time: 18205; Write time: 519 09:47:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:47:34 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 47586304; Write bytes: 322662400; Read time: 574; Write time: 1289 09:47:35 INFO - Running post-run listener: _upload_blobber_files 09:47:35 INFO - Blob upload gear active. 09:47:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:47:35 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 09:47:35 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'] 09:47:35 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 09:47:35 INFO - (blobuploader) - INFO - Open directory for files ... 09:47:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:47:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:47:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:36 INFO - (blobuploader) - INFO - Done attempting. 09:47:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:47:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:47:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:37 INFO - (blobuploader) - INFO - Done attempting. 09:47:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:47:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:47:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:38 INFO - (blobuploader) - INFO - Done attempting. 09:47:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:47:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:47:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:47:41 INFO - (blobuploader) - INFO - Done attempting. 09:47:41 INFO - (blobuploader) - INFO - Iteration through files over. 09:47:41 INFO - Return code: 0 09:47:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:47:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:47:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20454d0cd92e305ec32968373f80c072e62ccbc30d89bb737e5d47b445a1e4d8a3f888a247ee969bd08f6938afe5fb8715b3ee88c3157a79cd298117c9279e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/335bcc4c418490f001833d8634f2a86aabac415232e09ca89c0aad489683c76a6ff175ef445429fb36e795289f62a70414077b76b0ce9bbffa1b0cb1854fa1b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192451b1c4fa3640d4b34745c0d66b1b0b1ecf98c4392613160b046a7be840ebe7fd0388ec42f26c49b288b50cd77ae93e3fd62761d77c78da272c6e13deb9aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a478cf6966a6168bfe3798d55ac383a9c57c6aa04e54c45bdc6304df4ace890cea321c6ca1011d8ff1da824c01bda6c526e1d99d84f280b6a9aeb20b087c267f"} 09:47:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:47:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:47:41 INFO - Contents: 09:47:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20454d0cd92e305ec32968373f80c072e62ccbc30d89bb737e5d47b445a1e4d8a3f888a247ee969bd08f6938afe5fb8715b3ee88c3157a79cd298117c9279e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/335bcc4c418490f001833d8634f2a86aabac415232e09ca89c0aad489683c76a6ff175ef445429fb36e795289f62a70414077b76b0ce9bbffa1b0cb1854fa1b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192451b1c4fa3640d4b34745c0d66b1b0b1ecf98c4392613160b046a7be840ebe7fd0388ec42f26c49b288b50cd77ae93e3fd62761d77c78da272c6e13deb9aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a478cf6966a6168bfe3798d55ac383a9c57c6aa04e54c45bdc6304df4ace890cea321c6ca1011d8ff1da824c01bda6c526e1d99d84f280b6a9aeb20b087c267f"} 09:47:41 INFO - Running post-run listener: copy_logs_to_upload_dir 09:47:41 INFO - Copying logs to upload dir... 09:47:41 INFO - mkdir: /builds/slave/test/build/upload/logs 09:47:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1342.078090 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 22 secs) (at 2017-05-28 09:47:41.306855) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-28 09:47:41.310028) ========= 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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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/20454d0cd92e305ec32968373f80c072e62ccbc30d89bb737e5d47b445a1e4d8a3f888a247ee969bd08f6938afe5fb8715b3ee88c3157a79cd298117c9279e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/335bcc4c418490f001833d8634f2a86aabac415232e09ca89c0aad489683c76a6ff175ef445429fb36e795289f62a70414077b76b0ce9bbffa1b0cb1854fa1b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192451b1c4fa3640d4b34745c0d66b1b0b1ecf98c4392613160b046a7be840ebe7fd0388ec42f26c49b288b50cd77ae93e3fd62761d77c78da272c6e13deb9aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a478cf6966a6168bfe3798d55ac383a9c57c6aa04e54c45bdc6304df4ace890cea321c6ca1011d8ff1da824c01bda6c526e1d99d84f280b6a9aeb20b087c267f"} build_url:https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011172 build_url: 'https://queue.taskcluster.net/v1/task/foqfDGpxTa-VOVDciqNQkg/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/20454d0cd92e305ec32968373f80c072e62ccbc30d89bb737e5d47b445a1e4d8a3f888a247ee969bd08f6938afe5fb8715b3ee88c3157a79cd298117c9279e06", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/335bcc4c418490f001833d8634f2a86aabac415232e09ca89c0aad489683c76a6ff175ef445429fb36e795289f62a70414077b76b0ce9bbffa1b0cb1854fa1b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/192451b1c4fa3640d4b34745c0d66b1b0b1ecf98c4392613160b046a7be840ebe7fd0388ec42f26c49b288b50cd77ae93e3fd62761d77c78da272c6e13deb9aa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a478cf6966a6168bfe3798d55ac383a9c57c6aa04e54c45bdc6304df4ace890cea321c6ca1011d8ff1da824c01bda6c526e1d99d84f280b6a9aeb20b087c267f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-28 09:47:41.342644) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 09:47:41.342930) ========= 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.F7rvW0HrIQ/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.tQmA4ehhI1/Listeners TMPDIR=/var/folders/kx/7l4fx3453sv4mdb6z4lz3d3m00000w/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.005219 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 09:47:41.392849) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-28 09:47:41.393133) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-28 09:47:41.393431) ========= ========= Total master_lag: 0.38 =========