builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0317 starttime: 1494944893.23 results: success (0) revision: b9a4a6e2292e923684c2d25b78addaca23c6abd5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:13.232069) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:13.232539) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:13.232828) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:13.260711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:13.261085) ========= 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.qZJYwtRh5H/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.NCpilZCDXa/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-16 07:28:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.2M=0.001s 2017-05-16 07:28:13 (18.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139857 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:13.427075) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:13.427427) ========= 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.qZJYwtRh5H/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.NCpilZCDXa/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.027332 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:13.475586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-16 07:28:13.478954) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b9a4a6e2292e923684c2d25b78addaca23c6abd5 --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 b9a4a6e2292e923684c2d25b78addaca23c6abd5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qZJYwtRh5H/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.NCpilZCDXa/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-16 07:28:13,593 truncating revision to first 12 chars 2017-05-16 07:28:13,593 Setting DEBUG logging. 2017-05-16 07:28:13,593 attempt 1/10 2017-05-16 07:28:13,593 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b9a4a6e2292e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 07:28:15,349 unpacking tar archive at: mozilla-central-b9a4a6e2292e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.041787 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-16 07:28:15.542533) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:15.545891) ========= script_repo_revision: b9a4a6e2292e923684c2d25b78addaca23c6abd5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:15.546282) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:15.546550) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 07:28:15.565799) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-16 07:28:15.566103) ========= /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.qZJYwtRh5H/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.NCpilZCDXa/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:28:15 INFO - MultiFileLogger online at 20170516 07:28:15 in /builds/slave/test 07:28:15 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 07:28:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:28:15 INFO - {'append_to_log': False, 07:28:15 INFO - 'base_work_dir': '/builds/slave/test', 07:28:15 INFO - 'blob_upload_branch': 'Firefox', 07:28:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:28:15 INFO - 'branch': 'Firefox', 07:28:15 INFO - 'buildbot_json_path': 'buildprops.json', 07:28:15 INFO - 'config_files': ('talos/mac_config.py',), 07:28:15 INFO - 'default_actions': ('clobber', 07:28:15 INFO - 'read-buildbot-config', 07:28:15 INFO - 'download-and-extract', 07:28:15 INFO - 'populate-webroot', 07:28:15 INFO - 'create-virtualenv', 07:28:15 INFO - 'install', 07:28:15 INFO - 'run-tests'), 07:28:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:28:15 INFO - 'download_minidump_stackwalk': True, 07:28:15 INFO - 'download_symbols': 'ondemand', 07:28:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:28:15 INFO - 'tooltool.py': '/tools/tooltool.py', 07:28:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:28:15 INFO - '/tools/misc-python/virtualenv.py')}, 07:28:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:28:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:28:15 INFO - 'gecko_profile': False, 07:28:15 INFO - 'gecko_profile_interval': 0, 07:28:15 INFO - 'installer_path': 'installer.exe', 07:28:15 INFO - 'log_level': 'info', 07:28:15 INFO - 'log_name': 'talos', 07:28:15 INFO - 'log_to_console': True, 07:28:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:28:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:28:15 INFO - 'opt_config_files': (), 07:28:15 INFO - 'pip_index': False, 07:28:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:28:15 INFO - 'cmd': ('bash', 07:28:15 INFO - '-c', 07:28:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:28:15 INFO - 'enabled': True, 07:28:15 INFO - 'halt_on_failure': False, 07:28:15 INFO - 'name': 'check_screen_resolution'},), 07:28:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:28:15 INFO - 'cmd': ('bash', 07:28:15 INFO - '-c', 07:28:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:28:15 INFO - 'enabled': True, 07:28:15 INFO - 'halt_on_failure': False, 07:28:15 INFO - 'name': 'check_screen_resolution'},), 07:28:15 INFO - 'run_cmd_checks_enabled': True, 07:28:15 INFO - 'suite': 'dromaeojs-e10s', 07:28:15 INFO - 'system_bits': '32', 07:28:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:28:15 INFO - 'title': 't-yosemite-r7-0317', 07:28:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:28:15 INFO - 'use_talos_json': True, 07:28:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:28:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:28:15 INFO - 'work_dir': 'build'} 07:28:15 INFO - [mozharness: 2017-05-16 14:28:15.788514Z] Running clobber step. 07:28:15 INFO - Running pre-action listener: _resource_record_pre_action 07:28:15 INFO - Running main action method: clobber 07:28:15 INFO - rmtree: /builds/slave/test/build 07:28:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:28:18 INFO - Running post-action listener: _resource_record_post_action 07:28:18 INFO - [mozharness: 2017-05-16 14:28:18.163570Z] Finished clobber step (success) 07:28:18 INFO - [mozharness: 2017-05-16 14:28:18.163751Z] Running read-buildbot-config step. 07:28:18 INFO - Running pre-action listener: _resource_record_pre_action 07:28:18 INFO - Running main action method: read_buildbot_config 07:28:18 INFO - Using buildbot properties: 07:28:18 INFO - { 07:28:18 INFO - "project": "", 07:28:18 INFO - "product": "firefox", 07:28:18 INFO - "who": "cbook@mozilla.com", 07:28:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 07:28:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 07:28:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:28:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 07:28:18 INFO - "stage_platform": "macosx64", 07:28:18 INFO - "basedir": "/builds/slave/test", 07:28:18 INFO - "buildnumber": 58, 07:28:18 INFO - "platform": "yosemite_r7", 07:28:18 INFO - "parent_task_id": "M0nmZ_V0RrSt865DFz2fHg", 07:28:18 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:28:18 INFO - "slavebuilddir": "test", 07:28:18 INFO - "taskId": "NYsm77kjSO-vZpf7m_GT-w", 07:28:18 INFO - "branch": "mozilla-central", 07:28:18 INFO - "script_repo_revision": "production", 07:28:18 INFO - "revision": "b9a4a6e2292e923684c2d25b78addaca23c6abd5", 07:28:18 INFO - "slavename": "t-yosemite-r7-0317", 07:28:18 INFO - "repo_path": "mozilla-central" 07:28:18 INFO - } 07:28:18 INFO - Finding installer, test and symbols from parent task. 07:28:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NYsm77kjSO-vZpf7m_GT-w'}, attempt #1 07:28:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NYsm77kjSO-vZpf7m_GT-w'}, attempt #1 07:28:19 INFO - Task dependencies: BI0EdzwPQTSAlT7CrYQ32A 07:28:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg'}, attempt #1 07:28:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:28:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/test_packages.json 07:28:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/target.crashreporter-symbols.zip 07:28:19 INFO - Running post-action listener: _resource_record_post_action 07:28:19 INFO - [mozharness: 2017-05-16 14:28:19.922233Z] Finished read-buildbot-config step (success) 07:28:19 INFO - [mozharness: 2017-05-16 14:28:19.922377Z] Running download-and-extract step. 07:28:19 INFO - Running pre-action listener: _resource_record_pre_action 07:28:19 INFO - Running main action method: download_and_extract 07:28:19 INFO - mkdir: /builds/slave/test/build/tests 07:28:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:28:19 INFO - trying https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 07:28:19 INFO - Downloading https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/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 07:28:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/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 07:28:20 INFO - Downloaded 1325 bytes. 07:28:20 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 07:28:20 INFO - Using the following test package requirements: 07:28:20 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 07:28:20 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:28:20 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 07:28:20 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 07:28:20 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'jsshell-mac.zip'], 07:28:20 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 07:28:20 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 07:28:20 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 07:28:20 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 07:28:20 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 07:28:20 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 07:28:20 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 07:28:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:28:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:28:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:28:20 INFO - Fetch https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:28:22 INFO - Content-Length response header: 56566471 07:28:22 INFO - Bytes received: 56566471 07:28:40 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 07:28:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 07:28:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:28:40 INFO - Fetch https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 07:28:42 INFO - Content-Length response header: 56566471 07:28:42 INFO - Bytes received: 56566471 07:28:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 07:28:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:28:58 INFO - Fetch https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 07:29:00 INFO - Content-Length response header: 14410069 07:29:00 INFO - Bytes received: 14410069 07:29: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')]} 07:29:00 INFO - trying https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:29:00 INFO - Downloading https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 07:29:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/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 07:29:02 INFO - Downloaded 75954987 bytes. 07:29:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:29:02 INFO - mkdir: /builds/slave/test/properties 07:29:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:29:02 INFO - Writing to file /builds/slave/test/properties/build_url 07:29:02 INFO - Contents: 07:29:02 INFO - build_url:https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 07:29:02 INFO - Running post-action listener: _resource_record_post_action 07:29:02 INFO - Running post-action listener: set_extra_try_arguments 07:29:02 INFO - [mozharness: 2017-05-16 14:29:02.690656Z] Finished download-and-extract step (success) 07:29:02 INFO - [mozharness: 2017-05-16 14:29:02.690802Z] Running populate-webroot step. 07:29:02 INFO - Running pre-action listener: _resource_record_pre_action 07:29:02 INFO - Running main action method: populate_webroot 07:29:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:29:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:29:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:29:02 INFO - '--tppagecycles', 07:29:02 INFO - '7'], 07:29:02 INFO - 'tests': ['tsvgm']}}, 07:29:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:29:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:29:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:29:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:29:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:29:02 INFO - 'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:29:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:29:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:29:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:29:02 INFO - 'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['damp', 'tps']}, 07:29:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:29:02 INFO - 'tests': ['damp', 'tps']}, 07:29:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['dromaeo_dom']}, 07:29:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:29:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 07:29:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 07:29:02 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 07:29:02 INFO - 'tests': ['ts_paint', 'tp5o']}, 07:29:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['a11yr', 07:29:02 INFO - 'ts_paint', 07:29:02 INFO - 'tpaint', 07:29:02 INFO - 'sessionrestore', 07:29:02 INFO - 'sessionrestore_no_auto_restore', 07:29:02 INFO - 'tabpaint']}, 07:29:02 INFO - 'other-e10s': {'tests': ['a11yr', 07:29:02 INFO - 'ts_paint', 07:29:02 INFO - 'tpaint', 07:29:02 INFO - 'sessionrestore', 07:29:02 INFO - 'sessionrestore_no_auto_restore', 07:29:02 INFO - 'tabpaint']}, 07:29:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:29:02 INFO - 'ts_paint', 07:29:02 INFO - 'tpaint', 07:29:02 INFO - 'sessionrestore', 07:29:02 INFO - 'sessionrestore_no_auto_restore', 07:29:02 INFO - 'tabpaint']}, 07:29:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:29:02 INFO - 'ts_paint', 07:29:02 INFO - 'tpaint', 07:29:02 INFO - 'sessionrestore', 07:29:02 INFO - 'sessionrestore_no_auto_restore', 07:29:02 INFO - 'tabpaint']}, 07:29:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['a11yr', 07:29:02 INFO - 'ts_paint', 07:29:02 INFO - 'tpaint', 07:29:02 INFO - 'sessionrestore', 07:29:02 INFO - 'sessionrestore_no_auto_restore', 07:29:02 INFO - 'tabpaint']}, 07:29:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['a11yr', 07:29:02 INFO - 'ts_paint', 07:29:02 INFO - 'tpaint', 07:29:02 INFO - 'sessionrestore', 07:29:02 INFO - 'sessionrestore_no_auto_restore', 07:29:02 INFO - 'tabpaint']}, 07:29:02 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 07:29:02 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 07:29:02 INFO - 'bloom_basic_ref']}, 07:29:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['tsvgx', 07:29:02 INFO - 'tsvgr_opacity', 07:29:02 INFO - 'tart', 07:29:02 INFO - 'tscrollx', 07:29:02 INFO - 'cart', 07:29:02 INFO - 'tsvg_static']}, 07:29:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:29:02 INFO - 'tsvgr_opacity', 07:29:02 INFO - 'tart', 07:29:02 INFO - 'tscrollx', 07:29:02 INFO - 'cart', 07:29:02 INFO - 'tsvg_static']}, 07:29:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:29:02 INFO - 'talos_options': ['--disable-e10s'], 07:29:02 INFO - 'tests': ['tp5o']}, 07:29:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:29:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:29:02 INFO - 'talos_options': ['--disable-e10s', 07:29:02 INFO - '--xperf_path', 07:29:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:29:02 INFO - 'tests': ['tp5n']}, 07:29:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:29:02 INFO - 'talos_options': ['--xperf_path', 07:29:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:29:02 INFO - 'tests': ['tp5n']}}, 07:29:02 INFO - 'talos.zip': {'path': '', 07:29:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:29:02 INFO - Running post-action listener: _resource_record_post_action 07:29:02 INFO - [mozharness: 2017-05-16 14:29:02.713324Z] Finished populate-webroot step (success) 07:29:02 INFO - [mozharness: 2017-05-16 14:29:02.713469Z] Running create-virtualenv step. 07:29:02 INFO - Running pre-action listener: _resource_record_pre_action 07:29:02 INFO - Running main action method: create_virtualenv 07:29:02 INFO - Creating virtualenv /builds/slave/test/build/venv 07:29:02 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 07:29:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:29:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:29:02 INFO - Using real prefix '/tools/python27' 07:29:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:29:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:29:06 INFO - Installing pip.................done. 07:29:06 INFO - Return code: 0 07:29:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:29:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:29:06 INFO - Reading from file tmpfile_stdout 07:29:06 INFO - Output received: 07:29:06 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) 07:29:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:29:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:07 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')]} 07:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:07 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 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:07 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 07:29:07 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 07:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:07 INFO - 'HOME': '/Users/cltbld', 07:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:07 INFO - 'LOGNAME': 'cltbld', 07:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:07 INFO - 'MOZ_NO_REMOTE': '1', 07:29:07 INFO - 'NO_EM_RESTART': '1', 07:29:07 INFO - 'PAGER': '/bin/cat', 07:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:07 INFO - 'PWD': '/builds/slave/test', 07:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:07 INFO - 'SHELL': '/bin/bash', 07:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:07 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:07 INFO - 'USER': 'cltbld', 07:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:07 INFO - 'XPC_FLAGS': '0x0', 07:29:07 INFO - 'XPC_SERVICE_NAME': '0', 07:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:08 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 07:29:08 INFO - Cleaning up... 07:29:08 INFO - Return code: 0 07:29:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:29: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')]} 07:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29: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')]} 07:29:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:29:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:29:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:08 INFO - 'HOME': '/Users/cltbld', 07:29:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:08 INFO - 'LOGNAME': 'cltbld', 07:29:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:08 INFO - 'MOZ_NO_REMOTE': '1', 07:29:08 INFO - 'NO_EM_RESTART': '1', 07:29:08 INFO - 'PAGER': '/bin/cat', 07:29:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:08 INFO - 'PWD': '/builds/slave/test', 07:29:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:08 INFO - 'SHELL': '/bin/bash', 07:29:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:08 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:08 INFO - 'USER': 'cltbld', 07:29:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:08 INFO - 'XPC_FLAGS': '0x0', 07:29:08 INFO - 'XPC_SERVICE_NAME': '0', 07:29:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:08 INFO - Downloading/unpacking psutil>=3.1.1 07:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:29:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:29:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:29:10 INFO - Installing collected packages: psutil 07:29:10 INFO - Running setup.py install for psutil 07:29:11 INFO - building 'psutil._psutil_osx' extension 07:29:11 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 07:29:11 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 07:29:11 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 07:29:11 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 07:29:11 INFO - building 'psutil._psutil_posix' extension 07:29:11 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 07:29:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:29:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:29:11 INFO - ^ 07:29:11 INFO - 1 warning generated. 07:29:11 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 07:29:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:29:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:29:12 INFO - Successfully installed psutil 07:29:12 INFO - Cleaning up... 07:29:12 INFO - Return code: 0 07:29:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:29: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')]} 07:29:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29: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')]} 07:29:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:29:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:29:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:12 INFO - 'HOME': '/Users/cltbld', 07:29:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:12 INFO - 'LOGNAME': 'cltbld', 07:29:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:12 INFO - 'MOZ_NO_REMOTE': '1', 07:29:12 INFO - 'NO_EM_RESTART': '1', 07:29:12 INFO - 'PAGER': '/bin/cat', 07:29:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:12 INFO - 'PWD': '/builds/slave/test', 07:29:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:12 INFO - 'SHELL': '/bin/bash', 07:29:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:12 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:12 INFO - 'USER': 'cltbld', 07:29:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:12 INFO - 'XPC_FLAGS': '0x0', 07:29:12 INFO - 'XPC_SERVICE_NAME': '0', 07:29:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:29: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 07:29: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 07:29: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 07:29: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 07:29:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:29:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:29:13 INFO - Installing collected packages: mozsystemmonitor 07:29:13 INFO - Running setup.py install for mozsystemmonitor 07:29:13 INFO - Successfully installed mozsystemmonitor 07:29:13 INFO - Cleaning up... 07:29:14 INFO - Return code: 0 07:29:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:29: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')]} 07:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29: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')]} 07:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29: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==2.5.1'] in /builds/slave/test/build 07:29: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==2.5.1 07:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:14 INFO - 'HOME': '/Users/cltbld', 07:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:14 INFO - 'LOGNAME': 'cltbld', 07:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:14 INFO - 'MOZ_NO_REMOTE': '1', 07:29:14 INFO - 'NO_EM_RESTART': '1', 07:29:14 INFO - 'PAGER': '/bin/cat', 07:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:14 INFO - 'PWD': '/builds/slave/test', 07:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:14 INFO - 'SHELL': '/bin/bash', 07:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:14 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:14 INFO - 'USER': 'cltbld', 07:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:14 INFO - 'XPC_FLAGS': '0x0', 07:29:14 INFO - 'XPC_SERVICE_NAME': '0', 07:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:14 INFO - Downloading/unpacking jsonschema==2.5.1 07:29:14 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 07:29:14 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 07:29:14 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 07:29:14 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 07:29:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:29:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:29:15 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 07:29:15 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 07:29:15 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 07:29:15 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 07:29:16 INFO - Downloading functools32-3.2.3-2.tar.gz 07:29:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:29:16 INFO - warning: no files found matching '*.txt' 07:29:16 INFO - no previously-included directories found matching 'build' 07:29:16 INFO - no previously-included directories found matching 'dist' 07:29:16 INFO - no previously-included directories found matching '.git*' 07:29:16 INFO - Installing collected packages: jsonschema, functools32 07:29:16 INFO - Running setup.py install for functools32 07:29:16 INFO - warning: no files found matching '*.txt' 07:29:16 INFO - no previously-included directories found matching 'build' 07:29:16 INFO - no previously-included directories found matching 'dist' 07:29:16 INFO - no previously-included directories found matching '.git*' 07:29:16 INFO - Successfully installed jsonschema functools32 07:29:16 INFO - Cleaning up... 07:29:16 INFO - Return code: 0 07:29:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:16 INFO - 'HOME': '/Users/cltbld', 07:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:16 INFO - 'LOGNAME': 'cltbld', 07:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:16 INFO - 'MOZ_NO_REMOTE': '1', 07:29:16 INFO - 'NO_EM_RESTART': '1', 07:29:16 INFO - 'PAGER': '/bin/cat', 07:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:16 INFO - 'PWD': '/builds/slave/test', 07:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:16 INFO - 'SHELL': '/bin/bash', 07:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:16 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:16 INFO - 'USER': 'cltbld', 07:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:16 INFO - 'XPC_FLAGS': '0x0', 07:29:16 INFO - 'XPC_SERVICE_NAME': '0', 07:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:29:16 INFO - Cleaning up... 07:29:16 INFO - Return code: 0 07:29:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:29:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:29:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:16 INFO - 'HOME': '/Users/cltbld', 07:29:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:16 INFO - 'LOGNAME': 'cltbld', 07:29:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:16 INFO - 'MOZ_NO_REMOTE': '1', 07:29:16 INFO - 'NO_EM_RESTART': '1', 07:29:16 INFO - 'PAGER': '/bin/cat', 07:29:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:16 INFO - 'PWD': '/builds/slave/test', 07:29:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:16 INFO - 'SHELL': '/bin/bash', 07:29:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:16 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:16 INFO - 'USER': 'cltbld', 07:29:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:16 INFO - 'XPC_FLAGS': '0x0', 07:29:16 INFO - 'XPC_SERVICE_NAME': '0', 07:29:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:16 INFO - Downloading/unpacking blobuploader==1.2.4 07:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:18 INFO - Downloading blobuploader-1.2.4.tar.gz 07:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:29:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:29:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:19 INFO - Downloading docopt-0.6.1.tar.gz 07:29:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:29:19 INFO - Installing collected packages: blobuploader, requests, docopt 07:29:19 INFO - Running setup.py install for blobuploader 07:29:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:29:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:29:19 INFO - Running setup.py install for requests 07:29:19 INFO - Running setup.py install for docopt 07:29:20 INFO - Successfully installed blobuploader requests docopt 07:29:20 INFO - Cleaning up... 07:29:20 INFO - Return code: 0 07:29:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:20 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 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:20 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 07:29:20 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 07:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:20 INFO - 'HOME': '/Users/cltbld', 07:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:20 INFO - 'LOGNAME': 'cltbld', 07:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:20 INFO - 'MOZ_NO_REMOTE': '1', 07:29:20 INFO - 'NO_EM_RESTART': '1', 07:29:20 INFO - 'PAGER': '/bin/cat', 07:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:20 INFO - 'PWD': '/builds/slave/test', 07:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:20 INFO - 'SHELL': '/bin/bash', 07:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:20 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:20 INFO - 'USER': 'cltbld', 07:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:20 INFO - 'XPC_FLAGS': '0x0', 07:29:20 INFO - 'XPC_SERVICE_NAME': '0', 07:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:29:20 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-DNrVIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:29:20 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-YadhiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:29:20 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ryKfw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:29:20 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Mlx48j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:29:20 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-n1Z66i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:29:20 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-nOUwfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-UQnEZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-6K146C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-_lILmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ytGMx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-ilpVe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-8IoSKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-5m9hxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-y_I6dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-W1xFZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:29:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-JRDU5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:29:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-VpX_ln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:29:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:29:22 INFO - Running setup.py install for manifestparser 07:29:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:29:22 INFO - Running setup.py install for mozcrash 07:29:22 INFO - Running setup.py install for mozdebug 07:29:22 INFO - Running setup.py install for mozdevice 07:29:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:29:22 INFO - Running setup.py install for mozfile 07:29:22 INFO - Running setup.py install for mozhttpd 07:29:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:29:23 INFO - Running setup.py install for mozinfo 07:29:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:29:23 INFO - Running setup.py install for mozInstall 07:29:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:29:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:29:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:29:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:29:23 INFO - Running setup.py install for mozleak 07:29:23 INFO - Running setup.py install for mozlog 07:29:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:29:23 INFO - Running setup.py install for moznetwork 07:29:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:29:23 INFO - Running setup.py install for mozprocess 07:29:23 INFO - Running setup.py install for mozprofile 07:29:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:29:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:29:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:29:24 INFO - Running setup.py install for mozrunner 07:29:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:29:24 INFO - Running setup.py install for mozscreenshot 07:29:24 INFO - Running setup.py install for moztest 07:29:24 INFO - Running setup.py install for mozversion 07:29:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:29:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:29:24 INFO - Cleaning up... 07:29:24 INFO - Return code: 0 07:29:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:24 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 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:24 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 07:29:24 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 07:29:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:24 INFO - 'HOME': '/Users/cltbld', 07:29:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:24 INFO - 'LOGNAME': 'cltbld', 07:29:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:24 INFO - 'MOZ_NO_REMOTE': '1', 07:29:24 INFO - 'NO_EM_RESTART': '1', 07:29:24 INFO - 'PAGER': '/bin/cat', 07:29:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:24 INFO - 'PWD': '/builds/slave/test', 07:29:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:24 INFO - 'SHELL': '/bin/bash', 07:29:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:24 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:24 INFO - 'USER': 'cltbld', 07:29:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:24 INFO - 'XPC_FLAGS': '0x0', 07:29:24 INFO - 'XPC_SERVICE_NAME': '0', 07:29:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-RC2NBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:29:25 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)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-kG89Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:29:25 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)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-hzVq1I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:29:25 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)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-lLZDEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:29:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-uPpYwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:29:25 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)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-mGaEzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:29:25 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)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-5tBa7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:29:25 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)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-h8b7IL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:29:25 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)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-wzscyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:29:25 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)) 07:29:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:29:25 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-s4mUik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:29:26 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)) 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:29:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-s5bIMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:29:26 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)) 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:29:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-g3YZ_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:29:26 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)) 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:29:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-hDq_oI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:29:26 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)) 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:29:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Jq6rBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:29:26 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)) 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:29:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-d5L7OF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:29:26 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)) 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:29:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-nmUYny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:29:26 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)) 07:29:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:29:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-frJbV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:29:26 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)) 07:29:26 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)) 07:29:26 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)) 07:29:26 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)) 07:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:29:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:29:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:29:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:29:28 INFO - Downloading blessings-1.6.tar.gz 07:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:29:28 INFO - Installing collected packages: blessings 07:29:28 INFO - Running setup.py install for blessings 07:29:28 INFO - Successfully installed blessings 07:29:28 INFO - Cleaning up... 07:29:28 INFO - Return code: 0 07:29:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:29:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:29:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:29:28 INFO - Reading from file tmpfile_stdout 07:29:28 INFO - Current package versions: 07:29:28 INFO - blessings == 1.6 07:29:28 INFO - blobuploader == 1.2.4 07:29:28 INFO - docopt == 0.6.1 07:29:28 INFO - functools32 == 3.2.3-2 07:29:28 INFO - jsonschema == 2.5.1 07:29:28 INFO - manifestparser == 1.1 07:29:28 INFO - mozInstall == 1.12 07:29:28 INFO - mozcrash == 1.0 07:29:28 INFO - mozdebug == 0.1 07:29:28 INFO - mozdevice == 0.50 07:29:28 INFO - mozfile == 1.2 07:29:28 INFO - mozhttpd == 0.7 07:29:28 INFO - mozinfo == 0.9 07:29:28 INFO - mozleak == 0.1 07:29:28 INFO - mozlog == 3.4 07:29:28 INFO - moznetwork == 0.27 07:29:28 INFO - mozprocess == 0.25 07:29:28 INFO - mozprofile == 0.28 07:29:28 INFO - mozrunner == 6.13 07:29:28 INFO - mozscreenshot == 0.1 07:29:28 INFO - mozsystemmonitor == 0.3 07:29:28 INFO - moztest == 0.8 07:29:28 INFO - mozversion == 1.4 07:29:28 INFO - psutil == 3.1.1 07:29:28 INFO - requests == 1.2.3 07:29:28 INFO - wsgiref == 0.1.2 07:29:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:29:28 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')]} 07:29:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:28 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')]} 07:29:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:28 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 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:28 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 07:29:28 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 07:29:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:28 INFO - 'HOME': '/Users/cltbld', 07:29:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:28 INFO - 'LOGNAME': 'cltbld', 07:29:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:28 INFO - 'MOZ_NO_REMOTE': '1', 07:29:28 INFO - 'NO_EM_RESTART': '1', 07:29:28 INFO - 'PAGER': '/bin/cat', 07:29:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:28 INFO - 'PWD': '/builds/slave/test', 07:29:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:28 INFO - 'SHELL': '/bin/bash', 07:29:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:28 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:28 INFO - 'USER': 'cltbld', 07:29:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:28 INFO - 'XPC_FLAGS': '0x0', 07:29:28 INFO - 'XPC_SERVICE_NAME': '0', 07:29:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 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)) 07:29:29 INFO - Cleaning up... 07:29:29 INFO - Return code: 0 07:29:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:29:29 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 0x10c330b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c334768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a6159e6d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, '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.qZJYwtRh5H/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.NCpilZCDXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 07:29:29 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 07:29:29 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 07:29:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:29 INFO - 'HOME': '/Users/cltbld', 07:29:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:29 INFO - 'LOGNAME': 'cltbld', 07:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:29 INFO - 'MOZ_NO_REMOTE': '1', 07:29:29 INFO - 'NO_EM_RESTART': '1', 07:29:29 INFO - 'PAGER': '/bin/cat', 07:29:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:29 INFO - 'PWD': '/builds/slave/test', 07:29:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:29 INFO - 'SHELL': '/bin/bash', 07:29:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:29 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:29 INFO - 'USER': 'cltbld', 07:29:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:29 INFO - 'XPC_FLAGS': '0x0', 07:29:29 INFO - 'XPC_SERVICE_NAME': '0', 07:29:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:29:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:29:29 INFO - Cleaning up... 07:29:29 INFO - Return code: 0 07:29:29 INFO - Running post-action listener: _resource_record_post_action 07:29:29 INFO - Running post-action listener: _start_resource_monitoring 07:29:29 INFO - Starting resource monitoring. 07:29:29 INFO - [mozharness: 2017-05-16 14:29:29.395248Z] Finished create-virtualenv step (success) 07:29:29 INFO - [mozharness: 2017-05-16 14:29:29.396177Z] Running install step. 07:29:29 INFO - Running pre-action listener: _resource_record_pre_action 07:29:29 INFO - Running main action method: install 07:29:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:29:29 INFO - Reading from file tmpfile_stdout 07:29:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:29:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:29:29 INFO - Reading from file tmpfile_stdout 07:29:29 INFO - Output received: 07:29:29 INFO - Usage: mozinstall [options] installer 07:29:29 INFO - Options: 07:29:29 INFO - -h, --help show this help message and exit 07:29:29 INFO - -d DEST, --destination=DEST 07:29:29 INFO - Directory to install application into. [default: 07:29:29 INFO - "/builds/slave/test"] 07:29:29 INFO - --app=APP Application being installed. [default: firefox] 07:29:29 INFO - mkdir: /builds/slave/test/build/application 07:29:29 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'] 07:29:29 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 07:29:50 INFO - Reading from file tmpfile_stdout 07:29:50 INFO - Output received: 07:29:50 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:29:50 INFO - Running post-action listener: _resource_record_post_action 07:29:50 INFO - [mozharness: 2017-05-16 14:29:50.461294Z] Finished install step (success) 07:29:50 INFO - [mozharness: 2017-05-16 14:29:50.461471Z] Running run-tests step. 07:29:50 INFO - Running pre-action listener: _resource_record_pre_action 07:29:50 INFO - Running main action method: run_tests 07:29:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:29:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:29:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:29:50 INFO - 2017-05-16 07:29:50.475 screenresolution[1625:7333] starting screenresolution argv=screenresolution get 07:29:50 INFO - 2017-05-16 07:29:50.478 screenresolution[1625:7333] Display 0: 1600x1200x32@60 07:29:50 INFO - 2017-05-16 07:29:50.484 screenresolution[1626:7335] starting screenresolution argv=screenresolution list 07:29:50 INFO - Available Modes on Display 0 07:29:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:29:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:29:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:29:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:29:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:29:50 INFO - Intel Iris: 07:29:50 INFO - Chipset Model: Intel Iris 07:29:50 INFO - Type: GPU 07:29:50 INFO - Bus: Built-In 07:29:50 INFO - VRAM (Dynamic, Max): 1536 MB 07:29:50 INFO - Vendor: Intel (0x8086) 07:29:50 INFO - Device ID: 0x0a2e 07:29:50 INFO - Revision ID: 0x0009 07:29:50 INFO - Displays: 07:29:50 INFO - Display: 07:29:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:29:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:29:50 INFO - Display Serial Number: 1600x1200 60 07:29:50 INFO - Main Display: Yes 07:29:50 INFO - Mirror: Off 07:29:50 INFO - Online: Yes 07:29:50 INFO - Rotation: Supported 07:29:50 INFO - Return code: 0 07:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:29:50 INFO - Python 2.7.3 07:29:50 INFO - Return code: 0 07:29:50 INFO - grabbing minidump binary from tooltool 07:29:50 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')]} 07:29:50 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 0x10c040d30>, '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 0x10bff4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c035be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:29:50 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 07:29:50 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 07:29:50 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 07:29:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:29:50 INFO - Return code: 0 07:29:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:29:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:29:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:29:50 INFO - ENV: RUST_BACKTRACE is now 1 07:29:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:29:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:29:50 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/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--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 07:29:50 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/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0317 --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 07:29:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qZJYwtRh5H/Render', 07:29:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:29:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:29:50 INFO - 'HOME': '/Users/cltbld', 07:29:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:29:50 INFO - 'LOGNAME': 'cltbld', 07:29:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:29:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:29:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:29:50 INFO - 'MOZ_NO_REMOTE': '1', 07:29:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:29:50 INFO - 'NO_EM_RESTART': '1', 07:29:50 INFO - 'PAGER': '/bin/cat', 07:29:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:29:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:29:50 INFO - 'PWD': '/builds/slave/test', 07:29:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:29:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:29:50 INFO - 'RUST_BACKTRACE': '1', 07:29:50 INFO - 'SHELL': '/bin/bash', 07:29:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NCpilZCDXa/Listeners', 07:29:50 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 07:29:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:29:50 INFO - 'USER': 'cltbld', 07:29:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:29:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:29:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:29:50 INFO - 'XPC_FLAGS': '0x0', 07:29:50 INFO - 'XPC_SERVICE_NAME': '0', 07:29:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:29:50 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/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--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 07:29:51 INFO - mozversion application_buildid: 20170516060844 07:29:51 INFO - mozversion application_changeset: b9a4a6e2292e923684c2d25b78addaca23c6abd5 07:29:51 INFO - mozversion application_display_name: Nightly 07:29:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:29:51 INFO - mozversion application_name: Firefox 07:29:51 INFO - mozversion application_remotingname: firefox 07:29:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:29:51 INFO - mozversion application_vendor: Mozilla 07:29:51 INFO - mozversion application_version: 55.0a1 07:29:51 INFO - mozversion platform_buildid: 20170516060844 07:29:51 INFO - mozversion platform_changeset: b9a4a6e2292e923684c2d25b78addaca23c6abd5 07:29:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:29:51 INFO - mozversion platform_version: 55.0a1 07:29:51 INFO - using testdate: 1494944991 07:29:51 INFO - actual date: 1494944991 07:29:51 INFO - starting webserver on 'localhost:49219' 07:29:51 INFO - SUITE-START | Running 2 tests 07:29:51 INFO - TEST-START | dromaeo_css 07:29:51 INFO - operating with platform_type : mac_ 07:29:51 INFO - Initialising browser for dromaeo_css test... 07:29:51 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmp3QZGyB/profile http://localhost:49219/getInfo.html) 07:29:51 INFO - PID 1636 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 07:29:53 INFO - PID 1636 | 2017-05-16 07:29:53.739 plugin-container[1638:7495] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 07:29:53 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:29:53 INFO - PID 1636 | 2017-05-16 07:29:53.742 plugin-container[1638:7495] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.CFPasteboardClient' 07:29:53 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:29:53 INFO - PID 1636 | 2017-05-16 07:29:53.742 plugin-container[1638:7495] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:29:53 INFO - PID 1636 | __metrics Screen width/height:1600/1200 07:29:53 INFO - PID 1636 | colorDepth:24 07:29:53 INFO - PID 1636 | Browser inner width/height: 1024/691 07:29:53 INFO - PID 1636 | __metrics 07:29:54 INFO - PID 1636 | 2017-05-16 07:29:54.250 plugin-container[1639:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 07:29:54 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:29:54 INFO - PID 1636 | 2017-05-16 07:29:54.251 plugin-container[1639:7547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 07:29:54 INFO - PID 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:29:54 INFO - PID 1636 | 2017-05-16 07:29:54.251 plugin-container[1639:7547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:29:54 INFO - TEST-INFO | 1636: exit 0 07:29:54 INFO - Browser initialized. 07:29:54 INFO - Running cycle 1/1 for dromaeo_css test... 07:29:54 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmp3QZGyB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:29:56 INFO - PID 1640 | 2017-05-16 07:29:56.796 plugin-container[1641:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4453, name = 'com.apple.tsm.portname' 07:29:56 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:29:56 INFO - PID 1640 | 2017-05-16 07:29:56.797 plugin-container[1641:7647] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 07:29:56 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:29:56 INFO - PID 1640 | 2017-05-16 07:29:56.797 plugin-container[1641:7647] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:29:57 INFO - PID 1640 | 2017-05-16 07:29:57.317 plugin-container[1642:7693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 07:29:57 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:29:57 INFO - PID 1640 | 2017-05-16 07:29:57.318 plugin-container[1642:7693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 07:29:57 INFO - PID 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:29:57 INFO - PID 1640 | 2017-05-16 07:29:57.318 plugin-container[1642:7693] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:30:06 INFO - PID 1640 | RSS: Main: 234283008 07:30:06 INFO - PID 1640 | 07:33:10 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 07:33:12 INFO - PID 1640 | RSS: Main: 262131712 07:33:12 INFO - PID 1640 | 07:36:14 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 07:36:14 INFO - PID 1640 | RSS: Main: 268890112 07:36:14 INFO - PID 1640 | 07:39:16 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 07:39:17 INFO - PID 1640 | RSS: Main: 272375808 07:39:17 INFO - PID 1640 | 07:42:18 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 07:42:19 INFO - PID 1640 | RSS: Main: 270082048 07:42:19 INFO - PID 1640 | 07:45:22 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 07:45:22 INFO - PID 1640 | RSS: Main: 271605760 07:45:22 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 07:48:24 INFO - PID 1640 | RSS: Main: 272838656 07:48:24 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | __start_tp_report 07:48:24 INFO - PID 1640 | _x_x_mozilla_page_load 07:48:24 INFO - PID 1640 | _x_x_mozilla_page_load_details 07:48:24 INFO - PID 1640 | |i|pagename|runs| 07:48:24 INFO - PID 1640 | |0;dojo.html;3570.2502474480357;3578.2127931855;3607.368710475666;3629.0383048492154;3631.4915911772355;6101.176341193948;6174.876502469948;6234.064890266457;6240.219972503437;6257.092721555372;6195.721192546335;6204.4416002559765;6205.968970155143;6246.437820596146;6307.085472606469;5412.404635490093;5418.431064738204;5429.647072940266;5430.484104010133;5448.855740294533;5514.172874068881;5582.804601838937;5590.077637189859;5644.57665675074;5685.516731077863;5402.459754024566;5490.478404551562;5507.284053073115;5553.611247212697;5574.080276754351;5419.756110975016;5423.511883930426;5454.809081682122;5475.753591088371;5552.666839989573;5480.287562616834;5491.395946445888;5506.752196151143;5522.447755224485;5548.500634942834;6169.7532098716;6194.380561943814;6269.122322874801;6278;6284.585968157174;6354.523410744213;6687.966560167217;6717.56335838169;6743.93256067438;6756.8310792230095;6618.271990081087;6684.83287917801;6793.388595026422;6799.932000680029;6826.761063362758;6317;6332.746690132435;6340.048992651093;6342.270638876496;6356.364363563653;6979.581225126509;6982.47631427645;6999.09011828459;7005.159380874327;7019.122609673791;6367.267764207083;6414.422701956846;6448.161738973903;6488.675566221765;6561.704723287464;6745.831354216184;6780.559263647946;6788.864222715518;6791.830204244933;6811.25076241613;6441.774537891245;6497.415232629086;6523.510736694767;6623.9006414903815;6727.360900714424;6697.062411262427;6709.429698475684;6720.059191713164;6725.024871393718;6734.023566582818;5279.102552565996;5340;5341.706206158621;5357.330333708286;5385.865353366119;4756.524347565216;4770.260609605517;4855.878603034952;4867.61059115277;4884.169691152512;6398.392152745481;6398.424141827258;6403.583767055126;6418.518611104186;6418.614883107029;5783.277090363705;6249.312575616678;6282.523606952363;6778.864422711518;6789.355011273921;6268.965620672538;6272.937270627326;6295.37046295376;6328.145700330397;6376.266729326094;5894.145348924468;5918.260217472816;5933.525317974551;5949.851253718605;5999.760009599567;6936.167659880846;6939.5990498056835;6984.755533556301;6987.441004719712;6990.09128813251;6560.868782624416;6655.800325990228;6661.500387470862;6789.762358317633;6813.931860681528;5776.624519406225;6323.841903952438;6471.870562588816;6996.405305549085;7001.789946301619;6671.132752742113;6679.265280819203;6686.9331306688255;6689.732410703517;6695.09616201826;6541.215054193527;6709.0705755481185;6716.093327400738;6745.0219718139615;6804.285550017177;6465.806025819233;6516.771912983023;6583.670816459254;6586.473082153512;6623.039659249478;6576.605403675795;6601.141851559267;6608.339166083353;6616.437602803816;6660.100886380276;5327.706976116196;6199.907001395073;6366.586171898812;6425.9036114457385;6449.871002580016;6538.542302038812;6574.0796288520605;6604.570702904296;6613.437857782143;6619.867602647823;6377.585456945284;6422.935770642233;6450.838849007224;6457.61254324742;6469.644169570719 07:48:24 INFO - PID 1640 | |1;ext.html;688.4320435640597;735.738812721484;764.8929149919012;1038.5534220285274;1193.8686744458107;37621.81189094054;38028.619713802924;38089.61910380895;38185.80907095464;38267.61732382675;38223.23553528928;38572;38583.421248681225;38641.806790966075;38695.8391248263;7797.142314345418;7951.602419879012;8083.151269116746;8204.630791614361;8278.213569710868;8200.876986845202;8327.458715183524;8328.042275138376;8329.583520823964;8342.499450032987;6952.895706564405;8303.83392332153;8385.413021088527;8392.958035209815;8399;8306.169383061706;8319.251267385964;8343.123971982915;8347.040090389622;8378.245957863792;8105.918940810576;8178.754637360888;8184.508929464251;8186.508809471451;8187.5087494750505;35812;35857.103572410895;35909.28181436357;35927.10182245465;35971.4604280936;3586.067622418164;3591.9281614367565;3628.1655219299446;3630.9273814523826;3634.3821550336506;21506.462338441506;28069.736861841266;29655.70344296551;29715.40569188626;29831.40337193244;3081.3529158876663;3111.0977816433206;3113.7353324967403;3124.906252812419;3126.1559378967777;304085;304201;304224;315660;324462;42468;44642;47553.286700699515;47557;47711.5228847714;40620.593794062275;49311.50688493141;49548.50451495511;50429.24356134661;51291;13638.795418068737;13791.724165516633;13877.514286999907;13882.583522494342;13892.305384730926;11102.44487775608;11114.44427778624;11138.050841102775;11150.442477876073;11180.385078820744;13243.404046817916;13246.271455070064;13356.46574137043;13384.397702103428;13466.192028478323;5337.2794672719465;5679.8924209779525;5912.201852749909;5930.377310382434;5948.256467941507;876.3821505838496;969.8108868770663;995.8506224066448;1008.2135933971514;1043.1758910460803;19444.58274004533;20602.58794824095;21520.892395538234;23541.7645823543;24341.26976190717;878.5387671472528;1020.8621836052038;1101.553870682375;1112.238116889933;1134.6652737442473;10011.699649010541;12392.318422486669;12411.62765117048;12472.875271247352;12636.873631263754;335506;335930;337453.3127334348;337498;338195;51736.48263517392;51758.70603234874;52161.95676086457;52422.73788631032;52561.7371913138;1486.51688201335;1491.7314883321105;1522.4290890915906;1536.2933050796535;1547.3655801121486;21805.89097054568;21832.89083554572;21891.686498810122;23898.283051508482;24073.157439489532;38006.2398752029;38046.239075218895;38096.80951595224;38101.23797523978;38109.80945095257;280462.59768701025;280510;280540;280703;282196.5890170536;33860.32279354448;34028;34052.48921266232;34062.934335909886;34126;42690.57309426866;43521.34717979169;43857.78071109624;45166.77416612896;45204.773976131226;25671;29407.411851763274;30122.24694382658;30900.381992360475;31317.217069573442;29944.700552994193;30632.387352252383;30828.845855771477;30846.69153308438;30878;35708.28583428265;35977.10057248485;36804.89585312445;36928;37011.25977480382;23217.651735224325;30174.84912575423;31278.90523831655;31358;31440.52839207368;22138.386093612975;23143.537129256983;24173.274801755975;24232.51534969326;24480.877595611906 07:48:24 INFO - PID 1640 | |2;jquery.html;425.93234609479464;429.67659608406115;436.6899501354038;442.7609091090811;455.48530160918176;20100.89949550252;20971.370858874237;21113.944302784857;21932.013059412326;22095.558088838214;19172.904135479337;20345.49136271594;22782.65826012611;23189.652155217642;23409;5996.79011234608;6252.062190671413;6256.217972753406;6269.717862696167;6274.59215151016;5865.648061116347;6095.969520152393;6154.199954005995;6155.230596175478;6169.352218017111;5895.469407753301;6109.5723299369065;6132.969335153341;6148.446639802417;6151.661658608775;6049.364816694249;6133.907991380132;6175.59858609189;6177.505799536027;6193.938060619404;4966.776495057695;5108.131617625013;5414.052540805344;5507.889842203173;5508.476694713996;26047.609285860726;27042.86478567614;27186.592201117;27242.863785681144;27379.863100684568;1993.5813479169392;2151.9892400537897;2247.820174386045;2253.2226381898217;2254.6505291679814;13653.522126725617;17682.823171768374;17831.73252401215;17936.372226972126;18382.356617518322;2138.4974530985205;2184.421128400975;2188.4310079379316;2201.4936564590234;2209.1384360099573;149988.2500587501;157983;169403;171370;173165.13417432958;9845.950770246103;11696.824547631792;11746.882531174579;12461.813072803916;12790.552330668383;13704.3833027514;16646.75029874553;16664.916675416545;16698.83301166997;16706;8819.603117859711;9117.560978029493;9119.498427586414;9188;9304.209142222851;8736.606852691644;8754.86867696985;8775.122487751174;8955.104489550993;8964.68623598171;7313.2321106284135;7334.339909408179;7814.413918956101;7953.920460795433;7996.280334769783;2183.7816218378034;2185.8579192352445;2193.100828660242;2193.6928829963817;2344.4607740219844;946.7633091727068;961.6730311694058;966.5891995901713;973.1484950668165;993.3592832623062;20256.49358766013;20711;20753.066112024993;20785.89607051955;21059.15763369448;967.4969016111583;993.6124911284605;998.6754304850953;1001.9048190421898;1023.1661196125134;10101.494925253855;10405.06354428105;10631.149508039338;10679.626213082505;10768.420843514656;541228;542043;542842;553389;553792;121053.39473302577;125845;131048.51611871028;136139;139348.30325848507;1098.7913295375183;1197.2457351868268;1221.9327936963552;1248.3446190749748;1256.0265794009715;20661.793382065985;20754.896225518776;21528.909507193584;22049.11803527841;22052.88973555186;54042.64893377697;55107;55166.44833551613;55270.170947435014;57883.421165787804;75269;75897.86153207817;76061.23938760762;76273.23726762661;76500;8784.604692788713;8785.023369741757;8836.160564746338;9210.447373157633;9261.398009129385;13823.930880345937;13862;14119.223442710749;14240.71518569602;14318.785218221943;10507.527161277609;11829.408529573659;12076.698082548006;12187.878121218675;12198.756024879276;10743.08683761889;11097.889021110008;11185.608503702331;11411.087113031104;11417.257878237888;10808.297460665031;11850.407479626158;11968.76062478763;12207.267563946192;12412.379381031093;8849.91150088491;8913.885764279465;8925.464472131693;8972.461652300883;8995;11911.106666999836;11946.283223006647;12071.698207544881;12180.269183848997;12296.262224266573 07:48:24 INFO - PID 1640 | |3;mootools.html;1341.6243451833486;1378.1386633354155;1393.4217299820575;1400.8529104444033;1412.8445870954197;11667.24162929409;12048.457819398138;12058.57794977176;12065.155439119266;12364.134510584263;11900;11921.940390298058;12086.335251561182;12106.334151621657;12110.878891211085;2574.279201823483;2615.882285297166;2632.9473410531778;2635.3675117971643;2635.868206589663;2240.5070884405486;2451.3381387025493;2549.1715192562397;2578.22653204039;2578.858162801045;2305.9538809223804;2480.838745481538;2567.5891857302836;2607.34816295926;2613.8431694098417;2537.8731063446844;2564.3204550794053;2595.2603508000225;2606.504764094835;2608.1914606472055;2533.1134103063782;2566.8203225774205;2599.7790187833866;2634.486275176341;2642.2469596165192;11704.648860534198;11740.236884602473;11830.645080647508;11863.46614402354;11870.940645296805;6613.867722645568;6769.153855768029;6784.0841486399195;6865;6868.828279293008;4131.400946862718;4451.888702782457;4485.1254005468945;4503.256962601167;4506.662000349987;1235.086036333116;1255.8492980842266;1294.8964082873347;1299.3048718935324;1300.09643297908;15586.06483610987;16077.839221607868;16401.83598164027;16455.177241137895;16585.25366358517;5516.172574113834;5599.580031497572;5613;5757.481826635623;5800.8259752207505;11530.308181509135;11531.250468719507;11540.942295288638;11541.307521548733;11586.246893951866;6496.675166241669;6539.50953678476;6551.279359270477;6557.475401967831;6586.110875031809;5973.492253158443;6179.69101544921;6213.627182369072;6295.685215739195;6304.275008374004;3739.6634302912867;4076.673866090706;4393.538678438782;4421.712588681726;4446.755428451402;1107.85044019058;1274.2927675140202;1278.520554791953;1302.7720148973892;1305.3212329588562;683.7435961514432;781.6287263549768;799.6361655446758;806.7378102116718;811.2941740685524;6153.4154255345675;6205.2553693557065;6283.497320214372;6354.015127655221;6388.233411990591;589.1633879890566;592.1236569876521;611.6574718157846;621.7544070092288;621.7699451202993;3539.6460353964912;4300.956990430118;4389.31965545341;4436.134953684001;4503.279475283939;14605.488807891672;14795.926020369829;15064.322105505278;15332.846671533141;15399.92300038515;14694.191819450032;14885.92557037208;14948.551543453714;15107.848921510644;15128.773068403983;7559.886601700979;8467.237948584658;8510.914890851136;8632.784180395414;8650.826983460296;5465.672059676433;7076.186238582423;7150.856982860418;7307.525010874276;7339.055150385259;11784.4696988634;12635.620931372074;12895.226286422845;12933.03002274852;12944.870551294367;13586.388612512263;13943.16341019542;13949.721005580035;14031.087979281314;14144.78782818238;8246.670133194604;8304.252617264234;8326.375521836007;9187.632494700138;9481.288903332415;10057;10651.30766500175;10979.83530247031;11073.058790002618;11162.218644694793;6930.341617546325;7165.820854478679;9102.772430689285;9362.953185234303;9467.66863159786;8806.779830504289;8876.955615222141;8897.599608017525;8920.420172688755;9033.780439640565;8226.341892648694;8250.226201366735;8332.625031873702;8340.457870238415;8380.874286885579;5235.319408476874;5822.301323841166;6123.418275263843;6150.446459818638;6275.90171719938;6069.514438844793;6126;6252.624842509464;6290.119383286435;6399.168108146097 07:48:24 INFO - PID 1640 | |4;prototype.html;340.44167565193084;398.5416770713677;402.1093278388369;410.854146777894;412.6368795459995;5567.888642227158;6053.940560401934;6070.816190842787;6124.571280010401;6143.078538219269;4801.855944321667;4916.164252077147;5584.329880414346;6110.877782444349;6202.7284406696635;4441.555844415565;4485.304777759453;4531.27499600064;4534.546545345472;4570.748608826513;4535.251683472223;4536.954630453686;4549.24937385331;4565.543445655425;4582.587567118959;3590.443481260396;3948.447217389568;4399.218316581773;4508.188526065307;4524.298733696266;4449.466064072316;4454.198244316021;4504.617107545862;4513.7517436540975;4529.075143064201;3310.652381499932;3764.9458151717563;3772.9622703772884;3821.3503704370046;3836.0985168485386;5435.646682965634;5635.5773317001385;6054.878902421971;6073.635581865102;6101.511879049665;4328.891777705582;4554.521775213588;5358.3569971603265;5436.483534064258;5439;5728.942710572925;5731.598788084836;6608.933910660928;6613.7685181018915;6619.470442364599;4347;4362.556968097875;4368.89077773055;4373.497047839475;4376.6936314458;6627.701753421107;6677.5643236704345;6846.8972965405555;6917.239103698589;7019.262977387352;3612.458131280329;3728.757630753992;4410.963423595516;4421.209410188879;4427.667924905645;3752.81235938202;4476.37330773692;4491.820327186876;4495.865124046284;4528.88677783058;4883.389576302962;5150.562202212854;5290.894182116336;5406.216098665672;5408.891822163535;6088.817335479943;6603.712276106113;6607.273199948002;6612.537122401482;6618.073469714244;1297.9221246725226;1518.772184172383;1544.274191130167;1544.5906834688817;1546.3350759173663;914.465037952805;973.5813600151861;1006.6527048168371;1007.1942446043137;1009.7172791618211;6086.239220097488;6321.873562528724;6351.364863513704;6352.777652782131;6372.840678983062;5984.611000284967;6014.127951447016;6679.799606011828;6809.285025072296;6837.418819400406;5777.826665200051;6286.108390140495;6317.778877739257;6353.936460635427;6390.201224846895;3126.3122113135073;3411.556497655289;3417.111550996709;3495.563054618173;3595.065283026432;6580.40776330123;6931.4108300795;7028.648567571703;7095.680694368766;7103.183133939559;6236.314005459396;6303.054541818672;7004;7026.402755765817;7042.190148132928;5468.808591699351;6099.115628233882;6681.665916704145;6815.795526134224;6917.12342798326;5706;6495.610263384212;6811.489138314547;6821.8635627286185;6831.009503622046;5919.845630102089;6221.3156552780065;6650.634215118215;6703.396694297537;6706.6646667665445;5882.499987500939;6171.444569988543;6779;6847.520673553003;6993.685284162123;3660.762050466711;3756.6619004289746;4417.1386579616665;4431.977840110908;4437.29003359461;5343.492368225013;5418.864528386822;6885.449164066963;6889.655517224219;6917.89623155643;6516.5112616553;6579.151196550072;6613;6626.734930602722;6630.9336906630315;5618.494335509824;5647.011772939802;6336.58812177207;6431.646259455775;6567.671616419256;4871.9269210962575;5055.924161137659;6379.681015949277;6447.548671592944;6452.579832940352;6550.312217217123;6585.934140658724;6595.934040659724;6619.106420633153;6628.436582890508;5148.665336753099;6272.529560283088;6302.810915672537;6341;6511.609303441809 07:48:24 INFO - PID 1640 | |5;yui.html;837.3552364679193;850.3112478892098;862.7454900804262;867.054910147939;879.3624622148943;14774.778378324334;15033.242859632954;16791.328346866125;16941.915290423545;16943.661126777457;13542.187468751881;13938.51215207468;18182.363617273397;18229.453116406497;18230;799.3565180030073;981.4749109226565;990.1781521337273;991.0782971836189;993.0466751918132;941.2422999485411;977.3061126600122;983.5279066048302;993.3642468819961;999.1956475037592;1034.606849397228;1047.5338474378866;1048.2190767877942;1048.5700862646318;1054.2778196935124;1032.5250384822953;1044.634377967713;1046.08467590858;1047.177965297238;1047.6019112737188;760.6539024743784;762.371043888794;771.9809850996687;772.82997740497;776.3672606825476;16940.91529542357;17160.14199290043;17261;17339.306427742875;17355.739663905053;17273.65452690939;17492.47522574325;17557.824421755748;17588.504977076966;17802.91098544512;14299.57101286963;14539.636509087251;14541.927290363481;14728.631784205374;14739.852601474062;3614.530111085542;3621.5835178954494;3651.908702282464;3660.4692319613505;3664.8167591620577;98688;127673;127733;130982.34508827394;131231;13094.869051309555;13298.667533311746;13479.989000824977;13484.258365789976;13651;25607.48785024289;25745.871270643525;25768.484630307288;26004.21987340383;26225.606615900397;7579.54522728638;7663.501872378278;7690.923090769021;7885.1720569339395;7995.680172793023;3888.5528164260904;3944.2505923874373;3947.940780888289;3969.0871099646665;3972.364421692573;4152.671145233497;4196.958030419657;4240.597143271384;4253.914921701548;4257.808398622069;927.7541451515353;945.4610871803144;949.9050094990446;956.1920177450044;961.6682244625594;571.6198727846019;644.6776611694153;653.6208998780696;654.2182092399537;690.8549204667077;11228.382438965935;11325.660230193107;11913.761724765456;11954.342511161969;12056.517739290506;623.423333416597;648.6497291462651;660.0824853453703;667.7529314153794;672.2470832667444;4772.618190544748;5418.512333889968;5600.803971860965;5636.464535869086;5727.541796656257;99681;100778;102168;102668.48665756773;103996.48001759943;90736.54631726799;93950.5302473497;94560;95369.56945645821;95561;60077;62608.24350538982;84554;85454.14545854462;85663;11246.04408625276;11332.943335283271;11372.545098196311;11447.771044578894;11548.191626586058;36848.60484068527;42279.577204227564;42756.5724342765;43438.782806085765;43469.78265108654;54104;54598;55010;55200.6199844991;55455;12048.698782530508;13876;13953.860461395256;14217.573472795833;14229.573112806633;15447.5365739028;15722.842771572137;15784.289706962985;15797.447089351817;15825;14347.784783228051;14627.707445851236;14759.483418080745;14789.334479948644;16391.83608163903;16650.666986660442;17082.743758843877;17439.738403923697;17455.952642841472;17485.737713934046;16275.51173464798;16682.58293542671;16897.66204675875;17036.955565331744;17079.7438038432;11675.532978681098;11688.590899318824;11920.582779602672;11968.341741204318;12252.632421027383;12795;13118.672033198865;13184.208947463183;13190.934045330096;13240.470381184645 07:48:24 INFO - PID 1640 | __end_tp_report 07:48:24 INFO - PID 1640 | __start_cc_report 07:48:24 INFO - PID 1640 | _x_x_mozilla_cycle_collect,331 07:48:24 INFO - PID 1640 | __end_cc_report 07:48:24 INFO - PID 1640 | __startTimestamp1494946104684__endTimestamp 07:48:24 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | ------- Summary: start ------- 07:48:24 INFO - PID 1640 | Number of tests: 6 07:48:24 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | [#0] dojo.html Cycles:165 Average:6186.41 Median:6398.42 stddev:708.82 (11.1%) stddev-sans-first:680.63 07:48:24 INFO - PID 1640 | Values: 3570.3 3578.2 3607.4 3629.0 3631.5 6101.2 6174.9 6234.1 6240.2 6257.1 6195.7 6204.4 6206.0 6246.4 6307.1 5412.4 5418.4 5429.6 5430.5 5448.9 5514.2 5582.8 5590.1 5644.6 5685.5 5402.5 5490.5 5507.3 5553.6 5574.1 5419.8 5423.5 5454.8 5475.8 5552.7 5480.3 5491.4 5506.8 5522.4 5548.5 6169.8 6194.4 6269.1 6278.0 6284.6 6354.5 6688.0 6717.6 6743.9 6756.8 6618.3 6684.8 6793.4 6799.9 6826.8 6317.0 6332.7 6340.0 6342.3 6356.4 6979.6 6982.5 6999.1 7005.2 7019.1 6367.3 6414.4 6448.2 6488.7 6561.7 6745.8 6780.6 6788.9 6791.8 6811.3 6441.8 6497.4 6523.5 6623.9 6727.4 6697.1 6709.4 6720.1 6725.0 6734.0 5279.1 5340.0 5341.7 5357.3 5385.9 4756.5 4770.3 4855.9 4867.6 4884.2 6398.4 6398.4 6403.6 6418.5 6418.6 5783.3 6249.3 6282.5 6778.9 6789.4 6269.0 6272.9 6295.4 6328.1 6376.3 5894.1 5918.3 5933.5 5949.9 5999.8 6936.2 6939.6 6984.8 6987.4 6990.1 6560.9 6655.8 6661.5 6789.8 6813.9 5776.6 6323.8 6471.9 6996.4 7001.8 6671.1 6679.3 6686.9 6689.7 6695.1 6541.2 6709.1 6716.1 6745.0 6804.3 6465.8 6516.8 6583.7 6586.5 6623.0 6576.6 6601.1 6608.3 6616.4 6660.1 5327.7 6199.9 6366.6 6425.9 6449.9 6538.5 6574.1 6604.6 6613.4 6619.9 6377.6 6422.9 6450.8 6457.6 6469.6 07:48:24 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | [#1] ext.html Cycles:180 Average:45376.98 Median:34057.71 stddev:81557.09 (239.5%) stddev-sans-first:81716.86 07:48:24 INFO - PID 1640 | Values: 688.4 735.7 764.9 1038.6 1193.9 37621.8 38028.6 38089.6 38185.8 38267.6 38223.2 38572.0 38583.4 38641.8 38695.8 7797.1 7951.6 8083.2 8204.6 8278.2 8200.9 8327.5 8328.0 8329.6 8342.5 6952.9 8303.8 8385.4 8393.0 8399.0 8306.2 8319.3 8343.1 8347.0 8378.2 8105.9 8178.8 8184.5 8186.5 8187.5 35812.0 35857.1 35909.3 35927.1 35971.5 3586.1 3591.9 3628.2 3630.9 3634.4 21506.5 28069.7 29655.7 29715.4 29831.4 3081.4 3111.1 3113.7 3124.9 3126.2 304085.0 304201.0 304224.0 315660.0 324462.0 42468.0 44642.0 47553.3 47557.0 47711.5 40620.6 49311.5 49548.5 50429.2 51291.0 13638.8 13791.7 13877.5 13882.6 13892.3 11102.4 11114.4 11138.1 11150.4 11180.4 13243.4 13246.3 13356.5 13384.4 13466.2 5337.3 5679.9 5912.2 5930.4 5948.3 876.4 969.8 995.9 1008.2 1043.2 19444.6 20602.6 21520.9 23541.8 24341.3 878.5 1020.9 1101.6 1112.2 1134.7 10011.7 12392.3 12411.6 12472.9 12636.9 335506.0 335930.0 337453.3 337498.0 338195.0 51736.5 51758.7 52162.0 52422.7 52561.7 1486.5 1491.7 1522.4 1536.3 1547.4 21805.9 21832.9 21891.7 23898.3 24073.2 38006.2 38046.2 38096.8 38101.2 38109.8 280462.6 280510.0 280540.0 280703.0 282196.6 33860.3 34028.0 34052.5 34062.9 34126.0 42690.6 43521.3 43857.8 45166.8 45204.8 25671.0 29407.4 30122.2 30900.4 31317.2 29944.7 30632.4 30828.8 30846.7 30878.0 35708.3 35977.1 36804.9 36928.0 37011.3 23217.7 30174.8 31278.9 31358.0 31440.5 22138.4 23143.5 24173.3 24232.5 24480.9 07:48:24 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | [#2] jquery.html Cycles:180 Average:35701.40 Median:2253.94 stddev:93165.89 (4133.5%) stddev-sans-first:93389.59 07:48:24 INFO - PID 1640 | Values: 425.9 429.7 436.7 442.8 455.5 20100.9 20971.4 21113.9 21932.0 22095.6 19172.9 20345.5 22782.7 23189.7 23409.0 5996.8 6252.1 6256.2 6269.7 6274.6 5865.6 6096.0 6154.2 6155.2 6169.4 5895.5 6109.6 6133.0 6148.4 6151.7 6049.4 6133.9 6175.6 6177.5 6193.9 4966.8 5108.1 5414.1 5507.9 5508.5 26047.6 27042.9 27186.6 27242.9 27379.9 1993.6 2152.0 2247.8 2253.2 2254.7 13653.5 17682.8 17831.7 17936.4 18382.4 2138.5 2184.4 2188.4 2201.5 2209.1 149988.3 157983.0 169403.0 171370.0 173165.1 9846.0 11696.8 11746.9 12461.8 12790.6 13704.4 16646.8 16664.9 16698.8 16706.0 8819.6 9117.6 9119.5 9188.0 9304.2 8736.6 8754.9 8775.1 8955.1 8964.7 7313.2 7334.3 7814.4 7953.9 7996.3 2183.8 2185.9 2193.1 2193.7 2344.5 946.8 961.7 966.6 973.1 993.4 20256.5 20711.0 20753.1 20785.9 21059.2 967.5 993.6 998.7 1001.9 1023.2 10101.5 10405.1 10631.1 10679.6 10768.4 541228.0 542043.0 542842.0 553389.0 553792.0 121053.4 125845.0 131048.5 136139.0 139348.3 1098.8 1197.2 1221.9 1248.3 1256.0 20661.8 20754.9 21528.9 22049.1 22052.9 54042.6 55107.0 55166.4 55270.2 57883.4 75269.0 75897.9 76061.2 76273.2 76500.0 8784.6 8785.0 8836.2 9210.4 9261.4 13823.9 13862.0 14119.2 14240.7 14318.8 10507.5 11829.4 12076.7 12187.9 12198.8 10743.1 11097.9 11185.6 11411.1 11417.3 10808.3 11850.4 11968.8 12207.3 12412.4 8849.9 8913.9 8925.5 8972.5 8995.0 11911.1 11946.3 12071.7 12180.3 12296.3 07:48:24 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | [#3] mootools.html Cycles:180 Average:7086.18 Median:3639.65 stddev:4482.41 (123.2%) stddev-sans-first:4474.20 07:48:24 INFO - PID 1640 | Values: 1341.6 1378.1 1393.4 1400.9 1412.8 11667.2 12048.5 12058.6 12065.2 12364.1 11900.0 11921.9 12086.3 12106.3 12110.9 2574.3 2615.9 2632.9 2635.4 2635.9 2240.5 2451.3 2549.2 2578.2 2578.9 2306.0 2480.8 2567.6 2607.3 2613.8 2537.9 2564.3 2595.3 2606.5 2608.2 2533.1 2566.8 2599.8 2634.5 2642.2 11704.6 11740.2 11830.6 11863.5 11870.9 6613.9 6769.2 6784.1 6865.0 6868.8 4131.4 4451.9 4485.1 4503.3 4506.7 1235.1 1255.8 1294.9 1299.3 1300.1 15586.1 16077.8 16401.8 16455.2 16585.3 5516.2 5599.6 5613.0 5757.5 5800.8 11530.3 11531.3 11540.9 11541.3 11586.2 6496.7 6539.5 6551.3 6557.5 6586.1 5973.5 6179.7 6213.6 6295.7 6304.3 3739.7 4076.7 4393.5 4421.7 4446.8 1107.9 1274.3 1278.5 1302.8 1305.3 683.7 781.6 799.6 806.7 811.3 6153.4 6205.3 6283.5 6354.0 6388.2 589.2 592.1 611.7 621.8 621.8 3539.6 4301.0 4389.3 4436.1 4503.3 14605.5 14795.9 15064.3 15332.8 15399.9 14694.2 14885.9 14948.6 15107.8 15128.8 7559.9 8467.2 8510.9 8632.8 8650.8 5465.7 7076.2 7150.9 7307.5 7339.1 11784.5 12635.6 12895.2 12933.0 12944.9 13586.4 13943.2 13949.7 14031.1 14144.8 8246.7 8304.3 8326.4 9187.6 9481.3 10057.0 10651.3 10979.8 11073.1 11162.2 6930.3 7165.8 9102.8 9363.0 9467.7 8806.8 8877.0 8897.6 8920.4 9033.8 8226.3 8250.2 8332.6 8340.5 8380.9 5235.3 5822.3 6123.4 6150.4 6275.9 6069.5 6126.0 6252.6 6290.1 6399.2 07:48:24 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | [#4] prototype.html Cycles:180 Average:5223.16 Median:5830.16 stddev:1671.01 (28.7%) stddev-sans-first:1635.02 07:48:24 INFO - PID 1640 | Values: 340.4 398.5 402.1 410.9 412.6 5567.9 6053.9 6070.8 6124.6 6143.1 4801.9 4916.2 5584.3 6110.9 6202.7 4441.6 4485.3 4531.3 4534.5 4570.7 4535.3 4537.0 4549.2 4565.5 4582.6 3590.4 3948.4 4399.2 4508.2 4524.3 4449.5 4454.2 4504.6 4513.8 4529.1 3310.7 3764.9 3773.0 3821.4 3836.1 5435.6 5635.6 6054.9 6073.6 6101.5 4328.9 4554.5 5358.4 5436.5 5439.0 5728.9 5731.6 6608.9 6613.8 6619.5 4347.0 4362.6 4368.9 4373.5 4376.7 6627.7 6677.6 6846.9 6917.2 7019.3 3612.5 3728.8 4411.0 4421.2 4427.7 3752.8 4476.4 4491.8 4495.9 4528.9 4883.4 5150.6 5290.9 5406.2 5408.9 6088.8 6603.7 6607.3 6612.5 6618.1 1297.9 1518.8 1544.3 1544.6 1546.3 914.5 973.6 1006.7 1007.2 1009.7 6086.2 6321.9 6351.4 6352.8 6372.8 5984.6 6014.1 6679.8 6809.3 6837.4 5777.8 6286.1 6317.8 6353.9 6390.2 3126.3 3411.6 3417.1 3495.6 3595.1 6580.4 6931.4 7028.6 7095.7 7103.2 6236.3 6303.1 7004.0 7026.4 7042.2 5468.8 6099.1 6681.7 6815.8 6917.1 5706.0 6495.6 6811.5 6821.9 6831.0 5919.8 6221.3 6650.6 6703.4 6706.7 5882.5 6171.4 6779.0 6847.5 6993.7 3660.8 3756.7 4417.1 4432.0 4437.3 5343.5 5418.9 6885.4 6889.7 6917.9 6516.5 6579.2 6613.0 6626.7 6630.9 5618.5 5647.0 6336.6 6431.6 6567.7 4871.9 5055.9 6379.7 6447.5 6452.6 6550.3 6585.9 6595.9 6619.1 6628.4 5148.7 6272.5 6302.8 6341.0 6511.6 07:48:24 INFO - PID 1640 | 07:48:24 INFO - PID 1640 | [#5] yui.html Cycles:180 Average:21442.94 Median:18205.91 stddev:30270.75 (166.3%) stddev-sans-first:30316.12 07:48:24 INFO - PID 1640 | Values: 837.4 850.3 862.7 867.1 879.4 14774.8 15033.2 16791.3 16941.9 16943.7 13542.2 13938.5 18182.4 18229.5 18230.0 799.4 981.5 990.2 991.1 993.0 941.2 977.3 983.5 993.4 999.2 1034.6 1047.5 1048.2 1048.6 1054.3 1032.5 1044.6 1046.1 1047.2 1047.6 760.7 762.4 772.0 772.8 776.4 16940.9 17160.1 17261.0 17339.3 17355.7 17273.7 17492.5 17557.8 17588.5 17802.9 14299.6 14539.6 14541.9 14728.6 14739.9 3614.5 3621.6 3651.9 3660.5 3664.8 98688.0 127673.0 127733.0 130982.3 131231.0 13094.9 13298.7 13480.0 13484.3 13651.0 25607.5 25745.9 25768.5 26004.2 26225.6 7579.5 7663.5 7690.9 7885.2 7995.7 3888.6 3944.3 3947.9 3969.1 3972.4 4152.7 4197.0 4240.6 4253.9 4257.8 927.8 945.5 949.9 956.2 961.7 571.6 644.7 653.6 654.2 690.9 11228.4 11325.7 11913.8 11954.3 12056.5 623.4 648.6 660.1 667.8 672.2 4772.6 5418.5 5600.8 5636.5 5727.5 99681.0 100778.0 102168.0 102668.5 103996.5 90736.5 93950.5 94560.0 95369.6 95561.0 60077.0 62608.2 84554.0 85454.1 85663.0 11246.0 11332.9 11372.5 11447.8 11548.2 36848.6 42279.6 42756.6 43438.8 43469.8 54104.0 54598.0 55010.0 55200.6 55455.0 12048.7 13876.0 13953.9 14217.6 14229.6 15447.5 15722.8 15784.3 15797.4 15825.0 14347.8 14627.7 14759.5 14789.3 16391.8 16650.7 17082.7 17439.7 17456.0 17485.7 16275.5 16682.6 16897.7 17037.0 17079.7 11675.5 11688.6 11920.6 11968.3 12252.6 12795.0 13118.7 13184.2 13190.9 13240.5 07:48:24 INFO - PID 1640 | -------- Summary: end -------- 07:48:24 INFO - PID 1640 | 07:48:25 INFO - TEST-INFO | 1640: exit 0 07:48:25 INFO - TEST-OK | dromaeo_css | took 1114219ms 07:48:25 INFO - TEST-START | kraken 07:48:25 INFO - operating with platform_type : mac_ 07:48:25 INFO - Initialising browser for kraken test... 07:48:25 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpOXkfMR/profile http://localhost:49219/getInfo.html) 07:48:25 INFO - PID 1651 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2930: String.localeCompare is deprecated; use String.prototype.localeCompare instead 07:48:27 INFO - PID 1651 | 2017-05-16 07:48:27.675 plugin-container[1652:9903] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 07:48:27 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:48:27 INFO - PID 1651 | 2017-05-16 07:48:27.678 plugin-container[1652:9903] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 07:48:27 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:48:27 INFO - PID 1651 | 2017-05-16 07:48:27.679 plugin-container[1652:9903] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:48:27 INFO - PID 1651 | __metrics Screen width/height:1600/1200 07:48:27 INFO - PID 1651 | colorDepth:24 07:48:27 INFO - PID 1651 | Browser inner width/height: 1024/691 07:48:27 INFO - PID 1651 | __metrics 07:48:28 INFO - TEST-INFO | 1651: exit 0 07:48:28 INFO - Browser initialized. 07:48:28 INFO - Running cycle 1/1 for kraken test... 07:48:28 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpOXkfMR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:48:30 INFO - PID 1654 | 2017-05-16 07:48:30.509 plugin-container[1655:10050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 07:48:30 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:48:30 INFO - PID 1654 | 2017-05-16 07:48:30.510 plugin-container[1655:10050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x980b, name = 'com.apple.CFPasteboardClient' 07:48:30 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:48:30 INFO - PID 1654 | 2017-05-16 07:48:30.510 plugin-container[1655:10050] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:48:31 INFO - PID 1654 | 2017-05-16 07:48:31.261 plugin-container[1656:10100] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903b, name = 'com.apple.tsm.portname' 07:48:31 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:48:31 INFO - PID 1654 | 2017-05-16 07:48:31.261 plugin-container[1656:10100] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 07:48:31 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:48:31 INFO - PID 1654 | 2017-05-16 07:48:31.261 plugin-container[1656:10100] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:48:40 INFO - PID 1654 | RSS: Main: 235888640 07:48:40 INFO - PID 1654 | 07:48:43 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 07:48:44 INFO - PID 1654 | RSS: Main: 248320000 07:48:44 INFO - PID 1654 | 07:48:46 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 07:48:47 INFO - PID 1654 | RSS: Main: 256925696 07:48:47 INFO - PID 1654 | 07:48:51 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 07:48:51 INFO - PID 1654 | RSS: Main: 258744320 07:48:51 INFO - PID 1654 | 07:48:53 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 07:48:54 INFO - PID 1654 | RSS: Main: 260767744 07:48:54 INFO - PID 1654 | 07:48:56 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:48:56 INFO - PID 1654 | RSS: Main: 261455872 07:48:56 INFO - PID 1654 | 07:48:59 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 07:49:00 INFO - PID 1654 | RSS: Main: 266690560 07:49:00 INFO - PID 1654 | 07:49:03 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 07:49:04 INFO - PID 1654 | RSS: Main: 266784768 07:49:04 INFO - PID 1654 | 07:49:06 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 07:49:07 INFO - PID 1654 | RSS: Main: 266276864 07:49:07 INFO - PID 1654 | 07:49:09 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:49:09 INFO - PID 1654 | RSS: Main: 266018816 07:49:09 INFO - PID 1654 | 07:49:11 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:49:12 INFO - PID 1654 | RSS: Main: 265830400 07:49:12 INFO - PID 1654 | 07:49:14 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:49:14 INFO - PID 1654 | RSS: Main: 265220096 07:49:14 INFO - PID 1654 | 07:49:17 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:49:18 INFO - PID 1654 | RSS: Main: 265388032 07:49:18 INFO - PID 1654 | 07:49:21 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:49:22 INFO - PID 1654 | RSS: Main: 265510912 07:49:22 INFO - PID 1654 | 07:49:23 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 07:49:24 INFO - PID 1654 | RSS: Main: 267997184 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | __start_tp_report 07:49:24 INFO - PID 1654 | _x_x_mozilla_page_load 07:49:24 INFO - PID 1654 | _x_x_mozilla_page_load_details 07:49:24 INFO - PID 1654 | |i|pagename|runs| 07:49:24 INFO - PID 1654 | |0;ai-astar;104;114;115;114;115;114;118;162;114;155 07:49:24 INFO - PID 1654 | |1;audio-beat-detection;136;117;144;150;106;111;108;119;148;121 07:49:24 INFO - PID 1654 | |2;audio-dft;200;196;199;201;253;250;254;283;239;201 07:49:24 INFO - PID 1654 | |3;audio-fft;82;57;60;88;58;58;61;55;76;59 07:49:24 INFO - PID 1654 | |4;audio-oscillator;88;70;89;104;85;70;86;72;73;99 07:49:24 INFO - PID 1654 | |5;imaging-gaussian-blur;91;91;90;95;89;89;89;90;102;90 07:49:24 INFO - PID 1654 | |6;imaging-darkroom;98;98;97;97;98;97;97;128;156;109 07:49:24 INFO - PID 1654 | |7;imaging-desaturate;93;95;92;94;95;91;90;94;97;95 07:49:24 INFO - PID 1654 | |8;json-parse-financial;50;50;52;51;51;51;50;51;50;51 07:49:24 INFO - PID 1654 | |9;json-stringify-tinderbox;57;63;57;55;63;59;57;57;57;58 07:49:24 INFO - PID 1654 | |10;stanford-crypto-aes;82;84;83;85;84;83;84;104;109;103 07:49:24 INFO - PID 1654 | |11;stanford-crypto-ccm;137;141;140;139;158;151;138;138;189;136 07:49:24 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;191;185;185;188;189;181;257;185;186;185 07:49:24 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;71;70;71;69;72;70;70;67;69;68 07:49:24 INFO - PID 1654 | __end_tp_report 07:49:24 INFO - PID 1654 | __start_cc_report 07:49:24 INFO - PID 1654 | _x_x_mozilla_cycle_collect,934 07:49:24 INFO - PID 1654 | __end_cc_report 07:49:24 INFO - PID 1654 | __startTimestamp1494946164174__endTimestamp 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | ------- Summary: start ------- 07:49:24 INFO - PID 1654 | Number of tests: 14 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:122.50 Median:115.00 stddev:19.38 (16.9%) stddev-sans-first:19.37 07:49:24 INFO - PID 1654 | Values: 104.0 114.0 115.0 114.0 115.0 114.0 118.0 162.0 114.0 155.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:126.00 Median:128.50 stddev:16.96 (13.2%) stddev-sans-first:17.60 07:49:24 INFO - PID 1654 | Values: 136.0 117.0 144.0 150.0 106.0 111.0 108.0 119.0 148.0 121.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:227.60 Median:244.50 stddev:31.69 (13.0%) stddev-sans-first:32.00 07:49:24 INFO - PID 1654 | Values: 200.0 196.0 199.0 201.0 253.0 250.0 254.0 283.0 239.0 201.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:65.40 Median:60.50 stddev:11.91 (19.7%) stddev-sans-first:11.01 07:49:24 INFO - PID 1654 | Values: 82.0 57.0 60.0 88.0 58.0 58.0 61.0 55.0 76.0 59.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:85.50 stddev:12.14 (14.2%) stddev-sans-first:12.77 07:49:24 INFO - PID 1654 | Values: 88.0 70.0 89.0 104.0 85.0 70.0 86.0 72.0 73.0 99.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:90.00 stddev:4.06 (4.5%) stddev-sans-first:4.30 07:49:24 INFO - PID 1654 | Values: 91.0 91.0 90.0 95.0 89.0 89.0 89.0 90.0 102.0 90.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:97.00 stddev:19.68 (20.3%) stddev-sans-first:20.57 07:49:24 INFO - PID 1654 | Values: 98.0 98.0 97.0 97.0 98.0 97.0 97.0 128.0 156.0 109.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:94.50 stddev:2.12 (2.2%) stddev-sans-first:2.24 07:49:24 INFO - PID 1654 | Values: 93.0 95.0 92.0 94.0 95.0 91.0 90.0 94.0 97.0 95.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.67 (1.3%) stddev-sans-first:0.67 07:49:24 INFO - PID 1654 | Values: 50.0 50.0 52.0 51.0 51.0 51.0 50.0 51.0 50.0 51.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:57.50 stddev:2.67 (4.6%) stddev-sans-first:2.79 07:49:24 INFO - PID 1654 | Values: 57.0 63.0 57.0 55.0 63.0 59.0 57.0 57.0 57.0 58.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:83.50 stddev:10.65 (12.8%) stddev-sans-first:10.89 07:49:24 INFO - PID 1654 | Values: 82.0 84.0 83.0 85.0 84.0 83.0 84.0 104.0 109.0 103.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:140.50 stddev:16.44 (11.7%) stddev-sans-first:17.06 07:49:24 INFO - PID 1654 | Values: 137.0 141.0 140.0 139.0 158.0 151.0 138.0 138.0 189.0 136.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.20 Median:187.00 stddev:22.58 (12.1%) stddev-sans-first:23.94 07:49:24 INFO - PID 1654 | Values: 191.0 185.0 185.0 188.0 189.0 181.0 257.0 185.0 186.0 185.0 07:49:24 INFO - PID 1654 | 07:49:24 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:1.49 (2.1%) stddev-sans-first:1.51 07:49:24 INFO - PID 1654 | Values: 71.0 70.0 71.0 69.0 72.0 70.0 70.0 67.0 69.0 68.0 07:49:24 INFO - PID 1654 | -------- Summary: end -------- 07:49:24 INFO - PID 1654 | 07:49:25 INFO - TEST-INFO | 1654: exit 0 07:49:25 INFO - TEST-OK | kraken | took 59821ms 07:49:25 INFO - SUITE-END | took 1174s 07:49:25 INFO - Completed test suite (00:19:34) 07:49:25 INFO - javascript benchmark 07:49:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7815.69537670431, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3570.2502474480357, 3578.2127931855, 3607.368710475666, 3629.0383048492154, 3631.4915911772355, 6101.176341193948, 6174.876502469948, 6234.064890266457, 6240.219972503437, 6257.092721555372, 6195.721192546335, 6204.4416002559765, 6205.968970155143, 6246.437820596146, 6307.085472606469, 5412.404635490093, 5418.431064738204, 5429.647072940266, 5430.484104010133, 5448.855740294533, 5514.172874068881, 5582.804601838937, 5590.077637189859, 5644.57665675074, 5685.516731077863, 5402.459754024566, 5490.478404551562, 5507.284053073115, 5553.611247212697, 5574.080276754351, 5419.756110975016, 5423.511883930426, 5454.809081682122, 5475.753591088371, 5552.666839989573, 5480.287562616834, 5491.395946445888, 5506.752196151143, 5522.447755224485, 5548.500634942834, 6169.7532098716, 6194.380561943814, 6269.122322874801, 6278.0, 6284.585968157174, 6354.523410744213, 6687.966560167217, 6717.56335838169, 6743.93256067438, 6756.8310792230095, 6618.271990081087, 6684.83287917801, 6793.388595026422, 6799.932000680029, 6826.761063362758, 6317.0, 6332.746690132435, 6340.048992651093, 6342.270638876496, 6356.364363563653, 6979.581225126509, 6982.47631427645, 6999.09011828459, 7005.159380874327, 7019.122609673791, 6367.267764207083, 6414.422701956846, 6448.161738973903, 6488.675566221765, 6561.704723287464, 6745.831354216184, 6780.559263647946, 6788.864222715518, 6791.830204244933, 6811.25076241613, 6441.774537891245, 6497.415232629086, 6523.510736694767, 6623.9006414903815, 6727.360900714424, 6697.062411262427, 6709.429698475684, 6720.059191713164, 6725.024871393718, 6734.023566582818, 5279.102552565996, 5340.0, 5341.706206158621, 5357.330333708286, 5385.865353366119, 4756.524347565216, 4770.260609605517, 4855.878603034952, 4867.61059115277, 4884.169691152512, 6398.392152745481, 6398.424141827258, 6403.583767055126, 6418.518611104186, 6418.614883107029, 5783.277090363705, 6249.312575616678, 6282.523606952363, 6778.864422711518, 6789.355011273921, 6268.965620672538, 6272.937270627326, 6295.37046295376, 6328.145700330397, 6376.266729326094, 5894.145348924468, 5918.260217472816, 5933.525317974551, 5949.851253718605, 5999.760009599567, 6936.167659880846, 6939.5990498056835, 6984.755533556301, 6987.441004719712, 6990.09128813251, 6560.868782624416, 6655.800325990228, 6661.500387470862, 6789.762358317633, 6813.931860681528, 5776.624519406225, 6323.841903952438, 6471.870562588816, 6996.405305549085, 7001.789946301619, 6671.132752742113, 6679.265280819203, 6686.9331306688255, 6689.732410703517, 6695.09616201826, 6541.215054193527, 6709.0705755481185, 6716.093327400738, 6745.0219718139615, 6804.285550017177, 6465.806025819233, 6516.771912983023, 6583.670816459254, 6586.473082153512, 6623.039659249478, 6576.605403675795, 6601.141851559267, 6608.339166083353, 6616.437602803816, 6660.100886380276, 5327.706976116196, 6199.907001395073, 6366.586171898812, 6425.9036114457385, 6449.871002580016, 6538.542302038812, 6574.0796288520605, 6604.570702904296, 6613.437857782143, 6619.867602647823, 6377.585456945284, 6422.935770642233, 6450.838849007224, 6457.61254324742, 6469.644169570719], "value": 6140.188549714348, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [688.4320435640597, 735.738812721484, 764.8929149919012, 1038.5534220285274, 1193.8686744458107, 37621.81189094054, 38028.619713802924, 38089.61910380895, 38185.80907095464, 38267.61732382675, 38223.23553528928, 38572.0, 38583.421248681225, 38641.806790966075, 38695.8391248263, 7797.142314345418, 7951.602419879012, 8083.151269116746, 8204.630791614361, 8278.213569710868, 8200.876986845202, 8327.458715183524, 8328.042275138376, 8329.583520823964, 8342.499450032987, 6952.895706564405, 8303.83392332153, 8385.413021088527, 8392.958035209815, 8399.0, 8306.169383061706, 8319.251267385964, 8343.123971982915, 8347.040090389622, 8378.245957863792, 8105.918940810576, 8178.754637360888, 8184.508929464251, 8186.508809471451, 8187.5087494750505, 35812.0, 35857.103572410895, 35909.28181436357, 35927.10182245465, 35971.4604280936, 3586.067622418164, 3591.9281614367565, 3628.1655219299446, 3630.9273814523826, 3634.3821550336506, 21506.462338441506, 28069.736861841266, 29655.70344296551, 29715.40569188626, 29831.40337193244, 3081.3529158876663, 3111.0977816433206, 3113.7353324967403, 3124.906252812419, 3126.1559378967777, 304085.0, 304201.0, 304224.0, 315660.0, 324462.0, 42468.0, 44642.0, 47553.286700699515, 47557.0, 47711.5228847714, 40620.593794062275, 49311.50688493141, 49548.50451495511, 50429.24356134661, 51291.0, 13638.795418068737, 13791.724165516633, 13877.514286999907, 13882.583522494342, 13892.305384730926, 11102.44487775608, 11114.44427778624, 11138.050841102775, 11150.442477876073, 11180.385078820744, 13243.404046817916, 13246.271455070064, 13356.46574137043, 13384.397702103428, 13466.192028478323, 5337.2794672719465, 5679.8924209779525, 5912.201852749909, 5930.377310382434, 5948.256467941507, 876.3821505838496, 969.8108868770663, 995.8506224066448, 1008.2135933971514, 1043.1758910460803, 19444.58274004533, 20602.58794824095, 21520.892395538234, 23541.7645823543, 24341.26976190717, 878.5387671472528, 1020.8621836052038, 1101.553870682375, 1112.238116889933, 1134.6652737442473, 10011.699649010541, 12392.318422486669, 12411.62765117048, 12472.875271247352, 12636.873631263754, 335506.0, 335930.0, 337453.3127334348, 337498.0, 338195.0, 51736.48263517392, 51758.70603234874, 52161.95676086457, 52422.73788631032, 52561.7371913138, 1486.51688201335, 1491.7314883321105, 1522.4290890915906, 1536.2933050796535, 1547.3655801121486, 21805.89097054568, 21832.89083554572, 21891.686498810122, 23898.283051508482, 24073.157439489532, 38006.2398752029, 38046.239075218895, 38096.80951595224, 38101.23797523978, 38109.80945095257, 280462.59768701025, 280510.0, 280540.0, 280703.0, 282196.5890170536, 33860.32279354448, 34028.0, 34052.48921266232, 34062.934335909886, 34126.0, 42690.57309426866, 43521.34717979169, 43857.78071109624, 45166.77416612896, 45204.773976131226, 25671.0, 29407.411851763274, 30122.24694382658, 30900.381992360475, 31317.217069573442, 29944.700552994193, 30632.387352252383, 30828.845855771477, 30846.69153308438, 30878.0, 35708.28583428265, 35977.10057248485, 36804.89585312445, 36928.0, 37011.25977480382, 23217.651735224325, 30174.84912575423, 31278.90523831655, 31358.0, 31440.52839207368, 22138.386093612975, 23143.537129256983, 24173.274801755975, 24232.51534969326, 24480.877595611906], "value": 17135.385180534668, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.93234609479464, 429.67659608406115, 436.6899501354038, 442.7609091090811, 455.48530160918176, 20100.89949550252, 20971.370858874237, 21113.944302784857, 21932.013059412326, 22095.558088838214, 19172.904135479337, 20345.49136271594, 22782.65826012611, 23189.652155217642, 23409.0, 5996.79011234608, 6252.062190671413, 6256.217972753406, 6269.717862696167, 6274.59215151016, 5865.648061116347, 6095.969520152393, 6154.199954005995, 6155.230596175478, 6169.352218017111, 5895.469407753301, 6109.5723299369065, 6132.969335153341, 6148.446639802417, 6151.661658608775, 6049.364816694249, 6133.907991380132, 6175.59858609189, 6177.505799536027, 6193.938060619404, 4966.776495057695, 5108.131617625013, 5414.052540805344, 5507.889842203173, 5508.476694713996, 26047.609285860726, 27042.86478567614, 27186.592201117, 27242.863785681144, 27379.863100684568, 1993.5813479169392, 2151.9892400537897, 2247.820174386045, 2253.2226381898217, 2254.6505291679814, 13653.522126725617, 17682.823171768374, 17831.73252401215, 17936.372226972126, 18382.356617518322, 2138.4974530985205, 2184.421128400975, 2188.4310079379316, 2201.4936564590234, 2209.1384360099573, 149988.2500587501, 157983.0, 169403.0, 171370.0, 173165.13417432958, 9845.950770246103, 11696.824547631792, 11746.882531174579, 12461.813072803916, 12790.552330668383, 13704.3833027514, 16646.75029874553, 16664.916675416545, 16698.83301166997, 16706.0, 8819.603117859711, 9117.560978029493, 9119.498427586414, 9188.0, 9304.209142222851, 8736.606852691644, 8754.86867696985, 8775.122487751174, 8955.104489550993, 8964.68623598171, 7313.2321106284135, 7334.339909408179, 7814.413918956101, 7953.920460795433, 7996.280334769783, 2183.7816218378034, 2185.8579192352445, 2193.100828660242, 2193.6928829963817, 2344.4607740219844, 946.7633091727068, 961.6730311694058, 966.5891995901713, 973.1484950668165, 993.3592832623062, 20256.49358766013, 20711.0, 20753.066112024993, 20785.89607051955, 21059.15763369448, 967.4969016111583, 993.6124911284605, 998.6754304850953, 1001.9048190421898, 1023.1661196125134, 10101.494925253855, 10405.06354428105, 10631.149508039338, 10679.626213082505, 10768.420843514656, 541228.0, 542043.0, 542842.0, 553389.0, 553792.0, 121053.39473302577, 125845.0, 131048.51611871028, 136139.0, 139348.30325848507, 1098.7913295375183, 1197.2457351868268, 1221.9327936963552, 1248.3446190749748, 1256.0265794009715, 20661.793382065985, 20754.896225518776, 21528.909507193584, 22049.11803527841, 22052.88973555186, 54042.64893377697, 55107.0, 55166.44833551613, 55270.170947435014, 57883.421165787804, 75269.0, 75897.86153207817, 76061.23938760762, 76273.23726762661, 76500.0, 8784.604692788713, 8785.023369741757, 8836.160564746338, 9210.447373157633, 9261.398009129385, 13823.930880345937, 13862.0, 14119.223442710749, 14240.71518569602, 14318.785218221943, 10507.527161277609, 11829.408529573659, 12076.698082548006, 12187.878121218675, 12198.756024879276, 10743.08683761889, 11097.889021110008, 11185.608503702331, 11411.087113031104, 11417.257878237888, 10808.297460665031, 11850.407479626158, 11968.76062478763, 12207.267563946192, 12412.379381031093, 8849.91150088491, 8913.885764279465, 8925.464472131693, 8972.461652300883, 8995.0, 11911.106666999836, 11946.283223006647, 12071.698207544881, 12180.269183848997, 12296.262224266573], "value": 10454.144687440208, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.6243451833486, 1378.1386633354155, 1393.4217299820575, 1400.8529104444033, 1412.8445870954197, 11667.24162929409, 12048.457819398138, 12058.57794977176, 12065.155439119266, 12364.134510584263, 11900.0, 11921.940390298058, 12086.335251561182, 12106.334151621657, 12110.878891211085, 2574.279201823483, 2615.882285297166, 2632.9473410531778, 2635.3675117971643, 2635.868206589663, 2240.5070884405486, 2451.3381387025493, 2549.1715192562397, 2578.22653204039, 2578.858162801045, 2305.9538809223804, 2480.838745481538, 2567.5891857302836, 2607.34816295926, 2613.8431694098417, 2537.8731063446844, 2564.3204550794053, 2595.2603508000225, 2606.504764094835, 2608.1914606472055, 2533.1134103063782, 2566.8203225774205, 2599.7790187833866, 2634.486275176341, 2642.2469596165192, 11704.648860534198, 11740.236884602473, 11830.645080647508, 11863.46614402354, 11870.940645296805, 6613.867722645568, 6769.153855768029, 6784.0841486399195, 6865.0, 6868.828279293008, 4131.400946862718, 4451.888702782457, 4485.1254005468945, 4503.256962601167, 4506.662000349987, 1235.086036333116, 1255.8492980842266, 1294.8964082873347, 1299.3048718935324, 1300.09643297908, 15586.06483610987, 16077.839221607868, 16401.83598164027, 16455.177241137895, 16585.25366358517, 5516.172574113834, 5599.580031497572, 5613.0, 5757.481826635623, 5800.8259752207505, 11530.308181509135, 11531.250468719507, 11540.942295288638, 11541.307521548733, 11586.246893951866, 6496.675166241669, 6539.50953678476, 6551.279359270477, 6557.475401967831, 6586.110875031809, 5973.492253158443, 6179.69101544921, 6213.627182369072, 6295.685215739195, 6304.275008374004, 3739.6634302912867, 4076.673866090706, 4393.538678438782, 4421.712588681726, 4446.755428451402, 1107.85044019058, 1274.2927675140202, 1278.520554791953, 1302.7720148973892, 1305.3212329588562, 683.7435961514432, 781.6287263549768, 799.6361655446758, 806.7378102116718, 811.2941740685524, 6153.4154255345675, 6205.2553693557065, 6283.497320214372, 6354.015127655221, 6388.233411990591, 589.1633879890566, 592.1236569876521, 611.6574718157846, 621.7544070092288, 621.7699451202993, 3539.6460353964912, 4300.956990430118, 4389.31965545341, 4436.134953684001, 4503.279475283939, 14605.488807891672, 14795.926020369829, 15064.322105505278, 15332.846671533141, 15399.92300038515, 14694.191819450032, 14885.92557037208, 14948.551543453714, 15107.848921510644, 15128.773068403983, 7559.886601700979, 8467.237948584658, 8510.914890851136, 8632.784180395414, 8650.826983460296, 5465.672059676433, 7076.186238582423, 7150.856982860418, 7307.525010874276, 7339.055150385259, 11784.4696988634, 12635.620931372074, 12895.226286422845, 12933.03002274852, 12944.870551294367, 13586.388612512263, 13943.16341019542, 13949.721005580035, 14031.087979281314, 14144.78782818238, 8246.670133194604, 8304.252617264234, 8326.375521836007, 9187.632494700138, 9481.288903332415, 10057.0, 10651.30766500175, 10979.83530247031, 11073.058790002618, 11162.218644694793, 6930.341617546325, 7165.820854478679, 9102.772430689285, 9362.953185234303, 9467.66863159786, 8806.779830504289, 8876.955615222141, 8897.599608017525, 8920.420172688755, 9033.780439640565, 8226.341892648694, 8250.226201366735, 8332.625031873702, 8340.457870238415, 8380.874286885579, 5235.319408476874, 5822.301323841166, 6123.418275263843, 6150.446459818638, 6275.90171719938, 6069.514438844793, 6126.0, 6252.624842509464, 6290.119383286435, 6399.168108146097], "value": 5323.117342044794, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.44167565193084, 398.5416770713677, 402.1093278388369, 410.854146777894, 412.6368795459995, 5567.888642227158, 6053.940560401934, 6070.816190842787, 6124.571280010401, 6143.078538219269, 4801.855944321667, 4916.164252077147, 5584.329880414346, 6110.877782444349, 6202.7284406696635, 4441.555844415565, 4485.304777759453, 4531.27499600064, 4534.546545345472, 4570.748608826513, 4535.251683472223, 4536.954630453686, 4549.24937385331, 4565.543445655425, 4582.587567118959, 3590.443481260396, 3948.447217389568, 4399.218316581773, 4508.188526065307, 4524.298733696266, 4449.466064072316, 4454.198244316021, 4504.617107545862, 4513.7517436540975, 4529.075143064201, 3310.652381499932, 3764.9458151717563, 3772.9622703772884, 3821.3503704370046, 3836.0985168485386, 5435.646682965634, 5635.5773317001385, 6054.878902421971, 6073.635581865102, 6101.511879049665, 4328.891777705582, 4554.521775213588, 5358.3569971603265, 5436.483534064258, 5439.0, 5728.942710572925, 5731.598788084836, 6608.933910660928, 6613.7685181018915, 6619.470442364599, 4347.0, 4362.556968097875, 4368.89077773055, 4373.497047839475, 4376.6936314458, 6627.701753421107, 6677.5643236704345, 6846.8972965405555, 6917.239103698589, 7019.262977387352, 3612.458131280329, 3728.757630753992, 4410.963423595516, 4421.209410188879, 4427.667924905645, 3752.81235938202, 4476.37330773692, 4491.820327186876, 4495.865124046284, 4528.88677783058, 4883.389576302962, 5150.562202212854, 5290.894182116336, 5406.216098665672, 5408.891822163535, 6088.817335479943, 6603.712276106113, 6607.273199948002, 6612.537122401482, 6618.073469714244, 1297.9221246725226, 1518.772184172383, 1544.274191130167, 1544.5906834688817, 1546.3350759173663, 914.465037952805, 973.5813600151861, 1006.6527048168371, 1007.1942446043137, 1009.7172791618211, 6086.239220097488, 6321.873562528724, 6351.364863513704, 6352.777652782131, 6372.840678983062, 5984.611000284967, 6014.127951447016, 6679.799606011828, 6809.285025072296, 6837.418819400406, 5777.826665200051, 6286.108390140495, 6317.778877739257, 6353.936460635427, 6390.201224846895, 3126.3122113135073, 3411.556497655289, 3417.111550996709, 3495.563054618173, 3595.065283026432, 6580.40776330123, 6931.4108300795, 7028.648567571703, 7095.680694368766, 7103.183133939559, 6236.314005459396, 6303.054541818672, 7004.0, 7026.402755765817, 7042.190148132928, 5468.808591699351, 6099.115628233882, 6681.665916704145, 6815.795526134224, 6917.12342798326, 5706.0, 6495.610263384212, 6811.489138314547, 6821.8635627286185, 6831.009503622046, 5919.845630102089, 6221.3156552780065, 6650.634215118215, 6703.396694297537, 6706.6646667665445, 5882.499987500939, 6171.444569988543, 6779.0, 6847.520673553003, 6993.685284162123, 3660.762050466711, 3756.6619004289746, 4417.1386579616665, 4431.977840110908, 4437.29003359461, 5343.492368225013, 5418.864528386822, 6885.449164066963, 6889.655517224219, 6917.89623155643, 6516.5112616553, 6579.151196550072, 6613.0, 6626.734930602722, 6630.9336906630315, 5618.494335509824, 5647.011772939802, 6336.58812177207, 6431.646259455775, 6567.671616419256, 4871.9269210962575, 5055.924161137659, 6379.681015949277, 6447.548671592944, 6452.579832940352, 6550.312217217123, 6585.934140658724, 6595.934040659724, 6619.106420633153, 6628.436582890508, 5148.665336753099, 6272.529560283088, 6302.810915672537, 6341.0, 6511.609303441809], "value": 4703.167505454513, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.3552364679193, 850.3112478892098, 862.7454900804262, 867.054910147939, 879.3624622148943, 14774.778378324334, 15033.242859632954, 16791.328346866125, 16941.915290423545, 16943.661126777457, 13542.187468751881, 13938.51215207468, 18182.363617273397, 18229.453116406497, 18230.0, 799.3565180030073, 981.4749109226565, 990.1781521337273, 991.0782971836189, 993.0466751918132, 941.2422999485411, 977.3061126600122, 983.5279066048302, 993.3642468819961, 999.1956475037592, 1034.606849397228, 1047.5338474378866, 1048.2190767877942, 1048.5700862646318, 1054.2778196935124, 1032.5250384822953, 1044.634377967713, 1046.08467590858, 1047.177965297238, 1047.6019112737188, 760.6539024743784, 762.371043888794, 771.9809850996687, 772.82997740497, 776.3672606825476, 16940.91529542357, 17160.14199290043, 17261.0, 17339.306427742875, 17355.739663905053, 17273.65452690939, 17492.47522574325, 17557.824421755748, 17588.504977076966, 17802.91098544512, 14299.57101286963, 14539.636509087251, 14541.927290363481, 14728.631784205374, 14739.852601474062, 3614.530111085542, 3621.5835178954494, 3651.908702282464, 3660.4692319613505, 3664.8167591620577, 98688.0, 127673.0, 127733.0, 130982.34508827394, 131231.0, 13094.869051309555, 13298.667533311746, 13479.989000824977, 13484.258365789976, 13651.0, 25607.48785024289, 25745.871270643525, 25768.484630307288, 26004.21987340383, 26225.606615900397, 7579.54522728638, 7663.501872378278, 7690.923090769021, 7885.1720569339395, 7995.680172793023, 3888.5528164260904, 3944.2505923874373, 3947.940780888289, 3969.0871099646665, 3972.364421692573, 4152.671145233497, 4196.958030419657, 4240.597143271384, 4253.914921701548, 4257.808398622069, 927.7541451515353, 945.4610871803144, 949.9050094990446, 956.1920177450044, 961.6682244625594, 571.6198727846019, 644.6776611694153, 653.6208998780696, 654.2182092399537, 690.8549204667077, 11228.382438965935, 11325.660230193107, 11913.761724765456, 11954.342511161969, 12056.517739290506, 623.423333416597, 648.6497291462651, 660.0824853453703, 667.7529314153794, 672.2470832667444, 4772.618190544748, 5418.512333889968, 5600.803971860965, 5636.464535869086, 5727.541796656257, 99681.0, 100778.0, 102168.0, 102668.48665756773, 103996.48001759943, 90736.54631726799, 93950.5302473497, 94560.0, 95369.56945645821, 95561.0, 60077.0, 62608.24350538982, 84554.0, 85454.14545854462, 85663.0, 11246.04408625276, 11332.943335283271, 11372.545098196311, 11447.771044578894, 11548.191626586058, 36848.60484068527, 42279.577204227564, 42756.5724342765, 43438.782806085765, 43469.78265108654, 54104.0, 54598.0, 55010.0, 55200.6199844991, 55455.0, 12048.698782530508, 13876.0, 13953.860461395256, 14217.573472795833, 14229.573112806633, 15447.5365739028, 15722.842771572137, 15784.289706962985, 15797.447089351817, 15825.0, 14347.784783228051, 14627.707445851236, 14759.483418080745, 14789.334479948644, 16391.83608163903, 16650.666986660442, 17082.743758843877, 17439.738403923697, 17455.952642841472, 17485.737713934046, 16275.51173464798, 16682.58293542671, 16897.66204675875, 17036.955565331744, 17079.7438038432, 11675.532978681098, 11688.590899318824, 11920.582779602672, 11968.341741204318, 12252.632421027383, 12795.0, 13118.672033198865, 13184.208947463183, 13190.934045330096, 13240.470381184645], "value": 8276.649520766425, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 115.0, 114.0, 115.0, 114.0, 118.0, 162.0, 114.0, 155.0], "value": 122.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 117.0, 144.0, 150.0, 106.0, 111.0, 108.0, 119.0, 148.0, 121.0], "value": 126.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 196.0, 199.0, 201.0, 253.0, 250.0, 254.0, 283.0, 239.0, 201.0], "value": 227.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 60.0, 88.0, 58.0, 58.0, 61.0, 55.0, 76.0, 59.0], "value": 65.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 89.0, 104.0, 85.0, 70.0, 86.0, 72.0, 73.0, 99.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 90.0, 95.0, 89.0, 89.0, 89.0, 90.0, 102.0, 90.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 97.0, 97.0, 98.0, 97.0, 97.0, 128.0, 156.0, 109.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 92.0, 94.0, 95.0, 91.0, 90.0, 94.0, 97.0, 95.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 51.0, 51.0, 51.0, 50.0, 51.0, 50.0, 51.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 57.0, 55.0, 63.0, 59.0, 57.0, 57.0, 57.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 83.0, 85.0, 84.0, 83.0, 84.0, 104.0, 109.0, 103.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 141.0, 140.0, 139.0, 158.0, 151.0, 138.0, 138.0, 189.0, 136.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 185.0, 185.0, 188.0, 189.0, 181.0, 257.0, 185.0, 186.0, 185.0], "value": 193.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 71.0, 69.0, 72.0, 70.0, 70.0, 67.0, 69.0, 68.0], "value": 69.7, "unit": "score"}]}]} 07:49:25 INFO - Return code: 0 07:49:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:49:25 INFO - # TBPL SUCCESS # 07:49:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:49:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:49:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:49:25 INFO - 2017-05-16 07:49:25.501 screenresolution[1661:10484] starting screenresolution argv=screenresolution get 07:49:25 INFO - 2017-05-16 07:49:25.503 screenresolution[1661:10484] Display 0: 1600x1200x32@60 07:49:25 INFO - 2017-05-16 07:49:25.509 screenresolution[1662:10487] starting screenresolution argv=screenresolution list 07:49:25 INFO - Available Modes on Display 0 07:49:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:49:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:49:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:49:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:49:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:49:25 INFO - Intel Iris: 07:49:25 INFO - Chipset Model: Intel Iris 07:49:25 INFO - Type: GPU 07:49:25 INFO - Bus: Built-In 07:49:25 INFO - VRAM (Dynamic, Max): 1536 MB 07:49:25 INFO - Vendor: Intel (0x8086) 07:49:25 INFO - Device ID: 0x0a2e 07:49:25 INFO - Revision ID: 0x0009 07:49:25 INFO - Displays: 07:49:25 INFO - Display: 07:49:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:49:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:49:25 INFO - Display Serial Number: 1600x1200 60 07:49:25 INFO - Main Display: Yes 07:49:25 INFO - Mirror: Off 07:49:25 INFO - Online: Yes 07:49:25 INFO - Rotation: Supported 07:49:25 INFO - Return code: 0 07:49:25 INFO - Running post-action listener: _resource_record_post_action 07:49:25 INFO - [mozharness: 2017-05-16 14:49:25.748918Z] Finished run-tests step (success) 07:49:25 INFO - Running post-run listener: _resource_record_post_run 07:49:25 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 40320000; Write bytes: 530192384; Read time: 264; Write time: 1669 07:49:25 INFO - TinderboxPrint: CPU usage
54.1% 07:49:25 INFO - TinderboxPrint: I/O read bytes / time
40,320,000 / 264 07:49:25 INFO - TinderboxPrint: I/O write bytes / time
530,192,384 / 1,669 07:49:25 INFO - TinderboxPrint: CPU idle
2,192.4 (45.9%) 07:49:25 INFO - TinderboxPrint: CPU system
960.9 (20.1%) 07:49:25 INFO - TinderboxPrint: CPU user
1,628.4 (34.1%) 07:49:25 INFO - TinderboxPrint: Swap in / out
254,214,144 / 0 07:49:25 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 221286912; Write bytes: 215662592; Read time: 16885; Write time: 487 07:49:25 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 30725120; Write bytes: 312920064; Read time: 196; Write time: 1174 07:49:26 INFO - Running post-run listener: _upload_blobber_files 07:49:26 INFO - Blob upload gear active. 07:49:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:49:26 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 07:49:26 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'] 07:49:26 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 07:49:26 INFO - (blobuploader) - INFO - Open directory for files ... 07:49:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:49:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:49:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:49:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:49:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:49:27 INFO - (blobuploader) - INFO - Done attempting. 07:49:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:49:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:49:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:49:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:49:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:49:28 INFO - (blobuploader) - INFO - Done attempting. 07:49:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:49:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:49:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:49:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:49:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:49:29 INFO - (blobuploader) - INFO - Done attempting. 07:49:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:49:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:49:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:49:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:49:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:49:32 INFO - (blobuploader) - INFO - Done attempting. 07:49:32 INFO - (blobuploader) - INFO - Iteration through files over. 07:49:32 INFO - Return code: 0 07:49:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:49:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:49:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/619aa28d6b3361705a024d31e244e71bcda7f811dfc790ef24bcef04fce6dc73ec8f458705627c4b09f9f9fb58a67652127786a0bd11246a67a56bc1cbde1dde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16a90cee782b2f460c5b7a96f120ce608b49fefefed82812ff1873e459bdf8b0988a35ad9aea0eee533660e3d223d34079765ba23d2d36e36d934dd0389df0c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc7765133bfac1c7a5f4b72746c5af82b0b4c3dfeb4789be2d881b8e8048190405973c152799ca5f8474a78b3f013f1cecc41d26fa680dcd353b70132a71c5cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d7c96d3bf5f13da33e861175c21fa875921d22e726bb94e4d6470b3b5511aa14f9bbc3436a1da750d8a7e1beefd85ef724561b215201a341a1a280f66f09a3c"} 07:49:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:49:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:49:32 INFO - Contents: 07:49:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/619aa28d6b3361705a024d31e244e71bcda7f811dfc790ef24bcef04fce6dc73ec8f458705627c4b09f9f9fb58a67652127786a0bd11246a67a56bc1cbde1dde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16a90cee782b2f460c5b7a96f120ce608b49fefefed82812ff1873e459bdf8b0988a35ad9aea0eee533660e3d223d34079765ba23d2d36e36d934dd0389df0c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc7765133bfac1c7a5f4b72746c5af82b0b4c3dfeb4789be2d881b8e8048190405973c152799ca5f8474a78b3f013f1cecc41d26fa680dcd353b70132a71c5cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d7c96d3bf5f13da33e861175c21fa875921d22e726bb94e4d6470b3b5511aa14f9bbc3436a1da750d8a7e1beefd85ef724561b215201a341a1a280f66f09a3c"} 07:49:32 INFO - Running post-run listener: copy_logs_to_upload_dir 07:49:32 INFO - Copying logs to upload dir... 07:49:32 INFO - mkdir: /builds/slave/test/build/upload/logs 07:49:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.541158 ========= master_lag: 1.51 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-16 07:49:33.614156) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 07:49:33.617183) ========= 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.qZJYwtRh5H/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.NCpilZCDXa/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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/619aa28d6b3361705a024d31e244e71bcda7f811dfc790ef24bcef04fce6dc73ec8f458705627c4b09f9f9fb58a67652127786a0bd11246a67a56bc1cbde1dde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16a90cee782b2f460c5b7a96f120ce608b49fefefed82812ff1873e459bdf8b0988a35ad9aea0eee533660e3d223d34079765ba23d2d36e36d934dd0389df0c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc7765133bfac1c7a5f4b72746c5af82b0b4c3dfeb4789be2d881b8e8048190405973c152799ca5f8474a78b3f013f1cecc41d26fa680dcd353b70132a71c5cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d7c96d3bf5f13da33e861175c21fa875921d22e726bb94e4d6470b3b5511aa14f9bbc3436a1da750d8a7e1beefd85ef724561b215201a341a1a280f66f09a3c"} build_url:https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011523 build_url: 'https://queue.taskcluster.net/v1/task/M0nmZ_V0RrSt865DFz2fHg/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/619aa28d6b3361705a024d31e244e71bcda7f811dfc790ef24bcef04fce6dc73ec8f458705627c4b09f9f9fb58a67652127786a0bd11246a67a56bc1cbde1dde", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/16a90cee782b2f460c5b7a96f120ce608b49fefefed82812ff1873e459bdf8b0988a35ad9aea0eee533660e3d223d34079765ba23d2d36e36d934dd0389df0c5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bc7765133bfac1c7a5f4b72746c5af82b0b4c3dfeb4789be2d881b8e8048190405973c152799ca5f8474a78b3f013f1cecc41d26fa680dcd353b70132a71c5cc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d7c96d3bf5f13da33e861175c21fa875921d22e726bb94e4d6470b3b5511aa14f9bbc3436a1da750d8a7e1beefd85ef724561b215201a341a1a280f66f09a3c"}' ========= master_lag: 0.89 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 07:49:34.517138) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 07:49:34.517466) ========= 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.qZJYwtRh5H/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.NCpilZCDXa/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005313 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 07:49:34.552370) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 07:49:34.552659) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 07:49:34.556808) ========= ========= Total master_lag: 2.49 =========