builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0123 starttime: 1496368498.83 results: success (0) revision: 877c3da58fa35bf4e58f2ac0cbcf59840702cc6e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.830737) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.831179) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.831458) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.925475) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:58.925854) ========= 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.xzz4uMNAbo/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.lBfT1ea31k/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 18:54:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.8M=0s 2017-06-01 18:54:59 (63.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.633298 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:59.581881) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:59.582191) ========= 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.xzz4uMNAbo/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.lBfT1ea31k/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.021069 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 18:54:59.642722) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 18:54:59.643023) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 877c3da58fa35bf4e58f2ac0cbcf59840702cc6e --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 877c3da58fa35bf4e58f2ac0cbcf59840702cc6e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xzz4uMNAbo/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.lBfT1ea31k/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 18:54:59,713 truncating revision to first 12 chars 2017-06-01 18:54:59,713 Setting DEBUG logging. 2017-06-01 18:54:59,713 attempt 1/10 2017-06-01 18:54:59,713 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/877c3da58fa3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 18:55:00,871 unpacking tar archive at: mozilla-central-877c3da58fa3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.417631 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 18:55:01.111104) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:01.111427) ========= script_repo_revision: 877c3da58fa35bf4e58f2ac0cbcf59840702cc6e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:01.111836) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:01.112564) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 18:55:01.195625) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-01 18:55:01.195897) ========= /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.xzz4uMNAbo/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.lBfT1ea31k/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:55:01 INFO - MultiFileLogger online at 20170601 18:55:01 in /builds/slave/test 18:55:01 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 18:55:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:55:01 INFO - {'append_to_log': False, 18:55:01 INFO - 'base_work_dir': '/builds/slave/test', 18:55:01 INFO - 'blob_upload_branch': 'Firefox', 18:55:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:55:01 INFO - 'branch': 'Firefox', 18:55:01 INFO - 'buildbot_json_path': 'buildprops.json', 18:55:01 INFO - 'config_files': ('talos/mac_config.py',), 18:55:01 INFO - 'default_actions': ('clobber', 18:55:01 INFO - 'read-buildbot-config', 18:55:01 INFO - 'download-and-extract', 18:55:01 INFO - 'populate-webroot', 18:55:01 INFO - 'create-virtualenv', 18:55:01 INFO - 'install', 18:55:01 INFO - 'setup-mitmproxy', 18:55:01 INFO - 'run-tests'), 18:55:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:55:01 INFO - 'download_minidump_stackwalk': True, 18:55:01 INFO - 'download_symbols': 'ondemand', 18:55:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:55:01 INFO - 'tooltool.py': '/tools/tooltool.py', 18:55:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:55:01 INFO - '/tools/misc-python/virtualenv.py')}, 18:55:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:55:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:55:01 INFO - 'gecko_profile': False, 18:55:01 INFO - 'gecko_profile_interval': 0, 18:55:01 INFO - 'installer_path': 'installer.exe', 18:55:01 INFO - 'log_level': 'info', 18:55:01 INFO - 'log_name': 'talos', 18:55:01 INFO - 'log_to_console': True, 18:55:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:55:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:55:01 INFO - 'opt_config_files': (), 18:55:01 INFO - 'pip_index': False, 18:55:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:01 INFO - 'cmd': ('bash', 18:55:01 INFO - '-c', 18:55:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:01 INFO - 'enabled': True, 18:55:01 INFO - 'halt_on_failure': False, 18:55:01 INFO - 'name': 'check_screen_resolution'},), 18:55:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:01 INFO - 'cmd': ('bash', 18:55:01 INFO - '-c', 18:55:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:01 INFO - 'enabled': True, 18:55:01 INFO - 'halt_on_failure': False, 18:55:01 INFO - 'name': 'check_screen_resolution'},), 18:55:01 INFO - 'run_cmd_checks_enabled': True, 18:55:01 INFO - 'suite': 'dromaeojs-e10s', 18:55:01 INFO - 'system_bits': '32', 18:55:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:55:01 INFO - 'title': 't-yosemite-r7-0123', 18:55:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:55:01 INFO - 'use_talos_json': True, 18:55:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:55:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:55:01 INFO - 'work_dir': 'build'} 18:55:01 INFO - [mozharness: 2017-06-02 01:55:01.397717Z] Running clobber step. 18:55:01 INFO - Running pre-action listener: _resource_record_pre_action 18:55:01 INFO - Running main action method: clobber 18:55:01 INFO - rmtree: /builds/slave/test/build 18:55:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:55:01 INFO - Running post-action listener: _resource_record_post_action 18:55:01 INFO - [mozharness: 2017-06-02 01:55:01.592679Z] Finished clobber step (success) 18:55:01 INFO - [mozharness: 2017-06-02 01:55:01.592803Z] Running read-buildbot-config step. 18:55:01 INFO - Running pre-action listener: _resource_record_pre_action 18:55:01 INFO - Running main action method: read_buildbot_config 18:55:01 INFO - Using buildbot properties: 18:55:01 INFO - { 18:55:01 INFO - "project": "", 18:55:01 INFO - "product": "firefox", 18:55:01 INFO - "who": "ffxbld@noreply.mozilla.org", 18:55:01 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:55:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:55:01 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:55:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:55:01 INFO - "stage_platform": "macosx64", 18:55:01 INFO - "basedir": "/builds/slave/test", 18:55:01 INFO - "buildnumber": 173, 18:55:01 INFO - "platform": "yosemite_r7", 18:55:01 INFO - "parent_task_id": "d9QA_QLvTR-2t4GWaGCbEA", 18:55:01 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:55:01 INFO - "slavebuilddir": "test", 18:55:01 INFO - "taskId": "Ny4A1IM2QB6PRDhxHrzG2g", 18:55:01 INFO - "branch": "mozilla-central", 18:55:01 INFO - "script_repo_revision": "production", 18:55:01 INFO - "revision": "877c3da58fa35bf4e58f2ac0cbcf59840702cc6e", 18:55:01 INFO - "slavename": "t-yosemite-r7-0123", 18:55:01 INFO - "repo_path": "mozilla-central" 18:55:01 INFO - } 18:55:01 INFO - Finding installer, test and symbols from parent task. 18:55:01 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ny4A1IM2QB6PRDhxHrzG2g'}, attempt #1 18:55:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ny4A1IM2QB6PRDhxHrzG2g'}, attempt #1 18:55:02 INFO - Task dependencies: F-d0JuuJQviSBCdnUcj4hQ d9QA_QLvTR-2t4GWaGCbEA 18:55:02 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA'}, attempt #1 18:55:03 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:03 INFO - Set test_url: https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/test_packages.json 18:55:03 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/target.crashreporter-symbols.zip 18:55:03 INFO - Running post-action listener: _resource_record_post_action 18:55:03 INFO - [mozharness: 2017-06-02 01:55:03.405298Z] Finished read-buildbot-config step (success) 18:55:03 INFO - [mozharness: 2017-06-02 01:55:03.405402Z] Running download-and-extract step. 18:55:03 INFO - Running pre-action listener: _resource_record_pre_action 18:55:03 INFO - Running main action method: download_and_extract 18:55:03 INFO - mkdir: /builds/slave/test/build/tests 18:55:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:03 INFO - trying https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:55:03 INFO - Downloading https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/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 18:55:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/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 18:55:04 INFO - Downloaded 1325 bytes. 18:55:04 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:55:04 INFO - Using the following test package requirements: 18:55:04 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:55:04 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:55:04 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:55:04 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:55:04 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'jsshell-mac.zip'], 18:55:04 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:55:04 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:55:04 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:55:04 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:55:04 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:55:04 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:55:04 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:55:04 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:55:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:55:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:04 INFO - Fetch https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:55:07 INFO - Content-Length response header: 57625437 18:55:07 INFO - Bytes received: 57625437 18:55:25 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 18:55:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:55:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:25 INFO - Fetch https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:55:27 INFO - Content-Length response header: 57625437 18:55:27 INFO - Bytes received: 57625437 18:55:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:55:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:55:44 INFO - Fetch https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:55:45 INFO - Content-Length response header: 14447425 18:55:45 INFO - Bytes received: 14447425 18:55:46 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')]} 18:55:46 INFO - trying https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:46 INFO - Downloading https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:55:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/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 18:55:48 INFO - Downloaded 67912538 bytes. 18:55:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:48 INFO - mkdir: /builds/slave/test/properties 18:55:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:55:48 INFO - Writing to file /builds/slave/test/properties/build_url 18:55:48 INFO - Contents: 18:55:48 INFO - build_url:https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:55:48 INFO - Running post-action listener: _resource_record_post_action 18:55:48 INFO - Running post-action listener: set_extra_try_arguments 18:55:48 INFO - [mozharness: 2017-06-02 01:55:48.073658Z] Finished download-and-extract step (success) 18:55:48 INFO - [mozharness: 2017-06-02 01:55:48.073788Z] Running populate-webroot step. 18:55:48 INFO - Running pre-action listener: _resource_record_pre_action 18:55:48 INFO - Running main action method: populate_webroot 18:55:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:55:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:55:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:55:48 INFO - '--tppagecycles', 18:55:48 INFO - '7'], 18:55:48 INFO - 'tests': ['tsvgm']}}, 18:55:48 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:55:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:55:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:55:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:55:48 INFO - 'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:55:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:55:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:55:48 INFO - 'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['damp', 'tps']}, 18:55:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:55:48 INFO - 'tests': ['damp', 'tps']}, 18:55:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['dromaeo_dom']}, 18:55:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:55:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:55:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:55:48 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:55:48 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:55:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['a11yr', 18:55:48 INFO - 'ts_paint', 18:55:48 INFO - 'tpaint', 18:55:48 INFO - 'sessionrestore', 18:55:48 INFO - 'sessionrestore_no_auto_restore', 18:55:48 INFO - 'tabpaint']}, 18:55:48 INFO - 'other-e10s': {'tests': ['a11yr', 18:55:48 INFO - 'ts_paint', 18:55:48 INFO - 'tpaint', 18:55:48 INFO - 'sessionrestore', 18:55:48 INFO - 'sessionrestore_no_auto_restore', 18:55:48 INFO - 'tabpaint']}, 18:55:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:55:48 INFO - 'ts_paint', 18:55:48 INFO - 'tpaint', 18:55:48 INFO - 'sessionrestore', 18:55:48 INFO - 'sessionrestore_no_auto_restore', 18:55:48 INFO - 'tabpaint']}, 18:55:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:55:48 INFO - 'ts_paint', 18:55:48 INFO - 'tpaint', 18:55:48 INFO - 'sessionrestore', 18:55:48 INFO - 'sessionrestore_no_auto_restore', 18:55:48 INFO - 'tabpaint']}, 18:55:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['a11yr', 18:55:48 INFO - 'ts_paint', 18:55:48 INFO - 'tpaint', 18:55:48 INFO - 'sessionrestore', 18:55:48 INFO - 'sessionrestore_no_auto_restore', 18:55:48 INFO - 'tabpaint']}, 18:55:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['a11yr', 18:55:48 INFO - 'ts_paint', 18:55:48 INFO - 'tpaint', 18:55:48 INFO - 'sessionrestore', 18:55:48 INFO - 'sessionrestore_no_auto_restore', 18:55:48 INFO - 'tabpaint']}, 18:55:48 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:55:48 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:55:48 INFO - 'bloom_basic_ref']}, 18:55:48 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:55:48 INFO - 'talos_options': ['--mitmproxy', 18:55:48 INFO - 'mitmproxy-recording-1.mp', 18:55:48 INFO - '--firstNonBlankPaint'], 18:55:48 INFO - 'tests': ['Quantum_1']}, 18:55:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['tsvgx', 18:55:48 INFO - 'tsvgr_opacity', 18:55:48 INFO - 'tart', 18:55:48 INFO - 'tscrollx', 18:55:48 INFO - 'cart', 18:55:48 INFO - 'tsvg_static']}, 18:55:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:55:48 INFO - 'tsvgr_opacity', 18:55:48 INFO - 'tart', 18:55:48 INFO - 'tscrollx', 18:55:48 INFO - 'cart', 18:55:48 INFO - 'tsvg_static']}, 18:55:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:55:48 INFO - 'talos_options': ['--disable-e10s'], 18:55:48 INFO - 'tests': ['tp5o']}, 18:55:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:55:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:55:48 INFO - 'talos_options': ['--disable-e10s', 18:55:48 INFO - '--xperf_path', 18:55:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:48 INFO - 'tests': ['tp5n']}, 18:55:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:55:48 INFO - 'talos_options': ['--xperf_path', 18:55:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:55:48 INFO - 'tests': ['tp5n']}}, 18:55:48 INFO - 'talos.zip': {'path': '', 18:55:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:55:48 INFO - Running post-action listener: _resource_record_post_action 18:55:48 INFO - [mozharness: 2017-06-02 01:55:48.095693Z] Finished populate-webroot step (success) 18:55:48 INFO - [mozharness: 2017-06-02 01:55:48.095804Z] Running create-virtualenv step. 18:55:48 INFO - Running pre-action listener: _resource_record_pre_action 18:55:48 INFO - Running main action method: create_virtualenv 18:55:48 INFO - Creating virtualenv /builds/slave/test/build/venv 18:55:48 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 18:55:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:55:48 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:55:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:55:48 INFO - Using real prefix '/tools/python27' 18:55:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:55:48 INFO - Installing distribute.............................................................................................................................................................................................done. 18:55:51 INFO - Installing pip.................done. 18:55:51 INFO - Return code: 0 18:55:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:55:52 INFO - Reading from file tmpfile_stdout 18:55:52 INFO - Output received: 18:55:52 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) 18:55:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:52 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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:55:52 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 18:55:52 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 18:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:52 INFO - 'HOME': '/Users/cltbld', 18:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:52 INFO - 'LOGNAME': 'cltbld', 18:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:52 INFO - 'MOZ_NO_REMOTE': '1', 18:55:52 INFO - 'NO_EM_RESTART': '1', 18:55:52 INFO - 'PAGER': '/bin/cat', 18:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:52 INFO - 'PWD': '/builds/slave/test', 18:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:52 INFO - 'SHELL': '/bin/bash', 18:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:55:52 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:52 INFO - 'USER': 'cltbld', 18:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:52 INFO - 'XPC_FLAGS': '0x0', 18:55:52 INFO - 'XPC_SERVICE_NAME': '0', 18:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:52 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 18:55:52 INFO - Cleaning up... 18:55:52 INFO - Return code: 0 18:55:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:52 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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:55:52 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 18:55:52 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 18:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:52 INFO - 'HOME': '/Users/cltbld', 18:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:52 INFO - 'LOGNAME': 'cltbld', 18:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:52 INFO - 'MOZ_NO_REMOTE': '1', 18:55:52 INFO - 'NO_EM_RESTART': '1', 18:55:52 INFO - 'PAGER': '/bin/cat', 18:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:52 INFO - 'PWD': '/builds/slave/test', 18:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:52 INFO - 'SHELL': '/bin/bash', 18:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:55:52 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:52 INFO - 'USER': 'cltbld', 18:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:52 INFO - 'XPC_FLAGS': '0x0', 18:55:52 INFO - 'XPC_SERVICE_NAME': '0', 18:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:52 INFO - Downloading/unpacking psutil>=3.1.1 18:55:52 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 18:55:52 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 18:55:52 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 18:55:52 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 18:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:55:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:54 INFO - Installing collected packages: psutil 18:55:54 INFO - Running setup.py install for psutil 18:55:55 INFO - building 'psutil._psutil_osx' extension 18:55:55 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 18:55:55 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 18:55:55 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 18:55:55 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 18:55:55 INFO - building 'psutil._psutil_posix' extension 18:55:55 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 18:55:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:55:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:55:55 INFO - ^ 18:55:55 INFO - 1 warning generated. 18:55:55 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 18:55:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:55:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:55:55 INFO - Successfully installed psutil 18:55:55 INFO - Cleaning up... 18:55:55 INFO - Return code: 0 18:55:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:55 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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:55:55 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 18:55:55 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 18:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:55 INFO - 'HOME': '/Users/cltbld', 18:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:55 INFO - 'LOGNAME': 'cltbld', 18:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:55 INFO - 'MOZ_NO_REMOTE': '1', 18:55:55 INFO - 'NO_EM_RESTART': '1', 18:55:55 INFO - 'PAGER': '/bin/cat', 18:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:55 INFO - 'PWD': '/builds/slave/test', 18:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:55 INFO - 'SHELL': '/bin/bash', 18:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:55:55 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:55 INFO - 'USER': 'cltbld', 18:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:55 INFO - 'XPC_FLAGS': '0x0', 18:55:55 INFO - 'XPC_SERVICE_NAME': '0', 18:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:55:56 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 18:55:56 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 18:55:56 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 18:55:56 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 18:55:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:55:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:55:57 INFO - Installing collected packages: mozsystemmonitor 18:55:57 INFO - Running setup.py install for mozsystemmonitor 18:55:57 INFO - Successfully installed mozsystemmonitor 18:55:57 INFO - Cleaning up... 18:55:57 INFO - Return code: 0 18:55:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:55:57 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')]} 18:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:57 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')]} 18:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:57 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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:55:57 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 18:55:57 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 18:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:57 INFO - 'HOME': '/Users/cltbld', 18:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:57 INFO - 'LOGNAME': 'cltbld', 18:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:57 INFO - 'MOZ_NO_REMOTE': '1', 18:55:57 INFO - 'NO_EM_RESTART': '1', 18:55:57 INFO - 'PAGER': '/bin/cat', 18:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:57 INFO - 'PWD': '/builds/slave/test', 18:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:57 INFO - 'SHELL': '/bin/bash', 18:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:55:57 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:57 INFO - 'USER': 'cltbld', 18:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:57 INFO - 'XPC_FLAGS': '0x0', 18:55:57 INFO - 'XPC_SERVICE_NAME': '0', 18:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:55:57 INFO - Downloading/unpacking jsonschema==2.5.1 18:55:57 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 18:55:57 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 18:55:57 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 18:55:57 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 18:55:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:55:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:55:59 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 18:55:59 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 18:55:59 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 18:55:59 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 18:55:59 INFO - Downloading functools32-3.2.3-2.tar.gz 18:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:55:59 INFO - warning: no files found matching '*.txt' 18:55:59 INFO - no previously-included directories found matching 'build' 18:55:59 INFO - no previously-included directories found matching 'dist' 18:55:59 INFO - no previously-included directories found matching '.git*' 18:55:59 INFO - Installing collected packages: jsonschema, functools32 18:55:59 INFO - Running setup.py install for functools32 18:55:59 INFO - warning: no files found matching '*.txt' 18:55:59 INFO - no previously-included directories found matching 'build' 18:55:59 INFO - no previously-included directories found matching 'dist' 18:55:59 INFO - no previously-included directories found matching '.git*' 18:55:59 INFO - Successfully installed jsonschema functools32 18:55:59 INFO - Cleaning up... 18:55:59 INFO - Return code: 0 18:55:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:55:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:55:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:55:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:55:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:55:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:55:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:55:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:55:59 INFO - 'HOME': '/Users/cltbld', 18:55:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:55:59 INFO - 'LOGNAME': 'cltbld', 18:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:55:59 INFO - 'MOZ_NO_REMOTE': '1', 18:55:59 INFO - 'NO_EM_RESTART': '1', 18:55:59 INFO - 'PAGER': '/bin/cat', 18:55:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:55:59 INFO - 'PWD': '/builds/slave/test', 18:55:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:55:59 INFO - 'SHELL': '/bin/bash', 18:55:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:55:59 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:55:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:55:59 INFO - 'USER': 'cltbld', 18:55:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:55:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:55:59 INFO - 'XPC_FLAGS': '0x0', 18:55:59 INFO - 'XPC_SERVICE_NAME': '0', 18:55:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:56:00 INFO - Cleaning up... 18:56:00 INFO - Return code: 0 18:56:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:56:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:56:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:00 INFO - 'HOME': '/Users/cltbld', 18:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:00 INFO - 'LOGNAME': 'cltbld', 18:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:00 INFO - 'MOZ_NO_REMOTE': '1', 18:56:00 INFO - 'NO_EM_RESTART': '1', 18:56:00 INFO - 'PAGER': '/bin/cat', 18:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:00 INFO - 'PWD': '/builds/slave/test', 18:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:00 INFO - 'SHELL': '/bin/bash', 18:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:56:00 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:00 INFO - 'USER': 'cltbld', 18:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:00 INFO - 'XPC_FLAGS': '0x0', 18:56:00 INFO - 'XPC_SERVICE_NAME': '0', 18:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:00 INFO - Downloading/unpacking blobuploader==1.2.4 18:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:01 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:56:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:56:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:02 INFO - Downloading docopt-0.6.1.tar.gz 18:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:56:02 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:02 INFO - Running setup.py install for blobuploader 18:56:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:56:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:56:02 INFO - Running setup.py install for requests 18:56:03 INFO - Running setup.py install for docopt 18:56:03 INFO - Successfully installed blobuploader requests docopt 18:56:03 INFO - Cleaning up... 18:56:03 INFO - Return code: 0 18:56:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:03 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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:56:03 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 18:56:03 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 18:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:03 INFO - 'HOME': '/Users/cltbld', 18:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:03 INFO - 'LOGNAME': 'cltbld', 18:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:03 INFO - 'MOZ_NO_REMOTE': '1', 18:56:03 INFO - 'NO_EM_RESTART': '1', 18:56:03 INFO - 'PAGER': '/bin/cat', 18:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:03 INFO - 'PWD': '/builds/slave/test', 18:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:03 INFO - 'SHELL': '/bin/bash', 18:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:56:03 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:03 INFO - 'USER': 'cltbld', 18:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:03 INFO - 'XPC_FLAGS': '0x0', 18:56:03 INFO - 'XPC_SERVICE_NAME': '0', 18:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:03 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-UKun3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1kCA4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-DDPVNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-V5wiKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hwWlp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ZsSAhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wkgpY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-aQ82Jo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-K28hzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-hxznG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:04 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-9T0dbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-0qnOD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-w6CKqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-yFcLAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-HYOuul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_gV3MD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Ru5_ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:05 INFO - Running setup.py install for manifestparser 18:56:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:56:05 INFO - Running setup.py install for mozcrash 18:56:05 INFO - Running setup.py install for mozdebug 18:56:06 INFO - Running setup.py install for mozdevice 18:56:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:56:06 INFO - Running setup.py install for mozfile 18:56:06 INFO - Running setup.py install for mozhttpd 18:56:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:56:06 INFO - Running setup.py install for mozinfo 18:56:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:56:06 INFO - Running setup.py install for mozInstall 18:56:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:56:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:56:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:56:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:56:06 INFO - Running setup.py install for mozleak 18:56:06 INFO - Running setup.py install for mozlog 18:56:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:56:07 INFO - Running setup.py install for moznetwork 18:56:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:56:07 INFO - Running setup.py install for mozprocess 18:56:07 INFO - Running setup.py install for mozprofile 18:56:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:56:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:56:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:56:07 INFO - Running setup.py install for mozrunner 18:56:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:56:07 INFO - Running setup.py install for mozscreenshot 18:56:07 INFO - Running setup.py install for moztest 18:56:08 INFO - Running setup.py install for mozversion 18:56:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:56:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:08 INFO - Cleaning up... 18:56:08 INFO - Return code: 0 18:56:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:08 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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:56:08 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 18:56:08 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 18:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:08 INFO - 'HOME': '/Users/cltbld', 18:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:08 INFO - 'LOGNAME': 'cltbld', 18:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:08 INFO - 'MOZ_NO_REMOTE': '1', 18:56:08 INFO - 'NO_EM_RESTART': '1', 18:56:08 INFO - 'PAGER': '/bin/cat', 18:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:08 INFO - 'PWD': '/builds/slave/test', 18:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:08 INFO - 'SHELL': '/bin/bash', 18:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:56:08 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:08 INFO - 'USER': 'cltbld', 18:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:08 INFO - 'XPC_FLAGS': '0x0', 18:56:08 INFO - 'XPC_SERVICE_NAME': '0', 18:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vscJHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:08 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)) 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-arhm9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:08 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)) 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-WnORgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:08 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)) 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-RbBRZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:08 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)) 18:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:08 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-gKuE2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-QlUsIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-zGbF_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-kIgS9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-gY9jkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-h5WlUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-dN4uJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-qbl4Xh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-fwb8ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:09 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)) 18:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:09 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-90_DjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:10 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)) 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-5QNx63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:10 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)) 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-t0yLmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:10 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)) 18:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:10 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-z0pn2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:10 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)) 18:56:10 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)) 18:56:10 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)) 18:56:10 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)) 18:56:10 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)) 18:56:10 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)) 18:56:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:11 INFO - Downloading blessings-1.6.tar.gz 18:56:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:56:11 INFO - Installing collected packages: blessings 18:56:11 INFO - Running setup.py install for blessings 18:56:11 INFO - Successfully installed blessings 18:56:11 INFO - Cleaning up... 18:56:12 INFO - Return code: 0 18:56:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:56:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:12 INFO - Reading from file tmpfile_stdout 18:56:12 INFO - Current package versions: 18:56:12 INFO - blessings == 1.6 18:56:12 INFO - blobuploader == 1.2.4 18:56:12 INFO - docopt == 0.6.1 18:56:12 INFO - functools32 == 3.2.3-2 18:56:12 INFO - jsonschema == 2.5.1 18:56:12 INFO - manifestparser == 1.1 18:56:12 INFO - mozInstall == 1.12 18:56:12 INFO - mozcrash == 1.0 18:56:12 INFO - mozdebug == 0.1 18:56:12 INFO - mozdevice == 0.51 18:56:12 INFO - mozfile == 1.2 18:56:12 INFO - mozhttpd == 0.7 18:56:12 INFO - mozinfo == 0.9 18:56:12 INFO - mozleak == 0.1 18:56:12 INFO - mozlog == 3.4 18:56:12 INFO - moznetwork == 0.27 18:56:12 INFO - mozprocess == 0.25 18:56:12 INFO - mozprofile == 0.28 18:56:12 INFO - mozrunner == 6.13 18:56:12 INFO - mozscreenshot == 0.1 18:56:12 INFO - mozsystemmonitor == 0.3 18:56:12 INFO - moztest == 0.8 18:56:12 INFO - mozversion == 1.4 18:56:12 INFO - psutil == 3.1.1 18:56:12 INFO - requests == 1.2.3 18:56:12 INFO - wsgiref == 0.1.2 18:56:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56: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')]} 18:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56: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')]} 18:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:12 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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:56:12 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 18:56:12 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 18:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:12 INFO - 'HOME': '/Users/cltbld', 18:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:12 INFO - 'LOGNAME': 'cltbld', 18:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:12 INFO - 'MOZ_NO_REMOTE': '1', 18:56:12 INFO - 'NO_EM_RESTART': '1', 18:56:12 INFO - 'PAGER': '/bin/cat', 18:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:12 INFO - 'PWD': '/builds/slave/test', 18:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:12 INFO - 'SHELL': '/bin/bash', 18:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:56:12 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:12 INFO - 'USER': 'cltbld', 18:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:12 INFO - 'XPC_FLAGS': '0x0', 18:56:12 INFO - 'XPC_SERVICE_NAME': '0', 18:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:12 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)) 18:56:12 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)) 18:56:12 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)) 18:56:12 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)) 18:56:12 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)) 18:56:12 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)) 18:56:12 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)) 18:56:12 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)) 18:56:12 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)) 18:56:12 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:13 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:56:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:56:14 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)) 18:56:14 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)) 18:56:14 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)) 18:56:14 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)) 18:56:14 INFO - Installing collected packages: marionette-driver 18:56:14 INFO - Running setup.py install for marionette-driver 18:56:14 INFO - Successfully installed marionette-driver 18:56:14 INFO - Cleaning up... 18:56:14 INFO - Return code: 0 18:56:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:14 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 0x104134c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104138768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0b8566650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, '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.xzz4uMNAbo/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.lBfT1ea31k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 18:56:14 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 18:56:14 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 18:56:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:56:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:14 INFO - 'HOME': '/Users/cltbld', 18:56:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:14 INFO - 'LOGNAME': 'cltbld', 18:56:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:14 INFO - 'MOZ_NO_REMOTE': '1', 18:56:14 INFO - 'NO_EM_RESTART': '1', 18:56:14 INFO - 'PAGER': '/bin/cat', 18:56:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:14 INFO - 'PWD': '/builds/slave/test', 18:56:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:14 INFO - 'SHELL': '/bin/bash', 18:56:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:56:14 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:56:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:14 INFO - 'USER': 'cltbld', 18:56:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:14 INFO - 'XPC_FLAGS': '0x0', 18:56:14 INFO - 'XPC_SERVICE_NAME': '0', 18:56:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:56:14 INFO - Cleaning up... 18:56:14 INFO - Return code: 0 18:56:14 INFO - Running post-action listener: _resource_record_post_action 18:56:14 INFO - Running post-action listener: _start_resource_monitoring 18:56:14 INFO - Starting resource monitoring. 18:56:14 INFO - [mozharness: 2017-06-02 01:56:14.628966Z] Finished create-virtualenv step (success) 18:56:14 INFO - [mozharness: 2017-06-02 01:56:14.629910Z] Running install step. 18:56:14 INFO - Running pre-action listener: _resource_record_pre_action 18:56:14 INFO - Running main action method: install 18:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:14 INFO - Reading from file tmpfile_stdout 18:56:14 INFO - Detecting whether we're running mozinstall >=1.0... 18:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:56:14 INFO - Reading from file tmpfile_stdout 18:56:14 INFO - Output received: 18:56:14 INFO - Usage: mozinstall [options] installer 18:56:14 INFO - Options: 18:56:14 INFO - -h, --help show this help message and exit 18:56:14 INFO - -d DEST, --destination=DEST 18:56:14 INFO - Directory to install application into. [default: 18:56:14 INFO - "/builds/slave/test"] 18:56:14 INFO - --app=APP Application being installed. [default: firefox] 18:56:14 INFO - mkdir: /builds/slave/test/build/application 18:56:14 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'] 18:56:14 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 18:56:36 INFO - Reading from file tmpfile_stdout 18:56:36 INFO - Output received: 18:56:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:56:36 INFO - Running post-action listener: _resource_record_post_action 18:56:36 INFO - [mozharness: 2017-06-02 01:56:36.265385Z] Finished install step (success) 18:56:36 INFO - [mozharness: 2017-06-02 01:56:36.265531Z] Running setup-mitmproxy step. 18:56:36 INFO - Running pre-action listener: _resource_record_pre_action 18:56:36 INFO - Running main action method: setup_mitmproxy 18:56:36 INFO - Skipping: mitmproxy is not required 18:56:36 INFO - Running post-action listener: _resource_record_post_action 18:56:36 INFO - [mozharness: 2017-06-02 01:56:36.266174Z] Finished setup-mitmproxy step (success) 18:56:36 INFO - [mozharness: 2017-06-02 01:56:36.266291Z] Running run-tests step. 18:56:36 INFO - Running pre-action listener: _resource_record_pre_action 18:56:36 INFO - Running main action method: run_tests 18:56:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:56:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:56:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:56:36 INFO - 2017-06-01 18:56:36.280 screenresolution[1893:9662] starting screenresolution argv=screenresolution get 18:56:36 INFO - 2017-06-01 18:56:36.283 screenresolution[1893:9662] Display 0: 1600x1200x32@60 18:56:36 INFO - 2017-06-01 18:56:36.289 screenresolution[1894:9665] starting screenresolution argv=screenresolution list 18:56:36 INFO - Available Modes on Display 0 18:56:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:56:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:56:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:56:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:56:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:56:36 INFO - Intel Iris: 18:56:36 INFO - Chipset Model: Intel Iris 18:56:36 INFO - Type: GPU 18:56:36 INFO - Bus: Built-In 18:56:36 INFO - VRAM (Dynamic, Max): 1536 MB 18:56:36 INFO - Vendor: Intel (0x8086) 18:56:36 INFO - Device ID: 0x0a2e 18:56:36 INFO - Revision ID: 0x0009 18:56:36 INFO - Displays: 18:56:36 INFO - Display: 18:56:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:56:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:56:36 INFO - Display Serial Number: 1600x1200 60 18:56:36 INFO - Main Display: Yes 18:56:36 INFO - Mirror: Off 18:56:36 INFO - Online: Yes 18:56:36 INFO - Rotation: Supported 18:56:36 INFO - Return code: 0 18:56:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:56:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:56:36 INFO - Python 2.7.3 18:56:36 INFO - Return code: 0 18:56:36 INFO - grabbing minidump binary from tooltool 18:56:36 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')]} 18:56:36 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 0x1040bdc60>, '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 0x103e52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e7bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:56:36 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 18:56:36 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 18:56:36 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 18:56:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:56:36 INFO - Return code: 0 18:56:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:56:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:56:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:56:36 INFO - ENV: RUST_BACKTRACE is now 1 18:56:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:56:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:56:36 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/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 18:56:36 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/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 18:56:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xzz4uMNAbo/Render', 18:56:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:36 INFO - 'HOME': '/Users/cltbld', 18:56:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:36 INFO - 'LOGNAME': 'cltbld', 18:56:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:56:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:36 INFO - 'MOZ_NO_REMOTE': '1', 18:56:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:56:36 INFO - 'NO_EM_RESTART': '1', 18:56:36 INFO - 'PAGER': '/bin/cat', 18:56:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:36 INFO - 'PWD': '/builds/slave/test', 18:56:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:56:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:36 INFO - 'RUST_BACKTRACE': '1', 18:56:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:56:36 INFO - 'SHELL': '/bin/bash', 18:56:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lBfT1ea31k/Listeners', 18:56:36 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 18:56:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:36 INFO - 'USER': 'cltbld', 18:56:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:36 INFO - 'XPC_FLAGS': '0x0', 18:56:36 INFO - 'XPC_SERVICE_NAME': '0', 18:56:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:36 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/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 18:56:36 INFO - mozversion application_buildid: 20170601180953 18:56:36 INFO - mozversion application_changeset: 877c3da58fa35bf4e58f2ac0cbcf59840702cc6e 18:56:36 INFO - mozversion application_display_name: Nightly 18:56:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:56:36 INFO - mozversion application_name: Firefox 18:56:36 INFO - mozversion application_remotingname: firefox 18:56:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:56:36 INFO - mozversion application_vendor: Mozilla 18:56:36 INFO - mozversion application_version: 55.0a1 18:56:36 INFO - mozversion platform_buildid: 20170601180953 18:56:36 INFO - mozversion platform_changeset: 877c3da58fa35bf4e58f2ac0cbcf59840702cc6e 18:56:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:56:36 INFO - mozversion platform_version: 55.0a1 18:56:36 INFO - using testdate: 1496368596 18:56:36 INFO - actual date: 1496368596 18:56:36 INFO - starting webserver on 'localhost:49407' 18:56:36 INFO - SUITE-START | Running 2 tests 18:56:36 INFO - TEST-START | dromaeo_css 18:56:36 INFO - operating with platform_type : mac_ 18:56:36 INFO - Initialising browser for dromaeo_css test... 18:56:36 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49407/getInfo.html -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpJCzrIn/profile 18:56:36 INFO - TEST-INFO | started process 1904 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49407/getInfo.html -foreground) 18:57:10 INFO - TEST-INFO | 1904: killed by SIGTERM 18:57:10 INFO - Browser initialized. 18:57:10 INFO - Running cycle 1/1 for dromaeo_css test... 18:57:10 INFO - TEST-INFO | started process 1911 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpJCzrIn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:12 INFO - PID 1911 | 2017-06-01 18:57:12.603 plugin-container[1912:10144] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x992f, name = 'com.apple.tsm.portname' 18:57:12 INFO - PID 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:12 INFO - PID 1911 | 2017-06-01 18:57:12.604 plugin-container[1912:10144] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 18:57:12 INFO - PID 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:12 INFO - PID 1911 | 2017-06-01 18:57:12.604 plugin-container[1912:10144] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:57:13 INFO - PID 1911 | 2017-06-01 18:57:13.332 plugin-container[1913:10189] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 18:57:13 INFO - PID 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:13 INFO - PID 1911 | 2017-06-01 18:57:13.333 plugin-container[1913:10189] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 18:57:13 INFO - PID 1911 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:57:13 INFO - PID 1911 | 2017-06-01 18:57:13.333 plugin-container[1913:10189] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:57:22 INFO - PID 1911 | RSS: Main: 261509120 18:57:22 INFO - PID 1911 | 19:00:24 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49407/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49407/tests/dromaeo/cssquery-ext.html) 19:00:26 INFO - PID 1911 | RSS: Main: 271765504 19:00:26 INFO - PID 1911 | 19:03:28 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49407/tests/dromaeo/cssquery-ext.html (next: http://localhost:49407/tests/dromaeo/cssquery-jquery.html) 19:03:28 INFO - PID 1911 | RSS: Main: 285634560 19:03:28 INFO - PID 1911 | 19:06:30 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49407/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49407/tests/dromaeo/cssquery-mootools.html) 19:06:31 INFO - PID 1911 | RSS: Main: 285609984 19:06:31 INFO - PID 1911 | 19:09:32 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49407/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49407/tests/dromaeo/cssquery-prototype.html) 19:09:33 INFO - PID 1911 | RSS: Main: 289366016 19:09:33 INFO - PID 1911 | 19:12:36 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49407/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49407/tests/dromaeo/cssquery-yui.html) 19:12:37 INFO - PID 1911 | RSS: Main: 289435648 19:12:37 INFO - PID 1911 | 19:15:38 INFO - PID 1911 | Cycle 1(1): loaded http://localhost:49407/tests/dromaeo/cssquery-yui.html (next: http://localhost:49407/tests/dromaeo/cssquery-dojo.html) 19:15:39 INFO - PID 1911 | RSS: Main: 290938880 19:15:39 INFO - PID 1911 | 19:15:39 INFO - PID 1911 | __start_tp_report 19:15:39 INFO - PID 1911 | _x_x_mozilla_page_load 19:15:39 INFO - PID 1911 | _x_x_mozilla_page_load_details 19:15:39 INFO - PID 1911 | |i|pagename|runs| 19:15:39 INFO - PID 1911 | |0;dojo.html;2751.54599491084;3019.6074510313656;3561.0741207286105;3594.694450971669;3620.67413932746;6124.41144964001;6151.138840562319;6227.782027629034;6230.190075290206;6242.375762423761;6132.8144489347815;6182;6193.876122477548;6272.9372706272925;6274.968625156868;4465.508794032654;5323.387810401795;5410.513053825155;5491.258680078178;5533.474319939599;5545.611807173499;5592.161175823619;5600.18797274395;5625.634333768312;5644.238027866227;4522.706024108423;5424.349078110652;5471.343438787331;5475.4524547545325;5500.559955203594;4434.667399945017;5519.116941289414;5541.6674999499755;5544.556435485151;5562.0266453370905;4499.820007199708;5476.452354764531;5508.476694713996;5511.889762204773;5519.613627046108;6117.8274048008725;6135.9079613805825;6174.38256174379;6189.845253868644;6225.252969643671;6707.228668703064;6735.090762747015;6739.854989181667;6741.426978706816;6758.729650813962;6829.795106146824;6836.213835408892;6839.110915580993;6847.726090956356;6851.725930962755;6203.348648391944;6262.3111457739615;6299.181106456132;6324.71538780756;6349.079383489368;5811.302643682785;6950.061741664912;6961.721531138698;6988.93011069893;6993.4055605274125;6290.370962903765;6463.612183269019;6489.3510648934725;6605.174353205849;6628.602283862983;6630.7679231226675;6729.966350168315;6769;6774.45804335652;6775.593464392152;6465.062565927914;6506.642134682544;6678.432333251728;6680.331966803281;6697.3637504437;6612.471002319803;6613.437857782046;6691.096701945273;6711.6979735912;6742.123523941954;5334.546563542065;5339.973300133553;5353.57171426285;5374.596905232123;5384.138537833927;4663.347131401607;4735.455422626374;4765.11845308619;4788.257820037899;4823.662343635982;5295.655782374133;6283.868903597305;6289.842753931097;6339.587926784837;6354.618722876642;6781.457483401415;6818.079559259437;6824.761133360308;6845.657717114124;6864.141982252218;6220.502359811204;6331.715072821734;6340.619562826245;6372.139761132281;6372.522060845363;5869.413058694096;5957.4638282553915;5996;6014.879702405928;6064.151018857364;6958.234594194533;6963.582185068912;7001.859962800613;7011.964940175267;7021.368076873106;6824.93175068243;6837;6870.896936546056;6871;6886.380225779704;6789.558678685869;6870.416014638811;6870.62211578368;6945.236024037251;6982.930170698228;5871.647701137946;6664.333566643297;6687.3647003536535;6728.7308507659145;6738.157730283715;6457.192850893638;6785.321467853175;6830.8633827324165;6840.0765896603325;6845.691943862438;5028.522290382408;5302.893942121059;5551.723103686094;5932.466078052996;6371.55399122057;5441.972790136024;6540.117084193763;6593.208814942238;6608.405243527914;6611.107500487373;6330.651814150266;6388.936110638834;6449.903251451138;6486.383793539606;6493.642849643315;6389.329120442472;6447.097406363207;6578.473722102316;6613.470922326106;6618.503612229199;6380.872382552416;6399.968000159969;6412.647304398303;6451.548391612542;6453.645049522322 19:15:39 INFO - PID 1911 | |1;ext.html;775.9612019399028;784.8430313937213;844.3625063077374;1054.752133248687;1272.8027155790855;38176.80911595445;38515.61484385155;38542.03644908879;38545.421818672745;38582.228355432875;38850.22299554007;39216.607833921655;39224;39259;39280;7802.648880800363;8177.836443271131;8390.790230244262;8426.747197584082;8430.241278284953;8353.457025293366;8488.618012189467;8504.957475212646;8519.3610479214;8557.315414766803;8463.74608761738;8479.618417171212;8539.274161696263;8539.487630742144;8543;8439.451435656723;8483.024452187985;8486.830263394728;8497.490150590984;8547.700830470965;8237.588120593946;8275.337972962148;8323.750287491384;8326.70856520025;8328.541930193836;37525.24949500995;37549.24901501982;37822.621773782186;37921;37943.4308485373;3445.2248244144866;3476.913077173091;3485.9477107843404;3488.110531814371;3493.9301213975577;30439.391212175633;30594.541081883788;30655.38689226203;30691.38617227643;30711.078667640006;2241.2827895073583;2449.020391843259;2668.3462551674966;2700.4329090890938;2702.3919618085774;241398;247964;252706;272969.999999998;282402.1759782396;47614.28578571324;47944.56166314946;47950.520494794604;47958.28062579064;48264.27603585949;49673;49964.75017624888;50077.49922500801;53815.46184538105;54467.72766136144;12122.878771212176;14648.487302944346;14782.926085369505;14874.256287185597;14900;11911.58309459182;11955.581554645545;12048.337341446304;12053;12054.879451205376;13212.735745285041;14056.297185140702;14066.804321632775;14099.71800563983;14104.012719109563;6091.543134264948;6114.816555503342;6152.077188421773;6165.475934545524;6182;941.5668792355456;981.6711401680374;1064.4571268653042;1069.9129325974857;1084.5824357622373;23343.649845252334;24334.148304809245;24629.01483940658;24712.25863224106;24810.875945620155;970.4565443351746;978.4373984958677;1119.2612875502293;1168.4391492083848;1179.2334982261598;10937.945310273397;12062.733412991685;12540.749185016248;12607.558735444216;12681.30252836103;326709;328889;329556;329722;329841;53936.651583709936;54128.85775851288;54300;54415.45584544183;54565.72717136389;1525.2907398059956;1539.7844301797534;1571.0966194438245;1574.3781206423628;1577.8579927806552;17708.291668333124;24031.399215019763;24112.397190069685;24211.273661790176;24363.878180608983;37786;37791.05522361963;37872.24255514929;37915.052123697125;37933.24133517259;268606;271562;271564;271600;272209.6389518119;28877.71122288834;30801.921932733145;34360.48459273063;35739.64260357467;35942;43212;44883.55116448794;44918.550814491435;45364.31953520766;46841;25425.364365891;30325;30933.226669332547;31348.37303253968;31913.36173276475;29839;30837.691623084378;31712.682873170972;31842.84078579685;32374.676253237165;37106.07234819151;37360.626393735714;38272;38283.2343353137;38316.61683383131;25623.487530249662;32067.839660801546;32670.509942350407;32675.34649306953;32831.507527387585;20104.39686809398;23065.65401518945;24766;25238.873805630854;25397.746022539537 19:15:39 INFO - PID 1911 | |2;jquery.html;378.56465065175047;413.3427849718948;427.2928303657448;438.94433886502964;456.3337527210273;21946.902654867252;22733.204337848198;22757.544849103007;22769;22810;23469.530609387803;24511.509769804594;24634.260972170865;24666.876665616648;24683.382915427122;6141.877162456748;6263.498920086405;6284.968575157141;6285.560010799246;6295.055741638745;6186.412290832363;6197.75208991642;6248.6875656217235;6275.2469703635625;6277.623342599436;6144.5084393248435;6184.56708030438;6217.285012223584;6221.284552276501;6431.260405053432;6776.085228494164;6893.862122757542;6925.342092501204;6933.4106600938985;6934.895976560356;6733.192016957947;6736.2590115087305;6779.72881084756;6787.932120678779;6795.558288711268;30410;31017.37965240705;31201.531977020364;31227.531587026213;31297.217569560715;2236.3291012696127;2282.5777231212114;2301.505176387068;2303.090279339668;2305;19374.70937935932;19750.50623734418;19883.90058049715;19952.301669441644;20023.998800060086;2315.513742114158;2331.9067237310655;2377.405648587853;2383.97616023841;2406.7713567211085;147988;158691;172661;180054.09972950182;183959;19144.808551914582;19643.116059777345;24640.876795615906;26202;26330.6050409244;43813.780931095775;43877.341839872424;43901.341479877825;43934.56065439369;43994.78002609967;11198.21612487118;11296.265742726697;11341.773164536664;11425.08599312053;11538.538458461735;8983.865242021375;8985.955070224738;9036;9098.727038188732;9099.772505687279;12004;12189.916378013288;12195.573154939711;12305.384730763426;12320.260784352968;4122.896927576775;4892.412910450768;4983.551480366785;5000.224965130411;5004.974975125174;803.2650125135552;876.9914598212107;968.651285537206;974.7611835100446;997.0683215024463;19701.704474432896;21680;21719;21893.343199704035;22000.88999555024;997.2919227348664;998.5606333213362;1023.1712313026472;1037.9429131397847;1038.1642777563982;10344.948275258575;10791.352518848895;11219.719507012227;11300.265482743594;11308;540545;545810;546453;548681;549246.2537687366;133430.33284833515;146588.26705866403;148783;149254.7611785824;149295;1150.1316506037888;1154.8094564396781;1222.6270987348767;1236.6042866282883;1239.702471406856;19583.412497625093;23129.537409251385;23440.648390274502;23510.294691158608;23743.762562374155;64768;65021.374465638735;65028.699426012165;65159.1456496214;65374.01938970824;83107.75338369809;83242;83335.41530297665;83349.74778404838;83592.16407835844;10210.693679189637;10223.59105635796;10335;10366.948165259428;10371.533281002223;17361.826381736526;17411;17427.82572174262;17589.504892084315;17710.91144544269;13990.020698551003;13994.720105598035;13998.370073346927;14193.787093193818;14219.786703199254;12305.93847030759;12590.24458532491;12659.556915507914;12884.549040783528;12946.546870859474;14167.149971001772;14307.141571505743;14430.567082987527;14594.562163135122;14598.92700536533;10158.492075396349;10179.949100254747;10238.385696858213;10279.94860025695;10282.588696452058;13770.104943178661;13786.517471888435;13831.239281839595;13855.445782168601;14006.159630422208 19:15:39 INFO - PID 1911 | |3;mootools.html;1354.2551596621859;1423.5088894331452;1423.9216843073634;1431.3058166789112;1435.1460880775937;11499.655010349692;11873.703157421069;12031.879681203185;12033;12231.816522752144;11650.41747912603;11853.940730296357;11865.466054027567;11870.703232419193;11876.881231187686;2532.7467253274617;2585.1985884375904;2588.2364702412833;2589.753973372536;2594.44219492809;2515.924522264335;2550.2094350751236;2555.6166575013713;2559.731228221038;2565.7819085377764;2519.3827512259522;2541.3646588352913;2547.223096955428;2550.0947163756878;2557.1050132453584;2491.626256061596;2509.949801003979;2519.382751225943;2524.129175434472;2526.5325914705836;2466.666999955001;2501.3246423465744;2502.799776017914;2560.7055188653358;2561.3596600849787;11793.233439826385;11844.703882402922;11879.168458207929;11891.167618266725;11922.10584206188;6441.227052753699;6635.601863888182;6640.833979150511;6703.530752847302;6708.19501659804;4089.5092588889224;4222.1766755482695;4304.225239456897;4305.342443159406;4316.870493885157;1222.6637674639455;1246.738184981155;1262.3814330977755;1274.082660484459;1278.188350397495;15446.768298475532;15787.052776833427;16622.916885415616;16683.16584170799;16692.499225023268;5546.417626149277;5601.271834661549;5786.623869448473;5804.448577385142;5807.186993820868;12318.384080795924;12337.629871103882;12346.567870124503;12349.32078735678;12355.567555135707;5794.594378393472;6247.812915546032;6413.134226879405;6468.512242184324;6552.836179095465;5941.940580594139;6083.692006218595;6116.449519543263;6161.137440758297;6247.250329960434;4336.392904993303;4414.09511050234;4428.3800267962515;4457.331400289982;4484.461864576207;1277.7061275906594;1284.524725851441;1292.2956988441322;1303.517698451579;1307.8038294255937;703.9788806335819;780.4965797060977;791.5883740454931;796.1799346672937;804.0632662947601;5352.785888564492;5624.67820062285;6033.698315084316;6341.524385671093;6357.745690172342;442.30558023902165;609.984750381244;610.9419605137505;906.7415786500816;973.245734555725;4221.978890105591;4500.527444618333;4625.306204069417;4656.045510670305;4674.509176536482;12457.439415226336;13655.248961307025;14555.272236388138;15301.311440985182;15377.461788837338;14771.556853294418;15096;15194.164320962233;15342.539723808304;15380.692386152214;8432.915670843337;8558.01582817984;8611.880455540864;8632.395732298679;8636.524991125549;6500.609963402022;7120.456378244427;7138.678759455733;7203.387712044535;7342.155652099863;10910.781784364426;13106.410211540313;13121.950243980267;13129.671758205739;13176.407061682055;12653.87346126527;13210.207387556777;13387.196768193939;13436.596902092953;13452.663683407993;7690.000299961193;7885.290323870649;8331.583420829056;8463.830723385621;8999.595018224327;8878.95560522219;10147.238957078396;10536.315139515908;10640.935906409297;10663.36019838781;8778.297736181217;8918.910810892068;8956.193942545202;9168.037356077515;9175.862362064441;8823.823523529365;8872.600732967161;8952.820943580962;8988.011318754801;9121.863172052557;7769.6892124316655;8025.277725004544;8132.146124656826;8134.593270336341;8157.428979971345;4773;5090.389153301479;6115.205023347115;6209.348018457997;6225.31521532622;5924.644521328734;6006.819795406145;6083.904897118561;6190.226221722285;6321.557490975588 19:15:39 INFO - PID 1911 | |4;prototype.html;337.8783637890359;351.95776506819175;404.8137856585972;413.4748868936452;415.424636877924;6193.442590166884;6380.755752628239;6419.743210271584;6441.581297215677;6471.252761754329;6059.515238780898;6084.269887613482;6140.938590614092;6208.379162083789;6215.440610345068;3747.3442147624123;4619.399478067847;4621.329907163459;4623.352730617717;4626.65300102492;3774.282886251617;3988.302047141753;4505.77471126444;4641.744704041276;4663.930041049387;4165.812538435763;4213.410122582841;4600.493945665974;4600.884977875547;4610.4467463904375;3780.5633859133354;4525.886852828673;4587.977060114695;4598.8620341389815;4633.62930965522;3285.83570821458;3968.1071758854314;3991.1219531702977;3996.4005399190064;3997.620226078518;5282.788688452457;6136.2636483622255;6143.416375444371;6165.075238714184;6190.1952746142715;5435.076037073707;5509.972450137764;5514;5514.310711161105;5523.972380138114;5598.636088654224;5674.8650269945765;6648.601083934979;6702.290915816484;6718.2945790691765;3864.5507934524553;4165.333546632524;4434.846939795644;4530.207213737583;4546.681732278742;6791.320867913169;6949.5135340526185;6998.265182155902;7108.431325493948;7195.280471952815;3790.639889210521;3987.80060996949;4067.654249388776;4563.429571303587;4579.977100114478;3858.9035274118373;4651.697639653412;4653.115907977473;4653.418322709661;4682.391289132459;5118.97440512795;5192.402873669576;5238.7904483820985;5339.359276886798;5446.400895901447;6083.878322433527;6569.6203797203125;6686.264510903797;6693.2972037935315;6711.630860302633;1594.4977332140338;1605.3097168217778;1607.6463178100798;1612.935482580707;1619.2146808797725;951.4529145741227;995.4027583449872;1006.3509036671452;1066.898644628759;1070.7055559720984;5177.301064356339;6120.755169793248;6283.711839072979;6305.311392835151;6312.810615681537;5884.499817515559;6750.864982700318;6865.485088618374;6924.549904256207;6968.581885086911;5521.5306698931045;6232.75068997235;6297.418058595954;6297.4332310091395;6313.842153946096;3076.7384772294604;3425.8629654814026;3580.1944562473236;3644.653757892996;3648.5074514940675;5420.403755586883;7167.605781682058;7171.812597157917;7211.89182162268;7237.276272372721;6525.543211975116;7128.821779455555;7141.392981596518;7142.785716428411;7154.821129471805;6576.671166441659;6786.287439818847;7157.284271573009;7164.605946672983;7184.892226616605;6825.965870170618;6918.377346038881;6921.342472465108;6921.861562768616;6947.65261736901;5909.497692695997;6019.909701354396;6788;6814.795556133324;6818.863622727418;6063;6067.998780201394;6590.890914907375;6727.663616819042;7134.32223938725;3825.6748176405317;3986.661133803659;4532.954670453253;4575.611073058827;4605.4933957264;5511.200875873056;5854.648721076749;6047.244094488189;6580.9141491653345;6837.521373503807;6630.701618427279;6651.201855777338;6661.267260601233;6678.365555272436;6692.665366731741;6742.471573160065;6754.18949726036;6756.756756756882;6763.188417389944;6782.423494003065;6556.508261880474;6556.541042127005;6558.081868538313;6574.323547495358;6623.701933412912;6681.164854393201;6682.431993280626;6688.77943512538;6716.496262780409;6751.358620931003;5448.645838020516;6276.838784824639;6517.9348206517325;6520.771772987922;6568.310327415553 19:15:39 INFO - PID 1911 | |5;yui.html;654.5450911616429;688.5249178067135;706.4560288577792;775.3603277296232;816.918308169183;13475.797863032063;13994.860051399482;14137.434502619895;16041.518754437371;16056.598585035379;16376.344946202167;17806.19872105755;18276.725849112274;18334.724979125323;18348.256915593032;784.4861615641764;970.0590427285522;975.1223898491344;992.7766252593145;994.5524513968707;1010.9898901011006;1021.4586269277295;1024.8565200871847;1026.4354604967275;1026.5534492495751;956.4834989105874;1011.7521207304221;1064.9510232421098;1066.1470823341335;1068.0174239699495;1070.0797314309686;1071.0949247885524;1073.2648136026846;1073.7315671082229;1073.8013467508536;769.1616138409161;771.6488997506121;773.6595897805004;774.3688893551737;776.953382797034;13970.860291397057;15477;15951.521454356387;16148.919255403647;16297.429589964413;16983.405580804614;17192.054437006085;17433.38983135597;17541.386051488138;17608.207630656652;11392.943035284854;13828.170309781446;13898.93050534751;13922.30388480582;13934.024618276724;3484.4424892017155;3495.4582039783872;3504.859805607798;3506.2286297014616;3508.0002199373057;117319.41340293329;145247.27376363156;146806;147315;148977;13497.257650829299;13501.66245843842;13558.389872455764;13656.180629162282;13715.519956801463;26494.205173844817;26496.735032649427;26920.192394228205;26967.730322696916;26976.325591859968;7117.501774875813;7254.738668834253;7270.672819723125;7311.744088956861;7318.890216646754;2651.376926422289;3013;3468.7745296555645;3526.947095793565;3530.8411121499594;4180.393842892763;4189.4344263524645;4251.426057482262;4259.190753756776;4267.1679022590915;843.7173546861748;846.716350022737;850.7617866997251;858.3133493205411;865.7446053414258;641.7625478572958;647.119916912998;659.8251463362213;670.8691805098057;681.7796144900573;8433.831323373499;10951.56193752241;11381.146414018982;11660.708482288012;11982.940085299517;638.6551262318296;639.341478277375;642.0818229931243;643.707113263464;665.7536711416807;5251.789928402897;5269.920951185735;5294.152935530296;5364.517193452608;5465.59008074396;140984;141327.29336353458;143898;144399.5560044407;148002;119142;119162.80837191691;120490.3975480117;120591;121196.78803212031;79227.60386198148;102520;103404.9999999985;103443.4827825871;104603;9526.61893524279;9998.550065246936;10305.156050781607;10572.788544228919;10804.621838235938;44584;44991.55008450004;45304.77347613373;45842;45870;52361;53626.659333516975;61615.69192154011;61744.69127654333;62507.687461562404;13355.065145440114;13739.793903091262;13913.72172556523;14119.223442710749;14127.505537306144;14324.068935519575;14655.340509676877;14776.261186940395;14851.554453366416;14862.554123376316;13966.930165349107;16744.995300282022;16970.40603578869;18348.715589908577;18353.72469412933;14343.928280358532;15171.317290722165;17424;17593;18203.726944095586;17637.559061023578;17864.91067544654;17967.730484042488;18116.365927192484;18157.27370905149;9287.76780580491;11886.34625095628;11909.880901190878;12253.509859605516;12443.875561244633;12957.935210323889;13705;13794.65513362174;13829.51596694111;13884.028118031641 19:15:39 INFO - PID 1911 | __end_tp_report 19:15:39 INFO - PID 1911 | __start_cc_report 19:15:39 INFO - PID 1911 | _x_x_mozilla_cycle_collect,456 19:15:39 INFO - PID 1911 | __end_cc_report 19:15:39 INFO - PID 1911 | __startTimestamp1496369739083__endTimestamp 19:15:39 INFO - PID 1911 | 19:15:39 INFO - PID 1911 | ------- Summary: start ------- 19:15:39 INFO - PID 1911 | Number of tests: 6 19:15:39 INFO - PID 1911 | 19:15:39 INFO - PID 1911 | [#0] dojo.html Cycles:165 Average:6136.89 Median:6371.55 stddev:805.17 (12.6%) stddev-sans-first:762.59 19:15:39 INFO - PID 1911 | Values: 2751.5 3019.6 3561.1 3594.7 3620.7 6124.4 6151.1 6227.8 6230.2 6242.4 6132.8 6182.0 6193.9 6272.9 6275.0 4465.5 5323.4 5410.5 5491.3 5533.5 5545.6 5592.2 5600.2 5625.6 5644.2 4522.7 5424.3 5471.3 5475.5 5500.6 4434.7 5519.1 5541.7 5544.6 5562.0 4499.8 5476.5 5508.5 5511.9 5519.6 6117.8 6135.9 6174.4 6189.8 6225.3 6707.2 6735.1 6739.9 6741.4 6758.7 6829.8 6836.2 6839.1 6847.7 6851.7 6203.3 6262.3 6299.2 6324.7 6349.1 5811.3 6950.1 6961.7 6988.9 6993.4 6290.4 6463.6 6489.4 6605.2 6628.6 6630.8 6730.0 6769.0 6774.5 6775.6 6465.1 6506.6 6678.4 6680.3 6697.4 6612.5 6613.4 6691.1 6711.7 6742.1 5334.5 5340.0 5353.6 5374.6 5384.1 4663.3 4735.5 4765.1 4788.3 4823.7 5295.7 6283.9 6289.8 6339.6 6354.6 6781.5 6818.1 6824.8 6845.7 6864.1 6220.5 6331.7 6340.6 6372.1 6372.5 5869.4 5957.5 5996.0 6014.9 6064.2 6958.2 6963.6 7001.9 7012.0 7021.4 6824.9 6837.0 6870.9 6871.0 6886.4 6789.6 6870.4 6870.6 6945.2 6982.9 5871.6 6664.3 6687.4 6728.7 6738.2 6457.2 6785.3 6830.9 6840.1 6845.7 5028.5 5302.9 5551.7 5932.5 6371.6 5442.0 6540.1 6593.2 6608.4 6611.1 6330.7 6388.9 6449.9 6486.4 6493.6 6389.3 6447.1 6578.5 6613.5 6618.5 6380.9 6400.0 6412.6 6451.5 6453.6 19:15:39 INFO - PID 1911 | 19:15:39 INFO - PID 1911 | [#1] ext.html Cycles:180 Average:44108.17 Median:18902.85 stddev:75524.14 (399.5%) stddev-sans-first:75665.92 19:15:39 INFO - PID 1911 | Values: 776.0 784.8 844.4 1054.8 1272.8 38176.8 38515.6 38542.0 38545.4 38582.2 38850.2 39216.6 39224.0 39259.0 39280.0 7802.6 8177.8 8390.8 8426.7 8430.2 8353.5 8488.6 8505.0 8519.4 8557.3 8463.7 8479.6 8539.3 8539.5 8543.0 8439.5 8483.0 8486.8 8497.5 8547.7 8237.6 8275.3 8323.8 8326.7 8328.5 37525.2 37549.2 37822.6 37921.0 37943.4 3445.2 3476.9 3485.9 3488.1 3493.9 30439.4 30594.5 30655.4 30691.4 30711.1 2241.3 2449.0 2668.3 2700.4 2702.4 241398.0 247964.0 252706.0 272970.0 282402.2 47614.3 47944.6 47950.5 47958.3 48264.3 49673.0 49964.8 50077.5 53815.5 54467.7 12122.9 14648.5 14782.9 14874.3 14900.0 11911.6 11955.6 12048.3 12053.0 12054.9 13212.7 14056.3 14066.8 14099.7 14104.0 6091.5 6114.8 6152.1 6165.5 6182.0 941.6 981.7 1064.5 1069.9 1084.6 23343.6 24334.1 24629.0 24712.3 24810.9 970.5 978.4 1119.3 1168.4 1179.2 10937.9 12062.7 12540.7 12607.6 12681.3 326709.0 328889.0 329556.0 329722.0 329841.0 53936.7 54128.9 54300.0 54415.5 54565.7 1525.3 1539.8 1571.1 1574.4 1577.9 17708.3 24031.4 24112.4 24211.3 24363.9 37786.0 37791.1 37872.2 37915.1 37933.2 268606.0 271562.0 271564.0 271600.0 272209.6 28877.7 30801.9 34360.5 35739.6 35942.0 43212.0 44883.6 44918.6 45364.3 46841.0 25425.4 30325.0 30933.2 31348.4 31913.4 29839.0 30837.7 31712.7 31842.8 32374.7 37106.1 37360.6 38272.0 38283.2 38316.6 25623.5 32067.8 32670.5 32675.3 32831.5 20104.4 23065.7 24766.0 25238.9 25397.7 19:15:39 INFO - PID 1911 | 19:15:39 INFO - PID 1911 | [#2] jquery.html Cycles:180 Average:38881.79 Median:12717.27 stddev:93472.03 (735.0%) stddev-sans-first:93689.54 19:15:39 INFO - PID 1911 | Values: 378.6 413.3 427.3 438.9 456.3 21946.9 22733.2 22757.5 22769.0 22810.0 23469.5 24511.5 24634.3 24666.9 24683.4 6141.9 6263.5 6285.0 6285.6 6295.1 6186.4 6197.8 6248.7 6275.2 6277.6 6144.5 6184.6 6217.3 6221.3 6431.3 6776.1 6893.9 6925.3 6933.4 6934.9 6733.2 6736.3 6779.7 6787.9 6795.6 30410.0 31017.4 31201.5 31227.5 31297.2 2236.3 2282.6 2301.5 2303.1 2305.0 19374.7 19750.5 19883.9 19952.3 20024.0 2315.5 2331.9 2377.4 2384.0 2406.8 147988.0 158691.0 172661.0 180054.1 183959.0 19144.8 19643.1 24640.9 26202.0 26330.6 43813.8 43877.3 43901.3 43934.6 43994.8 11198.2 11296.3 11341.8 11425.1 11538.5 8983.9 8986.0 9036.0 9098.7 9099.8 12004.0 12189.9 12195.6 12305.4 12320.3 4122.9 4892.4 4983.6 5000.2 5005.0 803.3 877.0 968.7 974.8 997.1 19701.7 21680.0 21719.0 21893.3 22000.9 997.3 998.6 1023.2 1037.9 1038.2 10344.9 10791.4 11219.7 11300.3 11308.0 540545.0 545810.0 546453.0 548681.0 549246.3 133430.3 146588.3 148783.0 149254.8 149295.0 1150.1 1154.8 1222.6 1236.6 1239.7 19583.4 23129.5 23440.6 23510.3 23743.8 64768.0 65021.4 65028.7 65159.1 65374.0 83107.8 83242.0 83335.4 83349.7 83592.2 10210.7 10223.6 10335.0 10366.9 10371.5 17361.8 17411.0 17427.8 17589.5 17710.9 13990.0 13994.7 13998.4 14193.8 14219.8 12305.9 12590.2 12659.6 12884.5 12946.5 14167.1 14307.1 14430.6 14594.6 14598.9 10158.5 10179.9 10238.4 10279.9 10282.6 13770.1 13786.5 13831.2 13855.4 14006.2 19:15:39 INFO - PID 1911 | 19:15:39 INFO - PID 1911 | [#3] mootools.html Cycles:180 Average:7040.61 Median:4222.08 stddev:4435.95 (105.1%) stddev-sans-first:4427.82 19:15:39 INFO - PID 1911 | Values: 1354.3 1423.5 1423.9 1431.3 1435.1 11499.7 11873.7 12031.9 12033.0 12231.8 11650.4 11853.9 11865.5 11870.7 11876.9 2532.7 2585.2 2588.2 2589.8 2594.4 2515.9 2550.2 2555.6 2559.7 2565.8 2519.4 2541.4 2547.2 2550.1 2557.1 2491.6 2509.9 2519.4 2524.1 2526.5 2466.7 2501.3 2502.8 2560.7 2561.4 11793.2 11844.7 11879.2 11891.2 11922.1 6441.2 6635.6 6640.8 6703.5 6708.2 4089.5 4222.2 4304.2 4305.3 4316.9 1222.7 1246.7 1262.4 1274.1 1278.2 15446.8 15787.1 16622.9 16683.2 16692.5 5546.4 5601.3 5786.6 5804.4 5807.2 12318.4 12337.6 12346.6 12349.3 12355.6 5794.6 6247.8 6413.1 6468.5 6552.8 5941.9 6083.7 6116.4 6161.1 6247.3 4336.4 4414.1 4428.4 4457.3 4484.5 1277.7 1284.5 1292.3 1303.5 1307.8 704.0 780.5 791.6 796.2 804.1 5352.8 5624.7 6033.7 6341.5 6357.7 442.3 610.0 610.9 906.7 973.2 4222.0 4500.5 4625.3 4656.0 4674.5 12457.4 13655.2 14555.3 15301.3 15377.5 14771.6 15096.0 15194.2 15342.5 15380.7 8432.9 8558.0 8611.9 8632.4 8636.5 6500.6 7120.5 7138.7 7203.4 7342.2 10910.8 13106.4 13122.0 13129.7 13176.4 12653.9 13210.2 13387.2 13436.6 13452.7 7690.0 7885.3 8331.6 8463.8 8999.6 8879.0 10147.2 10536.3 10640.9 10663.4 8778.3 8918.9 8956.2 9168.0 9175.9 8823.8 8872.6 8952.8 8988.0 9121.9 7769.7 8025.3 8132.1 8134.6 8157.4 4773.0 5090.4 6115.2 6209.3 6225.3 5924.6 6006.8 6083.9 6190.2 6321.6 19:15:39 INFO - PID 1911 | 19:15:39 INFO - PID 1911 | [#4] prototype.html Cycles:180 Average:5363.44 Median:6102.51 stddev:1716.38 (28.1%) stddev-sans-first:1679.23 19:15:39 INFO - PID 1911 | Values: 337.9 352.0 404.8 413.5 415.4 6193.4 6380.8 6419.7 6441.6 6471.3 6059.5 6084.3 6140.9 6208.4 6215.4 3747.3 4619.4 4621.3 4623.4 4626.7 3774.3 3988.3 4505.8 4641.7 4663.9 4165.8 4213.4 4600.5 4600.9 4610.4 3780.6 4525.9 4588.0 4598.9 4633.6 3285.8 3968.1 3991.1 3996.4 3997.6 5282.8 6136.3 6143.4 6165.1 6190.2 5435.1 5510.0 5514.0 5514.3 5524.0 5598.6 5674.9 6648.6 6702.3 6718.3 3864.6 4165.3 4434.8 4530.2 4546.7 6791.3 6949.5 6998.3 7108.4 7195.3 3790.6 3987.8 4067.7 4563.4 4580.0 3858.9 4651.7 4653.1 4653.4 4682.4 5119.0 5192.4 5238.8 5339.4 5446.4 6083.9 6569.6 6686.3 6693.3 6711.6 1594.5 1605.3 1607.6 1612.9 1619.2 951.5 995.4 1006.4 1066.9 1070.7 5177.3 6120.8 6283.7 6305.3 6312.8 5884.5 6750.9 6865.5 6924.5 6968.6 5521.5 6232.8 6297.4 6297.4 6313.8 3076.7 3425.9 3580.2 3644.7 3648.5 5420.4 7167.6 7171.8 7211.9 7237.3 6525.5 7128.8 7141.4 7142.8 7154.8 6576.7 6786.3 7157.3 7164.6 7184.9 6826.0 6918.4 6921.3 6921.9 6947.7 5909.5 6019.9 6788.0 6814.8 6818.9 6063.0 6068.0 6590.9 6727.7 7134.3 3825.7 3986.7 4533.0 4575.6 4605.5 5511.2 5854.6 6047.2 6580.9 6837.5 6630.7 6651.2 6661.3 6678.4 6692.7 6742.5 6754.2 6756.8 6763.2 6782.4 6556.5 6556.5 6558.1 6574.3 6623.7 6681.2 6682.4 6688.8 6716.5 6751.4 5448.6 6276.8 6517.9 6520.8 6568.3 19:15:39 INFO - PID 1911 | 19:15:39 INFO - PID 1911 | [#5] yui.html Cycles:180 Average:24557.20 Median:17600.60 stddev:38432.69 (218.4%) stddev-sans-first:38498.60 19:15:39 INFO - PID 1911 | Values: 654.5 688.5 706.5 775.4 816.9 13475.8 13994.9 14137.4 16041.5 16056.6 16376.3 17806.2 18276.7 18334.7 18348.3 784.5 970.1 975.1 992.8 994.6 1011.0 1021.5 1024.9 1026.4 1026.6 956.5 1011.8 1065.0 1066.1 1068.0 1070.1 1071.1 1073.3 1073.7 1073.8 769.2 771.6 773.7 774.4 777.0 13970.9 15477.0 15951.5 16148.9 16297.4 16983.4 17192.1 17433.4 17541.4 17608.2 11392.9 13828.2 13898.9 13922.3 13934.0 3484.4 3495.5 3504.9 3506.2 3508.0 117319.4 145247.3 146806.0 147315.0 148977.0 13497.3 13501.7 13558.4 13656.2 13715.5 26494.2 26496.7 26920.2 26967.7 26976.3 7117.5 7254.7 7270.7 7311.7 7318.9 2651.4 3013.0 3468.8 3526.9 3530.8 4180.4 4189.4 4251.4 4259.2 4267.2 843.7 846.7 850.8 858.3 865.7 641.8 647.1 659.8 670.9 681.8 8433.8 10951.6 11381.1 11660.7 11982.9 638.7 639.3 642.1 643.7 665.8 5251.8 5269.9 5294.2 5364.5 5465.6 140984.0 141327.3 143898.0 144399.6 148002.0 119142.0 119162.8 120490.4 120591.0 121196.8 79227.6 102520.0 103405.0 103443.5 104603.0 9526.6 9998.6 10305.2 10572.8 10804.6 44584.0 44991.6 45304.8 45842.0 45870.0 52361.0 53626.7 61615.7 61744.7 62507.7 13355.1 13739.8 13913.7 14119.2 14127.5 14324.1 14655.3 14776.3 14851.6 14862.6 13966.9 16745.0 16970.4 18348.7 18353.7 14343.9 15171.3 17424.0 17593.0 18203.7 17637.6 17864.9 17967.7 18116.4 18157.3 9287.8 11886.3 11909.9 12253.5 12443.9 12957.9 13705.0 13794.7 13829.5 13884.0 19:15:39 INFO - PID 1911 | -------- Summary: end -------- 19:15:39 INFO - PID 1911 | 19:15:39 INFO - TEST-INFO | 1911: exit 0 19:15:39 INFO - TEST-OK | dromaeo_css | took 1142771ms 19:15:39 INFO - TEST-START | kraken 19:15:39 INFO - operating with platform_type : mac_ 19:15:39 INFO - Initialising browser for kraken test... 19:15:39 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49407/getInfo.html -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmp2l4hq1/profile 19:15:39 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49407/getInfo.html -foreground) 19:16:13 INFO - TEST-INFO | 1923: killed by SIGTERM 19:16:13 INFO - Browser initialized. 19:16:13 INFO - Running cycle 1/1 for kraken test... 19:16:13 INFO - TEST-INFO | started process 1927 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmp2l4hq1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:16:15 INFO - PID 1927 | 2017-06-01 19:16:15.442 plugin-container[1928:12718] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 19:16:15 INFO - PID 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:15 INFO - PID 1927 | 2017-06-01 19:16:15.442 plugin-container[1928:12718] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c27, name = 'com.apple.CFPasteboardClient' 19:16:15 INFO - PID 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:15 INFO - PID 1927 | 2017-06-01 19:16:15.442 plugin-container[1928:12718] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:16:15 INFO - PID 1927 | 2017-06-01 19:16:15.997 plugin-container[1929:12763] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 19:16:15 INFO - PID 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:15 INFO - PID 1927 | 2017-06-01 19:16:15.997 plugin-container[1929:12763] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4913, name = 'com.apple.CFPasteboardClient' 19:16:15 INFO - PID 1927 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:16:15 INFO - PID 1927 | 2017-06-01 19:16:15.997 plugin-container[1929:12763] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:16:25 INFO - PID 1927 | RSS: Main: 268771328 19:16:25 INFO - PID 1927 | 19:16:28 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49407/tests/kraken/driver.html?testName=audio-beat-detection) 19:16:29 INFO - PID 1927 | RSS: Main: 276254720 19:16:29 INFO - PID 1927 | 19:16:31 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49407/tests/kraken/driver.html?testName=audio-dft) 19:16:32 INFO - PID 1927 | RSS: Main: 285925376 19:16:32 INFO - PID 1927 | 19:16:35 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49407/tests/kraken/driver.html?testName=audio-fft) 19:16:36 INFO - PID 1927 | RSS: Main: 285954048 19:16:36 INFO - PID 1927 | 19:16:38 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49407/tests/kraken/driver.html?testName=audio-oscillator) 19:16:39 INFO - PID 1927 | RSS: Main: 286691328 19:16:39 INFO - PID 1927 | 19:16:41 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49407/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:16:41 INFO - PID 1927 | RSS: Main: 287109120 19:16:41 INFO - PID 1927 | 19:16:44 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49407/tests/kraken/driver.html?testName=imaging-darkroom) 19:16:45 INFO - PID 1927 | RSS: Main: 296038400 19:16:45 INFO - PID 1927 | 19:16:48 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49407/tests/kraken/driver.html?testName=imaging-desaturate) 19:16:49 INFO - PID 1927 | RSS: Main: 296103936 19:16:49 INFO - PID 1927 | 19:16:52 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49407/tests/kraken/driver.html?testName=json-parse-financial) 19:16:52 INFO - PID 1927 | RSS: Main: 295034880 19:16:52 INFO - PID 1927 | 19:16:54 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49407/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:16:55 INFO - PID 1927 | RSS: Main: 294903808 19:16:55 INFO - PID 1927 | 19:16:56 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49407/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:16:57 INFO - PID 1927 | RSS: Main: 294809600 19:16:57 INFO - PID 1927 | 19:16:59 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49407/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:17:00 INFO - PID 1927 | RSS: Main: 294305792 19:17:00 INFO - PID 1927 | 19:17:03 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49407/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:17:04 INFO - PID 1927 | RSS: Main: 295018496 19:17:04 INFO - PID 1927 | 19:17:07 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49407/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:17:07 INFO - PID 1927 | RSS: Main: 294653952 19:17:07 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | Cycle 1(1): loaded http://localhost:49407/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49407/tests/kraken/driver.html?testName=ai-astar) 19:17:09 INFO - PID 1927 | RSS: Main: 296443904 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | __start_tp_report 19:17:09 INFO - PID 1927 | _x_x_mozilla_page_load 19:17:09 INFO - PID 1927 | _x_x_mozilla_page_load_details 19:17:09 INFO - PID 1927 | |i|pagename|runs| 19:17:09 INFO - PID 1927 | |0;ai-astar;101;113;114;126;170;98;170;171;121;120 19:17:09 INFO - PID 1927 | |1;audio-beat-detection;133;114;109;150;110;112;109;113;145;139 19:17:09 INFO - PID 1927 | |2;audio-dft;204;199;195;211;254;261;211;290;243;196 19:17:09 INFO - PID 1927 | |3;audio-fft;86;61;63;84;60;51;76;52;73;49 19:17:09 INFO - PID 1927 | |4;audio-oscillator;84;70;85;72;69;66;85;69;73;95 19:17:09 INFO - PID 1927 | |5;imaging-gaussian-blur;137;94;137;92;90;89;105;136;102;137 19:17:09 INFO - PID 1927 | |6;imaging-darkroom;97;98;95;97;96;97;97;97;96;97 19:17:09 INFO - PID 1927 | |7;imaging-desaturate;106;145;134;92;91;89;91;91;123;80 19:17:09 INFO - PID 1927 | |8;json-parse-financial;51;50;51;52;50;50;51;54;52;50 19:17:09 INFO - PID 1927 | |9;json-stringify-tinderbox;58;59;61;58;62;57;58;61;57;56 19:17:09 INFO - PID 1927 | |10;stanford-crypto-aes;89;91;86;86;88;90;87;89;131;107 19:17:09 INFO - PID 1927 | |11;stanford-crypto-ccm;139;139;135;139;138;156;151;140;160;169 19:17:09 INFO - PID 1927 | |12;stanford-crypto-pbkdf2;189;191;188;188;188;189;182;188;189;215 19:17:09 INFO - PID 1927 | |13;stanford-crypto-sha256-iterative;70;67;67;67;69;79;91;71;67;68 19:17:09 INFO - PID 1927 | __end_tp_report 19:17:09 INFO - PID 1927 | __start_cc_report 19:17:09 INFO - PID 1927 | _x_x_mozilla_cycle_collect,1192 19:17:09 INFO - PID 1927 | __end_cc_report 19:17:09 INFO - PID 1927 | __startTimestamp1496369829728__endTimestamp 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | ------- Summary: start ------- 19:17:09 INFO - PID 1927 | Number of tests: 14 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#0] ai-astar Cycles:10 Average:130.40 Median:148.00 stddev:28.84 (19.5%) stddev-sans-first:28.56 19:17:09 INFO - PID 1927 | Values: 101.0 113.0 114.0 126.0 170.0 98.0 170.0 171.0 121.0 120.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#1] audio-beat-detection Cycles:10 Average:123.40 Median:123.50 stddev:16.43 (13.3%) stddev-sans-first:17.06 19:17:09 INFO - PID 1927 | Values: 133.0 114.0 109.0 150.0 110.0 112.0 109.0 113.0 145.0 139.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#2] audio-dft Cycles:10 Average:226.40 Median:227.00 stddev:33.19 (14.6%) stddev-sans-first:34.20 19:17:09 INFO - PID 1927 | Values: 204.0 199.0 195.0 211.0 254.0 261.0 211.0 290.0 243.0 196.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#3] audio-fft Cycles:10 Average:65.50 Median:68.00 stddev:13.54 (19.9%) stddev-sans-first:12.16 19:17:09 INFO - PID 1927 | Values: 86.0 61.0 63.0 84.0 60.0 51.0 76.0 52.0 73.0 49.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:78.50 stddev:9.66 (12.3%) stddev-sans-first:9.89 19:17:09 INFO - PID 1927 | Values: 84.0 70.0 85.0 72.0 69.0 66.0 85.0 69.0 73.0 95.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#5] imaging-gaussian-blur Cycles:10 Average:111.90 Median:113.00 stddev:21.95 (19.4%) stddev-sans-first:21.32 19:17:09 INFO - PID 1927 | Values: 137.0 94.0 137.0 92.0 90.0 89.0 105.0 136.0 102.0 137.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#6] imaging-darkroom Cycles:10 Average:96.70 Median:97.00 stddev:0.82 (0.8%) stddev-sans-first:0.87 19:17:09 INFO - PID 1927 | Values: 97.0 98.0 95.0 97.0 96.0 97.0 97.0 97.0 96.0 97.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#7] imaging-desaturate Cycles:10 Average:104.20 Median:90.00 stddev:22.10 (24.6%) stddev-sans-first:23.44 19:17:09 INFO - PID 1927 | Values: 106.0 145.0 134.0 92.0 91.0 89.0 91.0 91.0 123.0 80.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:1.29 (2.5%) stddev-sans-first:1.36 19:17:09 INFO - PID 1927 | Values: 51.0 50.0 51.0 52.0 50.0 50.0 51.0 54.0 52.0 50.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:58.50 stddev:2.00 (3.4%) stddev-sans-first:2.11 19:17:09 INFO - PID 1927 | Values: 58.0 59.0 61.0 58.0 62.0 57.0 58.0 61.0 57.0 56.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:88.50 stddev:14.24 (16.1%) stddev-sans-first:14.97 19:17:09 INFO - PID 1927 | Values: 89.0 91.0 86.0 86.0 88.0 90.0 87.0 89.0 131.0 107.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#11] stanford-crypto-ccm Cycles:10 Average:146.60 Median:145.50 stddev:11.62 (8.0%) stddev-sans-first:11.99 19:17:09 INFO - PID 1927 | Values: 139.0 139.0 135.0 139.0 138.0 156.0 151.0 140.0 160.0 169.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.70 Median:189.00 stddev:8.84 (4.7%) stddev-sans-first:9.36 19:17:09 INFO - PID 1927 | Values: 189.0 191.0 188.0 188.0 188.0 189.0 182.0 188.0 189.0 215.0 19:17:09 INFO - PID 1927 | 19:17:09 INFO - PID 1927 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:69.50 stddev:7.73 (11.1%) stddev-sans-first:8.18 19:17:09 INFO - PID 1927 | Values: 70.0 67.0 67.0 67.0 69.0 79.0 91.0 71.0 67.0 68.0 19:17:09 INFO - PID 1927 | -------- Summary: end -------- 19:17:09 INFO - PID 1927 | 19:17:10 INFO - TEST-INFO | 1927: exit 0 19:17:10 INFO - TEST-OK | kraken | took 90908ms 19:17:10 INFO - SUITE-END | took 1233s 19:17:10 INFO - Completed test suite (00:20:34) 19:17:10 INFO - javascript benchmark 19:17:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8084.647879609806, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2751.54599491084, 3019.6074510313656, 3561.0741207286105, 3594.694450971669, 3620.67413932746, 6124.41144964001, 6151.138840562319, 6227.782027629034, 6230.190075290206, 6242.375762423761, 6132.8144489347815, 6182.0, 6193.876122477548, 6272.9372706272925, 6274.968625156868, 4465.508794032654, 5323.387810401795, 5410.513053825155, 5491.258680078178, 5533.474319939599, 5545.611807173499, 5592.161175823619, 5600.18797274395, 5625.634333768312, 5644.238027866227, 4522.706024108423, 5424.349078110652, 5471.343438787331, 5475.4524547545325, 5500.559955203594, 4434.667399945017, 5519.116941289414, 5541.6674999499755, 5544.556435485151, 5562.0266453370905, 4499.820007199708, 5476.452354764531, 5508.476694713996, 5511.889762204773, 5519.613627046108, 6117.8274048008725, 6135.9079613805825, 6174.38256174379, 6189.845253868644, 6225.252969643671, 6707.228668703064, 6735.090762747015, 6739.854989181667, 6741.426978706816, 6758.729650813962, 6829.795106146824, 6836.213835408892, 6839.110915580993, 6847.726090956356, 6851.725930962755, 6203.348648391944, 6262.3111457739615, 6299.181106456132, 6324.71538780756, 6349.079383489368, 5811.302643682785, 6950.061741664912, 6961.721531138698, 6988.93011069893, 6993.4055605274125, 6290.370962903765, 6463.612183269019, 6489.3510648934725, 6605.174353205849, 6628.602283862983, 6630.7679231226675, 6729.966350168315, 6769.0, 6774.45804335652, 6775.593464392152, 6465.062565927914, 6506.642134682544, 6678.432333251728, 6680.331966803281, 6697.3637504437, 6612.471002319803, 6613.437857782046, 6691.096701945273, 6711.6979735912, 6742.123523941954, 5334.546563542065, 5339.973300133553, 5353.57171426285, 5374.596905232123, 5384.138537833927, 4663.347131401607, 4735.455422626374, 4765.11845308619, 4788.257820037899, 4823.662343635982, 5295.655782374133, 6283.868903597305, 6289.842753931097, 6339.587926784837, 6354.618722876642, 6781.457483401415, 6818.079559259437, 6824.761133360308, 6845.657717114124, 6864.141982252218, 6220.502359811204, 6331.715072821734, 6340.619562826245, 6372.139761132281, 6372.522060845363, 5869.413058694096, 5957.4638282553915, 5996.0, 6014.879702405928, 6064.151018857364, 6958.234594194533, 6963.582185068912, 7001.859962800613, 7011.964940175267, 7021.368076873106, 6824.93175068243, 6837.0, 6870.896936546056, 6871.0, 6886.380225779704, 6789.558678685869, 6870.416014638811, 6870.62211578368, 6945.236024037251, 6982.930170698228, 5871.647701137946, 6664.333566643297, 6687.3647003536535, 6728.7308507659145, 6738.157730283715, 6457.192850893638, 6785.321467853175, 6830.8633827324165, 6840.0765896603325, 6845.691943862438, 5028.522290382408, 5302.893942121059, 5551.723103686094, 5932.466078052996, 6371.55399122057, 5441.972790136024, 6540.117084193763, 6593.208814942238, 6608.405243527914, 6611.107500487373, 6330.651814150266, 6388.936110638834, 6449.903251451138, 6486.383793539606, 6493.642849643315, 6389.329120442472, 6447.097406363207, 6578.473722102316, 6613.470922326106, 6618.503612229199, 6380.872382552416, 6399.968000159969, 6412.647304398303, 6451.548391612542, 6453.645049522322], "value": 6078.774439221246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [775.9612019399028, 784.8430313937213, 844.3625063077374, 1054.752133248687, 1272.8027155790855, 38176.80911595445, 38515.61484385155, 38542.03644908879, 38545.421818672745, 38582.228355432875, 38850.22299554007, 39216.607833921655, 39224.0, 39259.0, 39280.0, 7802.648880800363, 8177.836443271131, 8390.790230244262, 8426.747197584082, 8430.241278284953, 8353.457025293366, 8488.618012189467, 8504.957475212646, 8519.3610479214, 8557.315414766803, 8463.74608761738, 8479.618417171212, 8539.274161696263, 8539.487630742144, 8543.0, 8439.451435656723, 8483.024452187985, 8486.830263394728, 8497.490150590984, 8547.700830470965, 8237.588120593946, 8275.337972962148, 8323.750287491384, 8326.70856520025, 8328.541930193836, 37525.24949500995, 37549.24901501982, 37822.621773782186, 37921.0, 37943.4308485373, 3445.2248244144866, 3476.913077173091, 3485.9477107843404, 3488.110531814371, 3493.9301213975577, 30439.391212175633, 30594.541081883788, 30655.38689226203, 30691.38617227643, 30711.078667640006, 2241.2827895073583, 2449.020391843259, 2668.3462551674966, 2700.4329090890938, 2702.3919618085774, 241398.0, 247964.0, 252706.0, 272969.999999998, 282402.1759782396, 47614.28578571324, 47944.56166314946, 47950.520494794604, 47958.28062579064, 48264.27603585949, 49673.0, 49964.75017624888, 50077.49922500801, 53815.46184538105, 54467.72766136144, 12122.878771212176, 14648.487302944346, 14782.926085369505, 14874.256287185597, 14900.0, 11911.58309459182, 11955.581554645545, 12048.337341446304, 12053.0, 12054.879451205376, 13212.735745285041, 14056.297185140702, 14066.804321632775, 14099.71800563983, 14104.012719109563, 6091.543134264948, 6114.816555503342, 6152.077188421773, 6165.475934545524, 6182.0, 941.5668792355456, 981.6711401680374, 1064.4571268653042, 1069.9129325974857, 1084.5824357622373, 23343.649845252334, 24334.148304809245, 24629.01483940658, 24712.25863224106, 24810.875945620155, 970.4565443351746, 978.4373984958677, 1119.2612875502293, 1168.4391492083848, 1179.2334982261598, 10937.945310273397, 12062.733412991685, 12540.749185016248, 12607.558735444216, 12681.30252836103, 326709.0, 328889.0, 329556.0, 329722.0, 329841.0, 53936.651583709936, 54128.85775851288, 54300.0, 54415.45584544183, 54565.72717136389, 1525.2907398059956, 1539.7844301797534, 1571.0966194438245, 1574.3781206423628, 1577.8579927806552, 17708.291668333124, 24031.399215019763, 24112.397190069685, 24211.273661790176, 24363.878180608983, 37786.0, 37791.05522361963, 37872.24255514929, 37915.052123697125, 37933.24133517259, 268606.0, 271562.0, 271564.0, 271600.0, 272209.6389518119, 28877.71122288834, 30801.921932733145, 34360.48459273063, 35739.64260357467, 35942.0, 43212.0, 44883.55116448794, 44918.550814491435, 45364.31953520766, 46841.0, 25425.364365891, 30325.0, 30933.226669332547, 31348.37303253968, 31913.36173276475, 29839.0, 30837.691623084378, 31712.682873170972, 31842.84078579685, 32374.676253237165, 37106.07234819151, 37360.626393735714, 38272.0, 38283.2343353137, 38316.61683383131, 25623.487530249662, 32067.839660801546, 32670.509942350407, 32675.34649306953, 32831.507527387585, 20104.39686809398, 23065.65401518945, 24766.0, 25238.873805630854, 25397.746022539537], "value": 17434.07844516047, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.56465065175047, 413.3427849718948, 427.2928303657448, 438.94433886502964, 456.3337527210273, 21946.902654867252, 22733.204337848198, 22757.544849103007, 22769.0, 22810.0, 23469.530609387803, 24511.509769804594, 24634.260972170865, 24666.876665616648, 24683.382915427122, 6141.877162456748, 6263.498920086405, 6284.968575157141, 6285.560010799246, 6295.055741638745, 6186.412290832363, 6197.75208991642, 6248.6875656217235, 6275.2469703635625, 6277.623342599436, 6144.5084393248435, 6184.56708030438, 6217.285012223584, 6221.284552276501, 6431.260405053432, 6776.085228494164, 6893.862122757542, 6925.342092501204, 6933.4106600938985, 6934.895976560356, 6733.192016957947, 6736.2590115087305, 6779.72881084756, 6787.932120678779, 6795.558288711268, 30410.0, 31017.37965240705, 31201.531977020364, 31227.531587026213, 31297.217569560715, 2236.3291012696127, 2282.5777231212114, 2301.505176387068, 2303.090279339668, 2305.0, 19374.70937935932, 19750.50623734418, 19883.90058049715, 19952.301669441644, 20023.998800060086, 2315.513742114158, 2331.9067237310655, 2377.405648587853, 2383.97616023841, 2406.7713567211085, 147988.0, 158691.0, 172661.0, 180054.09972950182, 183959.0, 19144.808551914582, 19643.116059777345, 24640.876795615906, 26202.0, 26330.6050409244, 43813.780931095775, 43877.341839872424, 43901.341479877825, 43934.56065439369, 43994.78002609967, 11198.21612487118, 11296.265742726697, 11341.773164536664, 11425.08599312053, 11538.538458461735, 8983.865242021375, 8985.955070224738, 9036.0, 9098.727038188732, 9099.772505687279, 12004.0, 12189.916378013288, 12195.573154939711, 12305.384730763426, 12320.260784352968, 4122.896927576775, 4892.412910450768, 4983.551480366785, 5000.224965130411, 5004.974975125174, 803.2650125135552, 876.9914598212107, 968.651285537206, 974.7611835100446, 997.0683215024463, 19701.704474432896, 21680.0, 21719.0, 21893.343199704035, 22000.88999555024, 997.2919227348664, 998.5606333213362, 1023.1712313026472, 1037.9429131397847, 1038.1642777563982, 10344.948275258575, 10791.352518848895, 11219.719507012227, 11300.265482743594, 11308.0, 540545.0, 545810.0, 546453.0, 548681.0, 549246.2537687366, 133430.33284833515, 146588.26705866403, 148783.0, 149254.7611785824, 149295.0, 1150.1316506037888, 1154.8094564396781, 1222.6270987348767, 1236.6042866282883, 1239.702471406856, 19583.412497625093, 23129.537409251385, 23440.648390274502, 23510.294691158608, 23743.762562374155, 64768.0, 65021.374465638735, 65028.699426012165, 65159.1456496214, 65374.01938970824, 83107.75338369809, 83242.0, 83335.41530297665, 83349.74778404838, 83592.16407835844, 10210.693679189637, 10223.59105635796, 10335.0, 10366.948165259428, 10371.533281002223, 17361.826381736526, 17411.0, 17427.82572174262, 17589.504892084315, 17710.91144544269, 13990.020698551003, 13994.720105598035, 13998.370073346927, 14193.787093193818, 14219.786703199254, 12305.93847030759, 12590.24458532491, 12659.556915507914, 12884.549040783528, 12946.546870859474, 14167.149971001772, 14307.141571505743, 14430.567082987527, 14594.562163135122, 14598.92700536533, 10158.492075396349, 10179.949100254747, 10238.385696858213, 10279.94860025695, 10282.588696452058, 13770.104943178661, 13786.517471888435, 13831.239281839595, 13855.445782168601, 14006.159630422208], "value": 12265.627077855159, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.2551596621859, 1423.5088894331452, 1423.9216843073634, 1431.3058166789112, 1435.1460880775937, 11499.655010349692, 11873.703157421069, 12031.879681203185, 12033.0, 12231.816522752144, 11650.41747912603, 11853.940730296357, 11865.466054027567, 11870.703232419193, 11876.881231187686, 2532.7467253274617, 2585.1985884375904, 2588.2364702412833, 2589.753973372536, 2594.44219492809, 2515.924522264335, 2550.2094350751236, 2555.6166575013713, 2559.731228221038, 2565.7819085377764, 2519.3827512259522, 2541.3646588352913, 2547.223096955428, 2550.0947163756878, 2557.1050132453584, 2491.626256061596, 2509.949801003979, 2519.382751225943, 2524.129175434472, 2526.5325914705836, 2466.666999955001, 2501.3246423465744, 2502.799776017914, 2560.7055188653358, 2561.3596600849787, 11793.233439826385, 11844.703882402922, 11879.168458207929, 11891.167618266725, 11922.10584206188, 6441.227052753699, 6635.601863888182, 6640.833979150511, 6703.530752847302, 6708.19501659804, 4089.5092588889224, 4222.1766755482695, 4304.225239456897, 4305.342443159406, 4316.870493885157, 1222.6637674639455, 1246.738184981155, 1262.3814330977755, 1274.082660484459, 1278.188350397495, 15446.768298475532, 15787.052776833427, 16622.916885415616, 16683.16584170799, 16692.499225023268, 5546.417626149277, 5601.271834661549, 5786.623869448473, 5804.448577385142, 5807.186993820868, 12318.384080795924, 12337.629871103882, 12346.567870124503, 12349.32078735678, 12355.567555135707, 5794.594378393472, 6247.812915546032, 6413.134226879405, 6468.512242184324, 6552.836179095465, 5941.940580594139, 6083.692006218595, 6116.449519543263, 6161.137440758297, 6247.250329960434, 4336.392904993303, 4414.09511050234, 4428.3800267962515, 4457.331400289982, 4484.461864576207, 1277.7061275906594, 1284.524725851441, 1292.2956988441322, 1303.517698451579, 1307.8038294255937, 703.9788806335819, 780.4965797060977, 791.5883740454931, 796.1799346672937, 804.0632662947601, 5352.785888564492, 5624.67820062285, 6033.698315084316, 6341.524385671093, 6357.745690172342, 442.30558023902165, 609.984750381244, 610.9419605137505, 906.7415786500816, 973.245734555725, 4221.978890105591, 4500.527444618333, 4625.306204069417, 4656.045510670305, 4674.509176536482, 12457.439415226336, 13655.248961307025, 14555.272236388138, 15301.311440985182, 15377.461788837338, 14771.556853294418, 15096.0, 15194.164320962233, 15342.539723808304, 15380.692386152214, 8432.915670843337, 8558.01582817984, 8611.880455540864, 8632.395732298679, 8636.524991125549, 6500.609963402022, 7120.456378244427, 7138.678759455733, 7203.387712044535, 7342.155652099863, 10910.781784364426, 13106.410211540313, 13121.950243980267, 13129.671758205739, 13176.407061682055, 12653.87346126527, 13210.207387556777, 13387.196768193939, 13436.596902092953, 13452.663683407993, 7690.000299961193, 7885.290323870649, 8331.583420829056, 8463.830723385621, 8999.595018224327, 8878.95560522219, 10147.238957078396, 10536.315139515908, 10640.935906409297, 10663.36019838781, 8778.297736181217, 8918.910810892068, 8956.193942545202, 9168.037356077515, 9175.862362064441, 8823.823523529365, 8872.600732967161, 8952.820943580962, 8988.011318754801, 9121.863172052557, 7769.6892124316655, 8025.277725004544, 8132.146124656826, 8134.593270336341, 8157.428979971345, 4773.0, 5090.389153301479, 6115.205023347115, 6209.348018457997, 6225.31521532622, 5924.644521328734, 6006.819795406145, 6083.904897118561, 6190.226221722285, 6321.557490975588], "value": 5322.377156482392, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.8783637890359, 351.95776506819175, 404.8137856585972, 413.4748868936452, 415.424636877924, 6193.442590166884, 6380.755752628239, 6419.743210271584, 6441.581297215677, 6471.252761754329, 6059.515238780898, 6084.269887613482, 6140.938590614092, 6208.379162083789, 6215.440610345068, 3747.3442147624123, 4619.399478067847, 4621.329907163459, 4623.352730617717, 4626.65300102492, 3774.282886251617, 3988.302047141753, 4505.77471126444, 4641.744704041276, 4663.930041049387, 4165.812538435763, 4213.410122582841, 4600.493945665974, 4600.884977875547, 4610.4467463904375, 3780.5633859133354, 4525.886852828673, 4587.977060114695, 4598.8620341389815, 4633.62930965522, 3285.83570821458, 3968.1071758854314, 3991.1219531702977, 3996.4005399190064, 3997.620226078518, 5282.788688452457, 6136.2636483622255, 6143.416375444371, 6165.075238714184, 6190.1952746142715, 5435.076037073707, 5509.972450137764, 5514.0, 5514.310711161105, 5523.972380138114, 5598.636088654224, 5674.8650269945765, 6648.601083934979, 6702.290915816484, 6718.2945790691765, 3864.5507934524553, 4165.333546632524, 4434.846939795644, 4530.207213737583, 4546.681732278742, 6791.320867913169, 6949.5135340526185, 6998.265182155902, 7108.431325493948, 7195.280471952815, 3790.639889210521, 3987.80060996949, 4067.654249388776, 4563.429571303587, 4579.977100114478, 3858.9035274118373, 4651.697639653412, 4653.115907977473, 4653.418322709661, 4682.391289132459, 5118.97440512795, 5192.402873669576, 5238.7904483820985, 5339.359276886798, 5446.400895901447, 6083.878322433527, 6569.6203797203125, 6686.264510903797, 6693.2972037935315, 6711.630860302633, 1594.4977332140338, 1605.3097168217778, 1607.6463178100798, 1612.935482580707, 1619.2146808797725, 951.4529145741227, 995.4027583449872, 1006.3509036671452, 1066.898644628759, 1070.7055559720984, 5177.301064356339, 6120.755169793248, 6283.711839072979, 6305.311392835151, 6312.810615681537, 5884.499817515559, 6750.864982700318, 6865.485088618374, 6924.549904256207, 6968.581885086911, 5521.5306698931045, 6232.75068997235, 6297.418058595954, 6297.4332310091395, 6313.842153946096, 3076.7384772294604, 3425.8629654814026, 3580.1944562473236, 3644.653757892996, 3648.5074514940675, 5420.403755586883, 7167.605781682058, 7171.812597157917, 7211.89182162268, 7237.276272372721, 6525.543211975116, 7128.821779455555, 7141.392981596518, 7142.785716428411, 7154.821129471805, 6576.671166441659, 6786.287439818847, 7157.284271573009, 7164.605946672983, 7184.892226616605, 6825.965870170618, 6918.377346038881, 6921.342472465108, 6921.861562768616, 6947.65261736901, 5909.497692695997, 6019.909701354396, 6788.0, 6814.795556133324, 6818.863622727418, 6063.0, 6067.998780201394, 6590.890914907375, 6727.663616819042, 7134.32223938725, 3825.6748176405317, 3986.661133803659, 4532.954670453253, 4575.611073058827, 4605.4933957264, 5511.200875873056, 5854.648721076749, 6047.244094488189, 6580.9141491653345, 6837.521373503807, 6630.701618427279, 6651.201855777338, 6661.267260601233, 6678.365555272436, 6692.665366731741, 6742.471573160065, 6754.18949726036, 6756.756756756882, 6763.188417389944, 6782.423494003065, 6556.508261880474, 6556.541042127005, 6558.081868538313, 6574.323547495358, 6623.701933412912, 6681.164854393201, 6682.431993280626, 6688.77943512538, 6716.496262780409, 6751.358620931003, 5448.645838020516, 6276.838784824639, 6517.9348206517325, 6520.771772987922, 6568.310327415553], "value": 4824.946734366262, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [654.5450911616429, 688.5249178067135, 706.4560288577792, 775.3603277296232, 816.918308169183, 13475.797863032063, 13994.860051399482, 14137.434502619895, 16041.518754437371, 16056.598585035379, 16376.344946202167, 17806.19872105755, 18276.725849112274, 18334.724979125323, 18348.256915593032, 784.4861615641764, 970.0590427285522, 975.1223898491344, 992.7766252593145, 994.5524513968707, 1010.9898901011006, 1021.4586269277295, 1024.8565200871847, 1026.4354604967275, 1026.5534492495751, 956.4834989105874, 1011.7521207304221, 1064.9510232421098, 1066.1470823341335, 1068.0174239699495, 1070.0797314309686, 1071.0949247885524, 1073.2648136026846, 1073.7315671082229, 1073.8013467508536, 769.1616138409161, 771.6488997506121, 773.6595897805004, 774.3688893551737, 776.953382797034, 13970.860291397057, 15477.0, 15951.521454356387, 16148.919255403647, 16297.429589964413, 16983.405580804614, 17192.054437006085, 17433.38983135597, 17541.386051488138, 17608.207630656652, 11392.943035284854, 13828.170309781446, 13898.93050534751, 13922.30388480582, 13934.024618276724, 3484.4424892017155, 3495.4582039783872, 3504.859805607798, 3506.2286297014616, 3508.0002199373057, 117319.41340293329, 145247.27376363156, 146806.0, 147315.0, 148977.0, 13497.257650829299, 13501.66245843842, 13558.389872455764, 13656.180629162282, 13715.519956801463, 26494.205173844817, 26496.735032649427, 26920.192394228205, 26967.730322696916, 26976.325591859968, 7117.501774875813, 7254.738668834253, 7270.672819723125, 7311.744088956861, 7318.890216646754, 2651.376926422289, 3013.0, 3468.7745296555645, 3526.947095793565, 3530.8411121499594, 4180.393842892763, 4189.4344263524645, 4251.426057482262, 4259.190753756776, 4267.1679022590915, 843.7173546861748, 846.716350022737, 850.7617866997251, 858.3133493205411, 865.7446053414258, 641.7625478572958, 647.119916912998, 659.8251463362213, 670.8691805098057, 681.7796144900573, 8433.831323373499, 10951.56193752241, 11381.146414018982, 11660.708482288012, 11982.940085299517, 638.6551262318296, 639.341478277375, 642.0818229931243, 643.707113263464, 665.7536711416807, 5251.789928402897, 5269.920951185735, 5294.152935530296, 5364.517193452608, 5465.59008074396, 140984.0, 141327.29336353458, 143898.0, 144399.5560044407, 148002.0, 119142.0, 119162.80837191691, 120490.3975480117, 120591.0, 121196.78803212031, 79227.60386198148, 102520.0, 103404.9999999985, 103443.4827825871, 104603.0, 9526.61893524279, 9998.550065246936, 10305.156050781607, 10572.788544228919, 10804.621838235938, 44584.0, 44991.55008450004, 45304.77347613373, 45842.0, 45870.0, 52361.0, 53626.659333516975, 61615.69192154011, 61744.69127654333, 62507.687461562404, 13355.065145440114, 13739.793903091262, 13913.72172556523, 14119.223442710749, 14127.505537306144, 14324.068935519575, 14655.340509676877, 14776.261186940395, 14851.554453366416, 14862.554123376316, 13966.930165349107, 16744.995300282022, 16970.40603578869, 18348.715589908577, 18353.72469412933, 14343.928280358532, 15171.317290722165, 17424.0, 17593.0, 18203.726944095586, 17637.559061023578, 17864.91067544654, 17967.730484042488, 18116.365927192484, 18157.27370905149, 9287.76780580491, 11886.34625095628, 11909.880901190878, 12253.509859605516, 12443.875561244633, 12957.935210323889, 13705.0, 13794.65513362174, 13829.51596694111, 13884.028118031641], "value": 8364.321765129298, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 114.0, 126.0, 170.0, 98.0, 170.0, 171.0, 121.0, 120.0], "value": 130.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 114.0, 109.0, 150.0, 110.0, 112.0, 109.0, 113.0, 145.0, 139.0], "value": 123.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 199.0, 195.0, 211.0, 254.0, 261.0, 211.0, 290.0, 243.0, 196.0], "value": 226.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 61.0, 63.0, 84.0, 60.0, 51.0, 76.0, 52.0, 73.0, 49.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 85.0, 72.0, 69.0, 66.0, 85.0, 69.0, 73.0, 95.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 94.0, 137.0, 92.0, 90.0, 89.0, 105.0, 136.0, 102.0, 137.0], "value": 111.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 95.0, 97.0, 96.0, 97.0, 97.0, 97.0, 96.0, 97.0], "value": 96.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 145.0, 134.0, 92.0, 91.0, 89.0, 91.0, 91.0, 123.0, 80.0], "value": 104.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 52.0, 50.0, 50.0, 51.0, 54.0, 52.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 61.0, 58.0, 62.0, 57.0, 58.0, 61.0, 57.0, 56.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 86.0, 86.0, 88.0, 90.0, 87.0, 89.0, 131.0, 107.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 135.0, 139.0, 138.0, 156.0, 151.0, 140.0, 160.0, 169.0], "value": 146.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 191.0, 188.0, 188.0, 188.0, 189.0, 182.0, 188.0, 189.0, 215.0], "value": 190.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 67.0, 67.0, 67.0, 69.0, 79.0, 91.0, 71.0, 67.0, 68.0], "value": 71.6, "unit": "score"}]}]} 19:17:11 INFO - Return code: 0 19:17:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:17:11 INFO - # TBPL SUCCESS # 19:17:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:17:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:17:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:17:11 INFO - 2017-06-01 19:17:11.160 screenresolution[1935:13151] starting screenresolution argv=screenresolution get 19:17:11 INFO - 2017-06-01 19:17:11.163 screenresolution[1935:13151] Display 0: 1600x1200x32@60 19:17:11 INFO - 2017-06-01 19:17:11.169 screenresolution[1936:13153] starting screenresolution argv=screenresolution list 19:17:11 INFO - Available Modes on Display 0 19:17:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:17:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:17:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:17:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:17:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:17:11 INFO - Intel Iris: 19:17:11 INFO - Chipset Model: Intel Iris 19:17:11 INFO - Type: GPU 19:17:11 INFO - Bus: Built-In 19:17:11 INFO - VRAM (Dynamic, Max): 1536 MB 19:17:11 INFO - Vendor: Intel (0x8086) 19:17:11 INFO - Device ID: 0x0a2e 19:17:11 INFO - Revision ID: 0x0009 19:17:11 INFO - Displays: 19:17:11 INFO - Display: 19:17:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:17:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:17:11 INFO - Display Serial Number: 1600x1200 60 19:17:11 INFO - Main Display: Yes 19:17:11 INFO - Mirror: Off 19:17:11 INFO - Online: Yes 19:17:11 INFO - Rotation: Supported 19:17:11 INFO - Return code: 0 19:17:11 INFO - Running post-action listener: _resource_record_post_action 19:17:11 INFO - [mozharness: 2017-06-02 02:17:11.405547Z] Finished run-tests step (success) 19:17:11 INFO - Running post-run listener: _resource_record_post_run 19:17:11 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 4044800; Write bytes: 574032896; Read time: 42; Write time: 1995 19:17:11 INFO - TinderboxPrint: CPU usage
53.2% 19:17:11 INFO - TinderboxPrint: I/O read bytes / time
4,044,800 / 42 19:17:11 INFO - TinderboxPrint: I/O write bytes / time
574,032,896 / 1,995 19:17:11 INFO - TinderboxPrint: CPU idle
2,350.7 (46.8%) 19:17:11 INFO - TinderboxPrint: CPU system
1,018.3 (20.3%) 19:17:11 INFO - TinderboxPrint: CPU user
1,656.6 (33.0%) 19:17:11 INFO - TinderboxPrint: Swap in / out
251,469,824 / 0 19:17:11 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232835584; Write bytes: 230307328; Read time: 16754; Write time: 443 19:17:11 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:17:11 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 3176448; Write bytes: 324404736; Read time: 38; Write time: 1505 19:17:11 INFO - Running post-run listener: _upload_blobber_files 19:17:11 INFO - Blob upload gear active. 19:17:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:17:11 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 19:17:11 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'] 19:17:11 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 19:17:12 INFO - (blobuploader) - INFO - Open directory for files ... 19:17:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:17:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:17:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:13 INFO - (blobuploader) - INFO - Done attempting. 19:17:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:17:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:17:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:13 INFO - (blobuploader) - INFO - Done attempting. 19:17:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:17:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:17:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:15 INFO - (blobuploader) - INFO - Done attempting. 19:17:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:17:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:17:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:17:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:17:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:17:17 INFO - (blobuploader) - INFO - Done attempting. 19:17:17 INFO - (blobuploader) - INFO - Iteration through files over. 19:17:17 INFO - Return code: 0 19:17:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:17:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:17:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357c2c0feed1ec403a9c0134d5b1ba10b8806399f5d699fda4111cf278ad77450afe1941a7bf5b4954f717667051d9d7c68840108e5372d4369a05570e986642", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71b5529eed683fce380bf645a641134d76aecb5d3073c75d9653a3d8b152e4d17b025785b1967b2e706ea8a4a005da1a24f4eb8ef2635b275d6fb6815361f2a8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d48b5eeff2ff160207b40c9aea1bcfd05e2853bc032e300236f2cf0a190f1d4b6abbe5ed45c45dbc87f32641f8f5c086890c1c5f879adba2bb280800ddd624a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8a2045e87d81ac8af29b674e44bd0744716ab1c69bcb8addd146c5f8016e40529de0b62668f70c7e5db604effef41a34e29f609c0b9763eeb5590ffd42bbc7c"} 19:17:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:17:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:17:17 INFO - Contents: 19:17:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/357c2c0feed1ec403a9c0134d5b1ba10b8806399f5d699fda4111cf278ad77450afe1941a7bf5b4954f717667051d9d7c68840108e5372d4369a05570e986642", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71b5529eed683fce380bf645a641134d76aecb5d3073c75d9653a3d8b152e4d17b025785b1967b2e706ea8a4a005da1a24f4eb8ef2635b275d6fb6815361f2a8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d48b5eeff2ff160207b40c9aea1bcfd05e2853bc032e300236f2cf0a190f1d4b6abbe5ed45c45dbc87f32641f8f5c086890c1c5f879adba2bb280800ddd624a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8a2045e87d81ac8af29b674e44bd0744716ab1c69bcb8addd146c5f8016e40529de0b62668f70c7e5db604effef41a34e29f609c0b9763eeb5590ffd42bbc7c"} 19:17:17 INFO - Running post-run listener: copy_logs_to_upload_dir 19:17:17 INFO - Copying logs to upload dir... 19:17:17 INFO - mkdir: /builds/slave/test/build/upload/logs 19:17:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.420617 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 16 secs) (at 2017-06-01 19:17:17.672848) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 19:17:17.677098) ========= 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.xzz4uMNAbo/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.lBfT1ea31k/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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/357c2c0feed1ec403a9c0134d5b1ba10b8806399f5d699fda4111cf278ad77450afe1941a7bf5b4954f717667051d9d7c68840108e5372d4369a05570e986642", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71b5529eed683fce380bf645a641134d76aecb5d3073c75d9653a3d8b152e4d17b025785b1967b2e706ea8a4a005da1a24f4eb8ef2635b275d6fb6815361f2a8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d48b5eeff2ff160207b40c9aea1bcfd05e2853bc032e300236f2cf0a190f1d4b6abbe5ed45c45dbc87f32641f8f5c086890c1c5f879adba2bb280800ddd624a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8a2045e87d81ac8af29b674e44bd0744716ab1c69bcb8addd146c5f8016e40529de0b62668f70c7e5db604effef41a34e29f609c0b9763eeb5590ffd42bbc7c"} build_url:https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011244 build_url: 'https://queue.taskcluster.net/v1/task/d9QA_QLvTR-2t4GWaGCbEA/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/357c2c0feed1ec403a9c0134d5b1ba10b8806399f5d699fda4111cf278ad77450afe1941a7bf5b4954f717667051d9d7c68840108e5372d4369a05570e986642", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71b5529eed683fce380bf645a641134d76aecb5d3073c75d9653a3d8b152e4d17b025785b1967b2e706ea8a4a005da1a24f4eb8ef2635b275d6fb6815361f2a8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d48b5eeff2ff160207b40c9aea1bcfd05e2853bc032e300236f2cf0a190f1d4b6abbe5ed45c45dbc87f32641f8f5c086890c1c5f879adba2bb280800ddd624a5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8a2045e87d81ac8af29b674e44bd0744716ab1c69bcb8addd146c5f8016e40529de0b62668f70c7e5db604effef41a34e29f609c0b9763eeb5590ffd42bbc7c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 19:17:17.717395) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:17:17.717747) ========= 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.xzz4uMNAbo/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.lBfT1ea31k/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005130 ========= master_lag: 0.09 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 19:17:17.813582) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 19:17:17.816931) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 19:17:17.817253) ========= ========= Total master_lag: 0.29 =========