builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0204 starttime: 1496750525.22 results: success (0) revision: 4dd1d17ba22660b8f5869a707f2e4e9f9dd5be5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:05.220749) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:05.221174) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:05.221458) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:05.244860) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:05.245235) ========= 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.tFCUaFiY5s/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.z9R6girkiu/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-06 05:02:05-- 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% 153M=0s 2017-06-06 05:02:05 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.089260 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:05.357140) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:05.357461) ========= 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.tFCUaFiY5s/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.z9R6girkiu/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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.021996 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:05.406232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 05:02:05.406574) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4dd1d17ba22660b8f5869a707f2e4e9f9dd5be5b --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 4dd1d17ba22660b8f5869a707f2e4e9f9dd5be5b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tFCUaFiY5s/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.z9R6girkiu/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-06 05:02:05,490 truncating revision to first 12 chars 2017-06-06 05:02:05,490 Setting DEBUG logging. 2017-06-06 05:02:05,490 attempt 1/10 2017-06-06 05:02:05,490 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4dd1d17ba226?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-06 05:02:06,790 unpacking tar archive at: mozilla-central-4dd1d17ba226/testing/mozharness/ program finished with exit code 0 elapsedTime=1.562410 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-06 05:02:06.990741) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:06.991056) ========= script_repo_revision: 4dd1d17ba22660b8f5869a707f2e4e9f9dd5be5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:06.991425) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:06.991691) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-06 05:02:07.011934) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-06 05:02:07.012312) ========= /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.tFCUaFiY5s/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.z9R6girkiu/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:02:07 INFO - MultiFileLogger online at 20170606 05:02:07 in /builds/slave/test 05:02:07 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 05:02:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:02:07 INFO - {'append_to_log': False, 05:02:07 INFO - 'base_work_dir': '/builds/slave/test', 05:02:07 INFO - 'blob_upload_branch': 'Firefox', 05:02:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:02:07 INFO - 'branch': 'Firefox', 05:02:07 INFO - 'buildbot_json_path': 'buildprops.json', 05:02:07 INFO - 'config_files': ('talos/mac_config.py',), 05:02:07 INFO - 'default_actions': ('clobber', 05:02:07 INFO - 'read-buildbot-config', 05:02:07 INFO - 'download-and-extract', 05:02:07 INFO - 'populate-webroot', 05:02:07 INFO - 'create-virtualenv', 05:02:07 INFO - 'install', 05:02:07 INFO - 'setup-mitmproxy', 05:02:07 INFO - 'run-tests'), 05:02:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:02:07 INFO - 'download_minidump_stackwalk': True, 05:02:07 INFO - 'download_symbols': 'ondemand', 05:02:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:02:07 INFO - 'tooltool.py': '/tools/tooltool.py', 05:02:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:02:07 INFO - '/tools/misc-python/virtualenv.py')}, 05:02:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:02:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:02:07 INFO - 'gecko_profile': False, 05:02:07 INFO - 'gecko_profile_interval': 0, 05:02:07 INFO - 'installer_path': 'installer.exe', 05:02:07 INFO - 'log_level': 'info', 05:02:07 INFO - 'log_name': 'talos', 05:02:07 INFO - 'log_to_console': True, 05:02:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:02:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:02:07 INFO - 'opt_config_files': (), 05:02:07 INFO - 'pip_index': False, 05:02:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:02:07 INFO - 'cmd': ('bash', 05:02:07 INFO - '-c', 05:02:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:02:07 INFO - 'enabled': True, 05:02:07 INFO - 'halt_on_failure': False, 05:02:07 INFO - 'name': 'check_screen_resolution'},), 05:02:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:02:07 INFO - 'cmd': ('bash', 05:02:07 INFO - '-c', 05:02:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:02:07 INFO - 'enabled': True, 05:02:07 INFO - 'halt_on_failure': False, 05:02:07 INFO - 'name': 'check_screen_resolution'},), 05:02:07 INFO - 'run_cmd_checks_enabled': True, 05:02:07 INFO - 'suite': 'dromaeojs-e10s', 05:02:07 INFO - 'system_bits': '32', 05:02:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:02:07 INFO - 'title': 't-yosemite-r7-0204', 05:02:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:02:07 INFO - 'use_talos_json': True, 05:02:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:02:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:02:07 INFO - 'work_dir': 'build'} 05:02:07 INFO - [mozharness: 2017-06-06 12:02:07.212612Z] Running clobber step. 05:02:07 INFO - Running pre-action listener: _resource_record_pre_action 05:02:07 INFO - Running main action method: clobber 05:02:07 INFO - rmtree: /builds/slave/test/build 05:02:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:02:08 INFO - Running post-action listener: _resource_record_post_action 05:02:08 INFO - [mozharness: 2017-06-06 12:02:08.996816Z] Finished clobber step (success) 05:02:08 INFO - [mozharness: 2017-06-06 12:02:08.996944Z] Running read-buildbot-config step. 05:02:08 INFO - Running pre-action listener: _resource_record_pre_action 05:02:08 INFO - Running main action method: read_buildbot_config 05:02:08 INFO - Using buildbot properties: 05:02:08 INFO - { 05:02:08 INFO - "project": "", 05:02:08 INFO - "product": "firefox", 05:02:08 INFO - "who": "ffxbld@noreply.mozilla.org", 05:02:08 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 05:02:08 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:02:08 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 05:02:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:02:08 INFO - "stage_platform": "macosx64", 05:02:08 INFO - "basedir": "/builds/slave/test", 05:02:08 INFO - "buildnumber": 232, 05:02:08 INFO - "platform": "yosemite_r7", 05:02:08 INFO - "parent_task_id": "cE1wU3foSe-vXL2xYUHdEA", 05:02:08 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 05:02:08 INFO - "slavebuilddir": "test", 05:02:08 INFO - "taskId": "XDocIUcgTPebzvAe0YrYlg", 05:02:08 INFO - "branch": "mozilla-central", 05:02:08 INFO - "script_repo_revision": "production", 05:02:08 INFO - "revision": "4dd1d17ba22660b8f5869a707f2e4e9f9dd5be5b", 05:02:08 INFO - "slavename": "t-yosemite-r7-0204", 05:02:09 INFO - "repo_path": "mozilla-central" 05:02:09 INFO - } 05:02:09 INFO - Finding installer, test and symbols from parent task. 05:02:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDocIUcgTPebzvAe0YrYlg'}, attempt #1 05:02:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDocIUcgTPebzvAe0YrYlg'}, attempt #1 05:02:10 INFO - Task dependencies: Mvu6btoJRkKqauHZzfZyzw cE1wU3foSe-vXL2xYUHdEA 05:02:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA'}, attempt #1 05:02:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:02:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/test_packages.json 05:02:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/target.crashreporter-symbols.zip 05:02:10 INFO - Running post-action listener: _resource_record_post_action 05:02:10 INFO - [mozharness: 2017-06-06 12:02:10.872075Z] Finished read-buildbot-config step (success) 05:02:10 INFO - [mozharness: 2017-06-06 12:02:10.872219Z] Running download-and-extract step. 05:02:10 INFO - Running pre-action listener: _resource_record_pre_action 05:02:10 INFO - Running main action method: download_and_extract 05:02:10 INFO - mkdir: /builds/slave/test/build/tests 05:02:10 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')]} 05:02:10 INFO - trying https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 05:02:10 INFO - Downloading https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/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 05:02:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/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 05:02:11 INFO - Downloaded 1328 bytes. 05:02:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 05:02:11 INFO - Using the following test package requirements: 05:02:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 05:02:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:02:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 05:02:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 05:02:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'jsshell-mac.zip'], 05:02:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 05:02:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 05:02:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 05:02:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 05:02:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 05:02:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 05:02:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 05:02:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:02:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:02:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:02:11 INFO - Fetch https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:02:13 INFO - Content-Length response header: 57632999 05:02:13 INFO - Bytes received: 57632999 05:02:31 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 05:02:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 05:02:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:02:31 INFO - Fetch https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 05:02:34 INFO - Content-Length response header: 57632999 05:02:34 INFO - Bytes received: 57632999 05:02:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 05:02:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:02:51 INFO - Fetch https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 05:02:52 INFO - Content-Length response header: 14447479 05:02:52 INFO - Bytes received: 14447479 05:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:53 INFO - trying https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:02:53 INFO - Downloading https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 05:02:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/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 05:02:55 INFO - Downloaded 67987138 bytes. 05:02:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:02:55 INFO - mkdir: /builds/slave/test/properties 05:02:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:02:55 INFO - Writing to file /builds/slave/test/properties/build_url 05:02:55 INFO - Contents: 05:02:55 INFO - build_url:https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 05:02:55 INFO - Running post-action listener: _resource_record_post_action 05:02:55 INFO - Running post-action listener: set_extra_try_arguments 05:02:55 INFO - [mozharness: 2017-06-06 12:02:55.011916Z] Finished download-and-extract step (success) 05:02:55 INFO - [mozharness: 2017-06-06 12:02:55.012035Z] Running populate-webroot step. 05:02:55 INFO - Running pre-action listener: _resource_record_pre_action 05:02:55 INFO - Running main action method: populate_webroot 05:02:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:02:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:02:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:02:55 INFO - '--tppagecycles', 05:02:55 INFO - '7'], 05:02:55 INFO - 'tests': ['tsvgm']}}, 05:02:55 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:02:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:02:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:02:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:02:55 INFO - 'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:02:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:02:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:02:55 INFO - 'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['damp', 'tps']}, 05:02:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:02:55 INFO - 'tests': ['damp', 'tps']}, 05:02:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['dromaeo_dom']}, 05:02:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:02:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 05:02:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 05:02:55 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 05:02:55 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 05:02:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['a11yr', 05:02:55 INFO - 'ts_paint', 05:02:55 INFO - 'tpaint', 05:02:55 INFO - 'sessionrestore', 05:02:55 INFO - 'sessionrestore_no_auto_restore', 05:02:55 INFO - 'tabpaint']}, 05:02:55 INFO - 'other-e10s': {'tests': ['a11yr', 05:02:55 INFO - 'ts_paint', 05:02:55 INFO - 'tpaint', 05:02:55 INFO - 'sessionrestore', 05:02:55 INFO - 'sessionrestore_no_auto_restore', 05:02:55 INFO - 'tabpaint']}, 05:02:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:02:55 INFO - 'ts_paint', 05:02:55 INFO - 'tpaint', 05:02:55 INFO - 'sessionrestore', 05:02:55 INFO - 'sessionrestore_no_auto_restore', 05:02:55 INFO - 'tabpaint']}, 05:02:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:02:55 INFO - 'ts_paint', 05:02:55 INFO - 'tpaint', 05:02:55 INFO - 'sessionrestore', 05:02:55 INFO - 'sessionrestore_no_auto_restore', 05:02:55 INFO - 'tabpaint']}, 05:02:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['a11yr', 05:02:55 INFO - 'ts_paint', 05:02:55 INFO - 'tpaint', 05:02:55 INFO - 'sessionrestore', 05:02:55 INFO - 'sessionrestore_no_auto_restore', 05:02:55 INFO - 'tabpaint']}, 05:02:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['a11yr', 05:02:55 INFO - 'ts_paint', 05:02:55 INFO - 'tpaint', 05:02:55 INFO - 'sessionrestore', 05:02:55 INFO - 'sessionrestore_no_auto_restore', 05:02:55 INFO - 'tabpaint']}, 05:02:55 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 05:02:55 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 05:02:55 INFO - 'bloom_basic_ref']}, 05:02:55 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 05:02:55 INFO - 'talos_options': ['--mitmproxy', 05:02:55 INFO - 'mitmproxy-recording-1.mp', 05:02:55 INFO - '--firstNonBlankPaint'], 05:02:55 INFO - 'tests': ['Quantum_1']}, 05:02:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['tsvgx', 05:02:55 INFO - 'tsvgr_opacity', 05:02:55 INFO - 'tart', 05:02:55 INFO - 'tscrollx', 05:02:55 INFO - 'cart', 05:02:55 INFO - 'tsvg_static']}, 05:02:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:02:55 INFO - 'tsvgr_opacity', 05:02:55 INFO - 'tart', 05:02:55 INFO - 'tscrollx', 05:02:55 INFO - 'cart', 05:02:55 INFO - 'tsvg_static']}, 05:02:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:02:55 INFO - 'talos_options': ['--disable-e10s'], 05:02:55 INFO - 'tests': ['tp5o']}, 05:02:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:02:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:02:55 INFO - 'talos_options': ['--disable-e10s', 05:02:55 INFO - '--xperf_path', 05:02:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:55 INFO - 'tests': ['tp5n']}, 05:02:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:02:55 INFO - 'talos_options': ['--xperf_path', 05:02:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:02:55 INFO - 'tests': ['tp5n']}}, 05:02:55 INFO - 'talos.zip': {'path': '', 05:02:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:02:55 INFO - Running post-action listener: _resource_record_post_action 05:02:55 INFO - [mozharness: 2017-06-06 12:02:55.034100Z] Finished populate-webroot step (success) 05:02:55 INFO - [mozharness: 2017-06-06 12:02:55.034235Z] Running create-virtualenv step. 05:02:55 INFO - Running pre-action listener: _resource_record_pre_action 05:02:55 INFO - Running main action method: create_virtualenv 05:02:55 INFO - Creating virtualenv /builds/slave/test/build/venv 05:02:55 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:02:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:02:55 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 05:02:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:02:55 INFO - Using real prefix '/tools/python27' 05:02:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:02:55 INFO - Installing distribute.............................................................................................................................................................................................done. 05:02:58 INFO - Installing pip.................done. 05:02:58 INFO - Return code: 0 05:02:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:02:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:02:58 INFO - Reading from file tmpfile_stdout 05:02:58 INFO - Output received: 05:02:58 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) 05:02:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:58 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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:02:58 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 05:02:58 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 05:02:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:02:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:58 INFO - 'HOME': '/Users/cltbld', 05:02:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:58 INFO - 'LOGNAME': 'cltbld', 05:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:58 INFO - 'MOZ_NO_REMOTE': '1', 05:02:58 INFO - 'NO_EM_RESTART': '1', 05:02:58 INFO - 'PAGER': '/bin/cat', 05:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:59 INFO - 'PWD': '/builds/slave/test', 05:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:59 INFO - 'SHELL': '/bin/bash', 05:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:02:59 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:59 INFO - 'USER': 'cltbld', 05:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:59 INFO - 'XPC_FLAGS': '0x0', 05:02:59 INFO - 'XPC_SERVICE_NAME': '0', 05:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:59 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 05:02:59 INFO - Cleaning up... 05:02:59 INFO - Return code: 0 05:02:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:02:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:02:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:02:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:02:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:02:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:02:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:02:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:02:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:02:59 INFO - 'HOME': '/Users/cltbld', 05:02:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:02:59 INFO - 'LOGNAME': 'cltbld', 05:02:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:02:59 INFO - 'MOZ_NO_REMOTE': '1', 05:02:59 INFO - 'NO_EM_RESTART': '1', 05:02:59 INFO - 'PAGER': '/bin/cat', 05:02:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:02:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:02:59 INFO - 'PWD': '/builds/slave/test', 05:02:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:02:59 INFO - 'SHELL': '/bin/bash', 05:02:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:02:59 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:02:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:02:59 INFO - 'USER': 'cltbld', 05:02:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:02:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:02:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:02:59 INFO - 'XPC_FLAGS': '0x0', 05:02:59 INFO - 'XPC_SERVICE_NAME': '0', 05:02:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:02:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:02:59 INFO - Downloading/unpacking psutil>=3.1.1 05:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:02:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:02:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:03:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:03:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:03:01 INFO - Installing collected packages: psutil 05:03:01 INFO - Running setup.py install for psutil 05:03:01 INFO - building 'psutil._psutil_osx' extension 05:03:01 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 05:03:01 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 05:03:01 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 05:03:02 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 05:03:02 INFO - building 'psutil._psutil_posix' extension 05:03:02 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 05:03:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:03:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:03:02 INFO - ^ 05:03:02 INFO - 1 warning generated. 05:03:02 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 05:03:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:03:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:03:02 INFO - Successfully installed psutil 05:03:02 INFO - Cleaning up... 05:03:02 INFO - Return code: 0 05:03:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:03:02 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')]} 05:03:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:02 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')]} 05:03:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:03:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:03:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:03:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:03 INFO - 'HOME': '/Users/cltbld', 05:03:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:03 INFO - 'LOGNAME': 'cltbld', 05:03:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:03 INFO - 'MOZ_NO_REMOTE': '1', 05:03:03 INFO - 'NO_EM_RESTART': '1', 05:03:03 INFO - 'PAGER': '/bin/cat', 05:03:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:03 INFO - 'PWD': '/builds/slave/test', 05:03:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:03 INFO - 'SHELL': '/bin/bash', 05:03:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:03 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:03 INFO - 'USER': 'cltbld', 05:03:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:03 INFO - 'XPC_FLAGS': '0x0', 05:03:03 INFO - 'XPC_SERVICE_NAME': '0', 05:03:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:03:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:03:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:03:05 INFO - Installing collected packages: mozsystemmonitor 05:03:05 INFO - Running setup.py install for mozsystemmonitor 05:03:05 INFO - Successfully installed mozsystemmonitor 05:03:05 INFO - Cleaning up... 05:03:05 INFO - Return code: 0 05:03:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:05 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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:03:05 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 05:03:05 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 05:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:05 INFO - 'HOME': '/Users/cltbld', 05:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:05 INFO - 'LOGNAME': 'cltbld', 05:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:05 INFO - 'MOZ_NO_REMOTE': '1', 05:03:05 INFO - 'NO_EM_RESTART': '1', 05:03:05 INFO - 'PAGER': '/bin/cat', 05:03:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:05 INFO - 'PWD': '/builds/slave/test', 05:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:05 INFO - 'SHELL': '/bin/bash', 05:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:05 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:05 INFO - 'USER': 'cltbld', 05:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:05 INFO - 'XPC_FLAGS': '0x0', 05:03:05 INFO - 'XPC_SERVICE_NAME': '0', 05:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:05 INFO - Downloading/unpacking jsonschema==2.5.1 05:03:05 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 05:03:05 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 05:03:05 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 05:03:05 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 05:03:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:03:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:03:07 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 05:03:07 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 05:03:07 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 05:03:07 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 05:03:07 INFO - Downloading functools32-3.2.3-2.tar.gz 05:03:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:03:07 INFO - warning: no files found matching '*.txt' 05:03:07 INFO - no previously-included directories found matching 'build' 05:03:07 INFO - no previously-included directories found matching 'dist' 05:03:07 INFO - no previously-included directories found matching '.git*' 05:03:07 INFO - Installing collected packages: jsonschema, functools32 05:03:07 INFO - Running setup.py install for functools32 05:03:07 INFO - warning: no files found matching '*.txt' 05:03:07 INFO - no previously-included directories found matching 'build' 05:03:07 INFO - no previously-included directories found matching 'dist' 05:03:07 INFO - no previously-included directories found matching '.git*' 05:03:07 INFO - Successfully installed jsonschema functools32 05:03:07 INFO - Cleaning up... 05:03:07 INFO - Return code: 0 05:03:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:03: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')]} 05:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03: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')]} 05:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03: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', '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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:03: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:03: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 functools32==3.2.3-2 05:03:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:07 INFO - 'HOME': '/Users/cltbld', 05:03:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:07 INFO - 'LOGNAME': 'cltbld', 05:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:07 INFO - 'MOZ_NO_REMOTE': '1', 05:03:07 INFO - 'NO_EM_RESTART': '1', 05:03:07 INFO - 'PAGER': '/bin/cat', 05:03:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:07 INFO - 'PWD': '/builds/slave/test', 05:03:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:07 INFO - 'SHELL': '/bin/bash', 05:03:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:07 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:07 INFO - 'USER': 'cltbld', 05:03:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:07 INFO - 'XPC_FLAGS': '0x0', 05:03:07 INFO - 'XPC_SERVICE_NAME': '0', 05:03:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:03:07 INFO - Cleaning up... 05:03:07 INFO - Return code: 0 05:03:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:03: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')]} 05:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03: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')]} 05:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03: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', '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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:03: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:03: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 blobuploader==1.2.4 05:03:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:07 INFO - 'HOME': '/Users/cltbld', 05:03:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:07 INFO - 'LOGNAME': 'cltbld', 05:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:07 INFO - 'MOZ_NO_REMOTE': '1', 05:03:07 INFO - 'NO_EM_RESTART': '1', 05:03:07 INFO - 'PAGER': '/bin/cat', 05:03:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:07 INFO - 'PWD': '/builds/slave/test', 05:03:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:07 INFO - 'SHELL': '/bin/bash', 05:03:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:07 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:07 INFO - 'USER': 'cltbld', 05:03:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:07 INFO - 'XPC_FLAGS': '0x0', 05:03:07 INFO - 'XPC_SERVICE_NAME': '0', 05:03:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:08 INFO - Downloading/unpacking blobuploader==1.2.4 05:03: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 05:03: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 05:03: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 05:03: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 05:03:09 INFO - Downloading blobuploader-1.2.4.tar.gz 05:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:03:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:03:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:10 INFO - Downloading docopt-0.6.1.tar.gz 05:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:03:10 INFO - Installing collected packages: blobuploader, requests, docopt 05:03:10 INFO - Running setup.py install for blobuploader 05:03:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:03:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:03:10 INFO - Running setup.py install for requests 05:03:11 INFO - Running setup.py install for docopt 05:03:11 INFO - Successfully installed blobuploader requests docopt 05:03:11 INFO - Cleaning up... 05:03:11 INFO - Return code: 0 05:03:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:11 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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:03:11 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 05:03:11 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 05:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:11 INFO - 'HOME': '/Users/cltbld', 05:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:11 INFO - 'LOGNAME': 'cltbld', 05:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:11 INFO - 'MOZ_NO_REMOTE': '1', 05:03:11 INFO - 'NO_EM_RESTART': '1', 05:03:11 INFO - 'PAGER': '/bin/cat', 05:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:11 INFO - 'PWD': '/builds/slave/test', 05:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:11 INFO - 'SHELL': '/bin/bash', 05:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:11 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:11 INFO - 'USER': 'cltbld', 05:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:11 INFO - 'XPC_FLAGS': '0x0', 05:03:11 INFO - 'XPC_SERVICE_NAME': '0', 05:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:03:11 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-eM92LG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:03:11 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-b_yM8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:03:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:03:11 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-UKWQiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-HEho5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-tCLna9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-Ol8ItM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-8liXba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-utVAIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-yCGFN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-mJZjbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-8Mck2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-NTJPbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:03:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:03:12 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-FgCLDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:03:13 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-nJQp1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:13 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-JTEdW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:03:13 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-RcoFLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:03:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:03:13 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-m5Rxs9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:03:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:03:13 INFO - Running setup.py install for manifestparser 05:03:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:03:13 INFO - Running setup.py install for mozcrash 05:03:13 INFO - Running setup.py install for mozdebug 05:03:13 INFO - Running setup.py install for mozdevice 05:03:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:03:14 INFO - Running setup.py install for mozfile 05:03:14 INFO - Running setup.py install for mozhttpd 05:03:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:03:14 INFO - Running setup.py install for mozinfo 05:03:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:03:14 INFO - Running setup.py install for mozInstall 05:03:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:03:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:03:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:03:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:03:14 INFO - Running setup.py install for mozleak 05:03:14 INFO - Running setup.py install for mozlog 05:03:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:03:14 INFO - Running setup.py install for moznetwork 05:03:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:03:15 INFO - Running setup.py install for mozprocess 05:03:15 INFO - Running setup.py install for mozprofile 05:03:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:03:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:03:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:03:15 INFO - Running setup.py install for mozrunner 05:03:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:03:15 INFO - Running setup.py install for mozscreenshot 05:03:15 INFO - Running setup.py install for moztest 05:03:15 INFO - Running setup.py install for mozversion 05:03:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:03:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:03:15 INFO - Cleaning up... 05:03:15 INFO - Return code: 0 05:03:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03:15 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')]} 05:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:15 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')]} 05:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:15 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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:03:15 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 05:03:15 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 05:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:15 INFO - 'HOME': '/Users/cltbld', 05:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:15 INFO - 'LOGNAME': 'cltbld', 05:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:15 INFO - 'MOZ_NO_REMOTE': '1', 05:03:15 INFO - 'NO_EM_RESTART': '1', 05:03:15 INFO - 'PAGER': '/bin/cat', 05:03:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:15 INFO - 'PWD': '/builds/slave/test', 05:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:15 INFO - 'SHELL': '/bin/bash', 05:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:15 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:15 INFO - 'USER': 'cltbld', 05:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:15 INFO - 'XPC_FLAGS': '0x0', 05:03:15 INFO - 'XPC_SERVICE_NAME': '0', 05:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:03:16 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-mjocvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:03:16 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)) 05:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:03:16 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-_s3zQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:03:16 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)) 05:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:03:16 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-diY1V8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:03:16 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)) 05:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:03:16 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-3U0B5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:03:16 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-UkW5np-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:03:16 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)) 05:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:03:16 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-ziQ5z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:03:16 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)) 05:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:03:16 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-M5ZCeS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 05:03:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:03:16 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-4CKxGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-A_UoBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-LAGP9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-Y5N_Qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-ypbRnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-4trNRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-5lIvg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-7uA7Rn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-2Pkkny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:03:17 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)) 05:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:03:17 INFO - Running setup.py (path:/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/pip-TZ8h_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:03:18 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)) 05:03:18 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)) 05:03:18 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)) 05:03:18 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)) 05:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:03:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:03: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 05:03: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 05:03: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 05:03: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 05:03:19 INFO - Downloading blessings-1.6.tar.gz 05:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:03:19 INFO - Installing collected packages: blessings 05:03:19 INFO - Running setup.py install for blessings 05:03:19 INFO - Successfully installed blessings 05:03:19 INFO - Cleaning up... 05:03:19 INFO - Return code: 0 05:03:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:03:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:20 INFO - Reading from file tmpfile_stdout 05:03:20 INFO - Current package versions: 05:03:20 INFO - blessings == 1.6 05:03:20 INFO - blobuploader == 1.2.4 05:03:20 INFO - docopt == 0.6.1 05:03:20 INFO - functools32 == 3.2.3-2 05:03:20 INFO - jsonschema == 2.5.1 05:03:20 INFO - manifestparser == 1.1 05:03:20 INFO - mozInstall == 1.12 05:03:20 INFO - mozcrash == 1.0 05:03:20 INFO - mozdebug == 0.1 05:03:20 INFO - mozdevice == 0.51 05:03:20 INFO - mozfile == 1.2 05:03:20 INFO - mozhttpd == 0.7 05:03:20 INFO - mozinfo == 0.10 05:03:20 INFO - mozleak == 0.1 05:03:20 INFO - mozlog == 3.4 05:03:20 INFO - moznetwork == 0.27 05:03:20 INFO - mozprocess == 0.25 05:03:20 INFO - mozprofile == 0.28 05:03:20 INFO - mozrunner == 6.13 05:03:20 INFO - mozscreenshot == 0.1 05:03:20 INFO - mozsystemmonitor == 0.3 05:03:20 INFO - moztest == 0.8 05:03:20 INFO - mozversion == 1.4 05:03:20 INFO - psutil == 3.1.1 05:03:20 INFO - requests == 1.2.3 05:03:20 INFO - wsgiref == 0.1.2 05:03:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:03: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')]} 05:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03: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')]} 05:03:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:20 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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:03:20 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 05:03:20 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 05:03:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:20 INFO - 'HOME': '/Users/cltbld', 05:03:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:20 INFO - 'LOGNAME': 'cltbld', 05:03:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:20 INFO - 'MOZ_NO_REMOTE': '1', 05:03:20 INFO - 'NO_EM_RESTART': '1', 05:03:20 INFO - 'PAGER': '/bin/cat', 05:03:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:20 INFO - 'PWD': '/builds/slave/test', 05:03:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:20 INFO - 'SHELL': '/bin/bash', 05:03:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:20 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:20 INFO - 'USER': 'cltbld', 05:03:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:20 INFO - 'XPC_FLAGS': '0x0', 05:03:20 INFO - 'XPC_SERVICE_NAME': '0', 05:03:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:20 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)) 05:03:20 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)) 05:03:20 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)) 05:03:20 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)) 05:03:20 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)) 05:03:20 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)) 05:03:20 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)) 05:03:20 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)) 05:03:20 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)) 05:03:20 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:03:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:03:21 INFO - Downloading marionette_driver-1.4.0.tar.gz 05:03:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 05:03:21 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)) 05:03:21 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)) 05:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:03:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 05:03:21 INFO - Installing collected packages: marionette-driver 05:03:21 INFO - Running setup.py install for marionette-driver 05:03:22 INFO - Successfully installed marionette-driver 05:03:22 INFO - Cleaning up... 05:03:22 INFO - Return code: 0 05:03:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:03:22 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 0x108d34c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d38768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88b9482c50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, '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.tFCUaFiY5s/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.z9R6girkiu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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 05:03:22 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 05:03:22 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 05:03:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:22 INFO - 'HOME': '/Users/cltbld', 05:03:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:22 INFO - 'LOGNAME': 'cltbld', 05:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:22 INFO - 'MOZ_NO_REMOTE': '1', 05:03:22 INFO - 'NO_EM_RESTART': '1', 05:03:22 INFO - 'PAGER': '/bin/cat', 05:03:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:22 INFO - 'PWD': '/builds/slave/test', 05:03:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:22 INFO - 'SHELL': '/bin/bash', 05:03:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:22 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:22 INFO - 'USER': 'cltbld', 05:03:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:22 INFO - 'XPC_FLAGS': '0x0', 05:03:22 INFO - 'XPC_SERVICE_NAME': '0', 05:03:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:03:22 INFO - Cleaning up... 05:03:22 INFO - Return code: 0 05:03:22 INFO - Running post-action listener: _resource_record_post_action 05:03:22 INFO - Running post-action listener: _start_resource_monitoring 05:03:22 INFO - Starting resource monitoring. 05:03:22 INFO - [mozharness: 2017-06-06 12:03:22.354249Z] Finished create-virtualenv step (success) 05:03:22 INFO - [mozharness: 2017-06-06 12:03:22.355262Z] Running install step. 05:03:22 INFO - Running pre-action listener: _resource_record_pre_action 05:03:22 INFO - Running main action method: install 05:03:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:03:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:03:22 INFO - Reading from file tmpfile_stdout 05:03:22 INFO - Detecting whether we're running mozinstall >=1.0... 05:03:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:03:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:03:22 INFO - Reading from file tmpfile_stdout 05:03:22 INFO - Output received: 05:03:22 INFO - Usage: mozinstall [options] installer 05:03:22 INFO - Options: 05:03:22 INFO - -h, --help show this help message and exit 05:03:22 INFO - -d DEST, --destination=DEST 05:03:22 INFO - Directory to install application into. [default: 05:03:22 INFO - "/builds/slave/test"] 05:03:22 INFO - --app=APP Application being installed. [default: firefox] 05:03:22 INFO - mkdir: /builds/slave/test/build/application 05:03:22 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'] 05:03:22 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 05:03:43 INFO - Reading from file tmpfile_stdout 05:03:43 INFO - Output received: 05:03:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:03:43 INFO - Running post-action listener: _resource_record_post_action 05:03:43 INFO - [mozharness: 2017-06-06 12:03:43.918355Z] Finished install step (success) 05:03:43 INFO - [mozharness: 2017-06-06 12:03:43.918494Z] Running setup-mitmproxy step. 05:03:43 INFO - Running pre-action listener: _resource_record_pre_action 05:03:43 INFO - Running main action method: setup_mitmproxy 05:03:43 INFO - Skipping: mitmproxy is not required 05:03:43 INFO - Running post-action listener: _resource_record_post_action 05:03:43 INFO - [mozharness: 2017-06-06 12:03:43.919087Z] Finished setup-mitmproxy step (success) 05:03:43 INFO - [mozharness: 2017-06-06 12:03:43.919186Z] Running run-tests step. 05:03:43 INFO - Running pre-action listener: _resource_record_pre_action 05:03:43 INFO - Running main action method: run_tests 05:03:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:03:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:03:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:03:43 INFO - 2017-06-06 05:03:43.933 screenresolution[2511:38245] starting screenresolution argv=screenresolution get 05:03:43 INFO - 2017-06-06 05:03:43.936 screenresolution[2511:38245] Display 0: 1600x1200x32@60 05:03:43 INFO - 2017-06-06 05:03:43.941 screenresolution[2512:38247] starting screenresolution argv=screenresolution list 05:03:43 INFO - Available Modes on Display 0 05:03:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:03:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:03:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:03:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:03:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:03:44 INFO - Intel Iris: 05:03:44 INFO - Chipset Model: Intel Iris 05:03:44 INFO - Type: GPU 05:03:44 INFO - Bus: Built-In 05:03:44 INFO - VRAM (Dynamic, Max): 1536 MB 05:03:44 INFO - Vendor: Intel (0x8086) 05:03:44 INFO - Device ID: 0x0a2e 05:03:44 INFO - Revision ID: 0x0009 05:03:44 INFO - Displays: 05:03:44 INFO - Display: 05:03:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:03:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:03:44 INFO - Display Serial Number: 1600x1200 60 05:03:44 INFO - Main Display: Yes 05:03:44 INFO - Mirror: Off 05:03:44 INFO - Online: Yes 05:03:44 INFO - Rotation: Supported 05:03:44 INFO - Return code: 0 05:03:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:03:44 INFO - Python 2.7.3 05:03:44 INFO - Return code: 0 05:03:44 INFO - grabbing minidump binary from tooltool 05:03:44 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')]} 05:03:44 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 0x108cbdc60>, '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 0x108a52cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108a7bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:03:44 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 05:03:44 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 05:03:44 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 05:03:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:03:44 INFO - Return code: 0 05:03:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:03:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:03:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:03:44 INFO - ENV: RUST_BACKTRACE is now 1 05:03:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:03:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:03:44 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/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0204', '--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 05:03:44 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/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0204 --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 05:03:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tFCUaFiY5s/Render', 05:03:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:03:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:03:44 INFO - 'HOME': '/Users/cltbld', 05:03:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:03:44 INFO - 'LOGNAME': 'cltbld', 05:03:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:03:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:03:44 INFO - 'MOZ_NO_REMOTE': '1', 05:03:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:03:44 INFO - 'NO_EM_RESTART': '1', 05:03:44 INFO - 'PAGER': '/bin/cat', 05:03:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:03:44 INFO - 'PWD': '/builds/slave/test', 05:03:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:03:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:03:44 INFO - 'RUST_BACKTRACE': '1', 05:03:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 05:03:44 INFO - 'SHELL': '/bin/bash', 05:03:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.z9R6girkiu/Listeners', 05:03:44 INFO - 'TMPDIR': '/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/', 05:03:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:03:44 INFO - 'USER': 'cltbld', 05:03:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:03:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:03:44 INFO - 'XPC_FLAGS': '0x0', 05:03:44 INFO - 'XPC_SERVICE_NAME': '0', 05:03:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:03:44 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/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0204', '--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 05:03:44 INFO - mozversion application_buildid: 20170606030422 05:03:44 INFO - mozversion application_changeset: 4dd1d17ba22660b8f5869a707f2e4e9f9dd5be5b 05:03:44 INFO - mozversion application_display_name: Nightly 05:03:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:03:44 INFO - mozversion application_name: Firefox 05:03:44 INFO - mozversion application_remotingname: firefox 05:03:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:03:44 INFO - mozversion application_vendor: Mozilla 05:03:44 INFO - mozversion application_version: 55.0a1 05:03:44 INFO - mozversion platform_buildid: 20170606030422 05:03:44 INFO - mozversion platform_changeset: 4dd1d17ba22660b8f5869a707f2e4e9f9dd5be5b 05:03:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:03:44 INFO - mozversion platform_version: 55.0a1 05:03:44 INFO - using testdate: 1496750624 05:03:44 INFO - actual date: 1496750624 05:03:44 INFO - starting webserver on 'localhost:58698' 05:03:44 INFO - SUITE-START | Running 2 tests 05:03:44 INFO - TEST-START | dromaeo_css 05:03:44 INFO - operating with platform_type : mac_ 05:03:44 INFO - Initialising browser for dromaeo_css test... 05:03:44 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58698/getInfo.html -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpdgU6Ys/profile 05:03:44 INFO - TEST-INFO | started process 2522 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58698/getInfo.html -foreground) 05:04:18 INFO - TEST-INFO | 2522: killed by SIGTERM 05:04:18 INFO - Browser initialized. 05:04:18 INFO - Running cycle 1/1 for dromaeo_css test... 05:04:18 INFO - TEST-INFO | started process 2529 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpdgU6Ys/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:04:20 INFO - PID 2529 | 2017-06-06 05:04:20.200 plugin-container[2530:38869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9943, name = 'com.apple.tsm.portname' 05:04:20 INFO - PID 2529 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:20 INFO - PID 2529 | 2017-06-06 05:04:20.202 plugin-container[2530:38869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 05:04:20 INFO - PID 2529 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:20 INFO - PID 2529 | 2017-06-06 05:04:20.202 plugin-container[2530:38869] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:04:20 INFO - PID 2529 | 2017-06-06 05:04:20.917 plugin-container[2531:38913] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 05:04:20 INFO - PID 2529 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:20 INFO - PID 2529 | 2017-06-06 05:04:20.918 plugin-container[2531:38913] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 05:04:20 INFO - PID 2529 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:04:20 INFO - PID 2529 | 2017-06-06 05:04:20.918 plugin-container[2531:38913] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:04:30 INFO - PID 2529 | RSS: Main: 261156864 05:04:30 INFO - PID 2529 | 05:07:32 INFO - PID 2529 | Cycle 1(1): loaded http://localhost:58698/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58698/tests/dromaeo/cssquery-ext.html) 05:07:33 INFO - PID 2529 | RSS: Main: 267296768 05:07:33 INFO - PID 2529 | 05:10:35 INFO - PID 2529 | Cycle 1(1): loaded http://localhost:58698/tests/dromaeo/cssquery-ext.html (next: http://localhost:58698/tests/dromaeo/cssquery-jquery.html) 05:10:36 INFO - PID 2529 | RSS: Main: 282497024 05:10:36 INFO - PID 2529 | 05:13:38 INFO - PID 2529 | Cycle 1(1): loaded http://localhost:58698/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58698/tests/dromaeo/cssquery-mootools.html) 05:13:38 INFO - PID 2529 | RSS: Main: 282595328 05:13:38 INFO - PID 2529 | 05:16:40 INFO - PID 2529 | Cycle 1(1): loaded http://localhost:58698/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58698/tests/dromaeo/cssquery-prototype.html) 05:16:41 INFO - PID 2529 | RSS: Main: 288411648 05:16:41 INFO - PID 2529 | 05:19:43 INFO - PID 2529 | Cycle 1(1): loaded http://localhost:58698/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58698/tests/dromaeo/cssquery-yui.html) 05:19:44 INFO - PID 2529 | RSS: Main: 288485376 05:19:44 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | Cycle 1(1): loaded http://localhost:58698/tests/dromaeo/cssquery-yui.html (next: http://localhost:58698/tests/dromaeo/cssquery-dojo.html) 05:22:46 INFO - PID 2529 | RSS: Main: 292118528 05:22:46 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | __start_tp_report 05:22:46 INFO - PID 2529 | _x_x_mozilla_page_load 05:22:46 INFO - PID 2529 | _x_x_mozilla_page_load_details 05:22:46 INFO - PID 2529 | |i|pagename|runs| 05:22:46 INFO - PID 2529 | |0;dojo.html;3549.0062782420932;3558.4662300654904;3565.3047655707132;3608.3685355062876;3614.5481814773143;5107.846764597063;5350.250964864912;6100.084987251903;6157.076438534216;6236.1892953915985;6131.590761709979;6162.537809664282;6162.876742465148;6173.259208894928;6223.502119830414;4277.957220427803;5012.223105418666;5422.240886275925;5474.014677358074;5537.833864984057;4376.2997920332755;5526.751296191661;5538.418466061066;5596.58025648078;5634.7182640867995;5474.178873169017;5508.091164957777;5540.612157149001;5590.468905453975;5615.466530679579;5375.462453754632;5465;5471.06991811393;5476.205950137248;5510.035743744868;4467.285234362486;4900.289458028602;5481.123020316731;5481.780728770845;5497.532709719679;6113.750943094551;6226.003839385722;6237.968810155966;6238.968805155991;6241.8127456175935;6438.066480360369;6530.346965303479;6567.573107748029;6660.666966651648;6673.532852700313;6743.831404214934;6803.863922721567;6822.590644561293;6825.658717064127;6830.146231721035;6285.340039295854;6299.653519056405;6319.652419116901;6322.36776322364;6370.33111523288;6658.234303055114;6757.628330441873;6771.153605799275;6907.447404207652;6939.167299923941;6290.370962903765;6475.125858009108;6486.2216534016225;6553.442957348583;6568.1789776277965;6481.02784582307;6580.276169621337;6662.067310576524;6762.763303284361;6778.8644227116165;6633.336666333328;6644.900326495106;6656.900146497806;6662.866742665216;6669.7665581704405;6449.709763060673;6677.165354330708;6683.832904177434;6685.6322902240845;6692;5309.548688361532;5345.65253258537;5360.061989151961;5393.406725260218;5421.647592906448;4788.545088216614;4797.280407938837;4804.495527969513;4814.374131362971;4875.780589873464;4822.565969062801;6069.846729121453;6208.81373558794;6289.150964619809;6315.210598675166;5746.4253574643035;6643.534952553371;6709.329067093349;6737.056812046318;6801;6278.497720182374;6283.465905398001;6302.590331628429;6323.462505686976;6328.6519241442165;5806.738696758656;6034.306054803666;6035.155078289043;6049.8487537811025;6057.121717350959;6730.7307707692125;6797.184337879387;6840.931590684029;6886.8622627548175;6915.170179578483;6827.214870289781;6827.52207345481;6840.965795170992;6847.6576171190245;6856.725730970706;5969.880602388015;6857.759978400732;6915.446764258948;6935.757248496278;6956.200036995608;6104.26748790148;6119.624418755179;6522;6571.868562628625;6619.536632435684;6763.086983257394;6770.627615481196;6788.626625535643;6797.6941037654415;6811.352921472452;6543;6601.702923368364;6607.240167380813;6613.603183808987;6625.17185351831;6393.87212255743;6469.8706025880165;6562.343765623399;6586.967065164644;6595.604263744191;6409.4231519163495;6482.708278127401;6493.707783149675;6494.993276042221;6509.707063182073;6559.048937904073;6585.3085426029575;6588.8352791180605;6589.769358072444;6615.867682646416;6294.370562943669;6314.589551679126;6360.109584658059;6382.36176382358;6457.903131453126 05:22:46 INFO - PID 2529 | |1;ext.html;707;800.2237829305571;815.844989452004;1141.212563331302;1257.182831159746;39202.21595568087;40090;40091.799541002256;40122.79324637144;40210;40292;40764.388534171936;40866.79566602163;40876;41003.79498102505;7978.042634883807;8148.166662500949;8443.746687599381;8656.394052416334;8662;8423.578821058953;8499.660013599449;8545.487270763742;8545.572721363907;8557.272631826303;8496.915030849705;8499.065102838682;8535.743927682179;8572.271356934636;8588.527630980294;8496.362772792034;8525.95737021311;8545.871811922827;8567.74296771098;8570.785730356729;7194.676239569232;8436.957815210884;8480.279176269964;8499.872501912476;8517.318614510825;37580.62419375798;37627.623723762685;37674.81162594197;37739.86780396565;37833.621663783284;3068.5704001439817;3566.197605538759;3566.4828599853136;3601.7838929664304;3616.4936908832783;24598.754012459824;30582.541261881088;30724.692753072406;30747.538786918216;30752;3062.3569050499423;3094.7678924080783;3095.798773079765;3112;3128.655847856734;241438;242830;282314.5884270586;301547;302425;47465.5253447468;47624.523754762704;47644;47781.76109119432;47828.04343913102;49374.753126234136;49446.752766235935;49642.50357496451;55363.72318138384;55481;13862.514811981533;14753;14787.408503659948;14789.704205916036;14906.254687265593;11681.707957301136;11768.235064720937;11783.469743861375;11816.46825892837;11880.524779008916;14108.435662573587;14112.576622701336;14160.150390976574;14166.079204851652;14262.643433914234;6270.467010304175;6276.46650034743;6290.21372328459;6323.588966717149;6324.335944725737;845.4377838737171;1005.969820905374;1026.8973102689672;1040.9271351005357;1047.4553232319304;19613.80386196148;23055.53888922246;23127.027689046936;24367.634485482733;24544.50910981806;1073.8174510333263;1084.983725244122;1100.7028102412303;1152.7924973504682;1152.8558930133734;12311.6922076947;12567.93716031414;12726.80909786354;12774.808377874338;12804.97560195182;335391.6460835409;337631;337635;338014;338296;54411.45588544143;54441.72779136079;54457.183142252106;54623.726881365335;54882;1493.9775903361458;1551.2088834694162;1579.1393690438738;1580.2967679382793;1580.913049782273;22804.657930131394;22838.31485055451;23574.52850943006;24101;24588.385290367885;38654;38696.8390948272;38698.80650596729;38773.64292249758;38819;267462;267507;268329;268334;268911;35989.82005090063;36042.63957360393;36203;36259.28629278177;36276.911692649264;43900;44247.77876110599;45627.31559026684;45679.54320456886;45795;29507.967221147155;29797.70202298036;30533.847330763205;30696.539551907186;31226.687733122377;29219.831206752344;29859.850700746356;30767;30932.69067309298;31228.12631242133;35480.64519354773;37072.70245541393;37752;37782;38012.429813553375;26594.468110637292;29854.850725746233;30984.380312394078;31311.686883131788;31589.52615710812;22619.88690056539;24312.51374972526;24503.50992980166;24575.631365529174;24618.261452156465 05:22:46 INFO - PID 2529 | |2;jquery.html;433.81346021210885;437.43789230838377;438.1237524950101;441.9823207071717;455.37158720965044;21665.91670416479;22524.66213006804;22537.436564085885;23793.40516487089;24781.876090619568;19966.60066798667;24078.75921240787;24402.413843100185;24539.509209815795;24561.75438245617;6091.360407157251;6219.222597175353;6232.376762323776;6233.2208473940755;6238.220222472191;6062.4543791058795;6072.240969878765;6137.754489820401;6150.569460137792;6153.969230153843;5944.7324870380935;6171.691415429233;6186.474149697258;6211.565190436671;6233.753249350126;6175.536834737391;6176.907346389808;6194.5044396448175;6226.097215903714;6230.065490176465;5509.173623956399;5524.861878453031;5534.252875861787;5541.33503979521;5545.473180047889;28184.57723134134;29044.8547757262;29442.558361624593;29445.411091778045;29541.704582954324;2229.498362868358;2270.738865030526;2271.818254539633;2274;2279.247848210087;14483.855161448462;18769.436916892515;18815.71776423355;18823.71764423535;18899.52751181218;1874.4470381237568;2170.7720689327707;2202.9559408811892;2226.743924448677;2232.2633530934754;154883.67674484893;160943.19528402283;189144.16283755755;203788;209414;12973.481060757653;13228.933855330853;13269.469221231042;13280.203187808762;13424.26166560829;17001.319947201973;17140.742888856676;17160.141992900306;17161.742573861404;17180.140992950302;8927.062658420902;9237.12247336502;9243.90756092444;9253.444793312423;9293.163615274658;8854.557272136368;8899.599518021705;8916.242119419792;8973.50645714479;8985.281177505914;8283.544405057779;8313.33493320533;8351.8329633407;8383.916160838435;8400.915990840012;2253.5380247049316;2261.7398999114985;2262.841601087941;2274.454131008546;2438.9756102439105;941.75985123793;949.8670186173941;950.0509483280574;960.6301573893961;967.9449400153866;20673.586528269352;21283.680744788842;21343.67984480234;21388.679169812465;21853;979.6032606794117;979.6424305128719;1008.5007921078966;1023.2325755683238;1040.1834559870617;8193.46742461738;9607.663731769355;10228.693139205836;10250.231232657432;10982.835257471144;551309;553009;553681;562364;565376;149878;155245;155252;155528;156414;979.7305740921304;1111.8332250162541;1164.8019836627793;1240.8200810882372;1243.8631750507618;17396.347347002014;20602.896985514977;21945.561088778453;21948.231811886508;23418.297451076494;60828;69457.65271173611;70697;70719.64640176766;71683;93869;94873;95075.52462237644;95133.5243323779;95689;10009.149222315893;10224.948875255575;10230.846537302095;10238.33450825694;10247.692569222936;14609.488667896572;17533;17946.282148713904;18018.549536261697;18040.368587099387;13432.597022089352;14667.559973200821;14687.265636717908;14797.482088126864;14805.851941480447;12866.935665321613;12904.741905162033;13127.73744525085;13199.538016169772;13451.865481345452;14893.329800159236;14938.850611494181;14940.551783446514;15101.39594416255;15130.54608361751;9847.753806154904;10351.948240258751;10370.27408081427;10437.425941573287;10461.790764184825;14823.851761482678;14860.479883204469;14914.105153690813;14971.32629031718;15043.323050462974 05:22:46 INFO - PID 2529 | |3;mootools.html;1335.5659410691524;1382.5714028651112;1388.4376827384913;1392.2133994293224;1403.880670143038;11593.42032898355;11961.2823230606;12083.758324833498;12116.75766484671;12307.63077107686;11905.16663833532;11948.342841143756;12076;12081.697957551065;12095.939520302387;2201.8678879267213;2482.7889629381434;2521.10500772226;2564.3332733489333;2564.8076394270506;2131.6909048188004;2487.1543675150447;2526.0527302261653;2539.3651587103222;2540.8602526861014;2498.2130628851946;2509.573372526675;2514.346269969812;2547.605121206216;2553.4510080332725;2286.1884031168943;2299.678045073691;2500.5624015797252;2508.423062695572;2518.8110891683104;2449.632555116729;2502.5620516409554;2511.0207019262502;2563.218218443374;2564.9355517460417;11202.327860328407;11742.882571174265;11846.822297665542;11864.406779661069;11870.762584748343;6288.245410550718;6431.389018043278;6463.48292136628;6516.57642253252;6520.836979075513;4119.073208528087;4220.409142720022;4298.505671847746;4311.9784401078105;4312.22379971605;1045.7973330669715;1163.7032556697989;1243.030436259719;1270.8284381608457;1279.8976081913424;15491;15947.999999999884;16551.420700275554;16573.17134143288;16721.83278167227;5214.113600687892;5530.557555395559;5714.342850572238;5753.8561535961935;5781.161731548902;10148.797024059626;10874.45627718611;12059.75880482403;12276.202046867103;12300.507979680713;6460.321666224979;6486.67566621667;6502.414782669583;6567.868642627216;6574.506911981621;6031.819045428644;6201.472874805692;6214.130021796862;6230.7819226327765;6248.718807653667;4344.3483477478;4386.583274588909;4401.22978478765;4429.712068715523;4453.240969816904;1274.2736640115045;1297.5977446991462;1297.7339645372865;1305.0733978874916;1310.7640624687458;810.8418858322689;822.9094799572042;828.1552816127517;830.6967956695884;841.0748177005245;6298.716557754912;6759.662016899234;7553.697852085965;7697.153313135551;7862.72480463181;770.8535378278109;941.1012483078644;952.1620973543376;956.8851737791509;967.351874244258;4708.576228139484;4843.927341089887;4992.525710057539;5195.948040519622;5215.452377500384;14532.782008269884;14834.332455039548;15189.316480758393;15344.462943796914;15350.23248837576;12469.314187719581;14880.925595371953;15195.164265965477;15198.620034499005;15328.386864525517;6441.935580644134;6934.271901450376;6974.651267436608;8556.957215214008;8824.779380515538;6079.878402432015;6482.444213388847;7124.5725256484775;7503.962480187564;7572.280633339824;12616.053795965523;12680.302583358003;12938.482460701467;12943.288119153536;13246.602601921957;11032.944835275772;13318.800217996544;13383.19700817954;13699;13731.862681373059;7491.726406511015;9315.627374904929;9710.660126895526;9725.367851089657;9828.852567211641;10438.21713371485;10733.624323148651;10746.677599672023;10775.838362424727;10824.134069274282;8808.647654093764;9098;9118.726438206864;9165.495897725688;9269.67556135559;8778.780530486789;8822.51476168817;8840.911590884267;8913.465192088495;9063.818723625622;8185.918140818516;8319.916800831914;8329.916700832913;8340.666373344999;8357.707480238163;6017.127516510169;6125.938740612715;6398.744050237939;6452.9677351612945;6508.967455162694;6164.845878852885;6175.567710260419;6507.284198738041;6545.21457425112;6554.311797261219 05:22:46 INFO - PID 2529 | |4;prototype.html;337.741627654844;403.2580052703026;403.4976454314377;406.1308799169779;417.8788151436083;4776.665633405663;5183.844484665461;5624.521915637166;6408.308510425621;6431.967840160799;6169.722362493687;6232.252129744437;6245.002398848544;6260.436560709535;6278.403551662596;4535.70517916336;4634.745089020103;4679.321498382733;4696.671233013691;4736.85789426316;3895.987043368716;4498.52765459627;4677.929831052537;4684.086603112394;4692.671512994092;3906.453096566483;4637.489876113625;4644.140833945731;4650.4186976627925;4654.301854721802;3845.653891149782;4661.184292748772;4671.906561868744;4677.158111539921;4698.765061746899;3527.9823600882087;3586.1393265616066;3782.8273235297147;3818.713596480275;4066.085130845566;5317.457937198246;5413.998410294069;6301.873962520724;6355.872882542323;6376.26672932614;5516.7517461714315;5528.640638358494;5552;5565.526930210896;5567.916481252784;6843.7604683836325;6851;6858.279880612564;6891.552049116792;6916.515843890929;3674.191677830873;3797.373433383488;3849.5188101487315;4571.588557029883;4586.830358258622;6927.58434493932;7119.184607924998;7200.495965282433;7295.416366690652;7340.853182936365;3907.7264591478324;3978.542467616261;4245.97877010613;4591.95408045922;4628.768561571908;4747.738874361943;4751.667383283137;4754.334393184957;4785.3300537924715;4790.760461976957;5271.4728527147745;5485.890282194414;5489.459298752283;5509.779608815683;5513.338399392099;6849.760258390932;6857.897131543031;6886.724531018703;6909.654517274116;6940.9652951734915;1602.5016609470183;1604.9197540122948;1610.5007447691253;1617.5713435939485;1628.9755653665204;1039.2569312941132;1057.9153667706564;1079.3416016230094;1079.9352038877691;1081.2377274021796;6291.496680265568;6410.294867564658;6473.514486413538;6477.514186435942;6586.868262634721;5685.459881311269;6223.315435302113;7120.750773722895;7129.465290103263;7143.535480259302;5460.836174914759;5513.641613295204;6429.646369449725;6432.967835160888;6458.093857764025;3407.9659203408146;3592.7125829933543;3684.907877303035;3725.627437256307;3736.4955730976512;6351.714172862232;7310.59791711461;7332.853342933112;7337.789264771253;7340.15588207363;6055.878882422327;7286.453515986323;7308.817279568053;7319.2314806944505;7332.810227551033;5674.687892165971;6768.187817461936;6772.3926288379325;6801.387875091265;7222.277772222946;6276.959988003826;6575.572587781779;6690.149641509866;7062.435005199469;7136.3220494052985;5758.740856661377;5946.524278057658;6460.415833666383;6921.501024334022;7035.854064510373;5775.27932821451;6999.825004374931;7260.578400226589;7266.818329541592;7364.3003914628025;4651.372064771348;4665.6034237090225;4683.812647494062;4688.109259240733;4709.270063140219;6430.324815894263;6674.699638516376;6720.966395167993;6891.517593768388;7127.144742630918;6844;6859.897101543581;6872.725090996304;6889.517733758589;6908.689108990208;5661.886762264649;6864.759733409307;6878.518503704692;6905.309469053054;6907.309269073254;6182.938170618416;6645.245436328459;6766.4248538874745;6774.848275793029;6798.5383142622595;5757.453041961007;6891.552049116792;6908.065741592362;6908.374817569292;6940.9652951734915;5646.576506761927;6669.43226857673;6719.177945262903;6736.360045795642;6756.256811750605 05:22:46 INFO - PID 2529 | |5;yui.html;795.9999999999997;806.5402720449346;831.5384961346452;843.7890527368158;858.6737039924826;16502.009879407244;17002.234899429524;17017.404390846325;17034.063126528035;17041.91479042606;15104.546863594082;15236.238188090607;15460.304286307115;16002.119883406407;16039.518814435556;1000.4947501511745;1012.9746756331077;1014.9137323327526;1019.7399663085903;1023.0843395161326;1021.6117875207448;1023.6110278094313;1025.1286406554407;1028.444639894456;1032.664384075175;1019.9235057370689;1062.9946850265776;1066.4720963123266;1067.0769784136705;1069.6577095329499;1035.6168217759478;1058;1065.1106326217619;1067.2849191041983;1072.7479042425025;767.4819496839611;786.5241528875006;789.2226157235119;789.6643926331344;790.5809920742016;16989.15054247295;16993.66012679752;17044.573885652833;17107.57231069221;17331.740023899652;14259.643508912257;16872.734544909057;17351.91324043384;17862.0175890326;17876.55308617282;14256.429742810275;14788.556343309718;14877.925610371987;14904.70190596182;14907.180105094216;3529.4176460883923;3573.4997100405963;3577.5528058992627;3595.7303202259936;3596.5684117905757;129116;163030;163500;166057.99999999878;166371;11125.777484450266;11306.547738090549;12559.811602825965;13869.445222191202;13904.096233744775;26287.868560657073;26908.327291817863;26917.192484225503;26961.0563630272;27190.728092719215;7668.156502784689;7740.6129693515095;7750.031246094239;7752;7764.844703105906;3839.308924393636;3882.5535063467496;3896.493455850778;3915.393114067324;3920.8039598019986;4374.4531933508315;4425.3140763181755;4484.484284307347;4517.344984977226;4525.456945166601;814.1980149552686;870.1037930931151;876.5748611923212;892.6295587331185;905.2305540290832;633.8196603683315;643.340575909687;645.3127419298432;664.5879554676035;673.5217995223348;11295.322280663186;12594;12946;13721.108127971649;14033.578992630237;645.6384424722171;657.9111819904348;659.0376258717097;664.4319107163402;666.3736088077288;4410.338449232577;5540.778368865281;5690.9146362804595;5717.142428635655;5865.824025279248;132436;132507;136754.31622841823;141659;145331;117860.41069794596;125046.3747681274;125054;125875.74124258824;127922;95172.52413738026;99516.50241748746;99534.5023274879;99574.99999999854;99775;11741.647750567497;12334.50661973547;12335.938320308342;12712.872871271169;12739.426725797175;41593;42439.3634095495;42524;43308.56691433171;43594.78202608967;41908.79045604752;54986.90026199534;55337.44662553484;55532.88934221374;56534.86930261454;16529.256183472015;17311.307547698456;17617;17761;17856.910715446338;18631.44105676832;19279.325223617107;19406;19432;19460.221591136196;17341.618384414916;17387.13064346803;18415.90792046031;18446;18458.26166953307;17402.129893505527;17492.737608935622;18131.456056318333;18192.636147277244;18334.72497912559;17382.652346953244;17644.82355176483;17832.643347133246;17914.193861276013;18024.2790288387;11419.60031398897;11436.1422893285;11915.285082895054;12335.629931102081;12340.074494412775;12671.493140274655;12773.872261277269;13475.932620336836;13565.050446469048;13581.320933953066 05:22:46 INFO - PID 2529 | __end_tp_report 05:22:46 INFO - PID 2529 | __start_cc_report 05:22:46 INFO - PID 2529 | _x_x_mozilla_cycle_collect,450 05:22:46 INFO - PID 2529 | __end_cc_report 05:22:46 INFO - PID 2529 | __startTimestamp1496751766683__endTimestamp 05:22:46 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | ------- Summary: start ------- 05:22:46 INFO - PID 2529 | Number of tests: 6 05:22:46 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | [#0] dojo.html Cycles:165 Average:6133.33 Median:6370.33 stddev:756.91 (11.9%) stddev-sans-first:731.57 05:22:46 INFO - PID 2529 | Values: 3549.0 3558.5 3565.3 3608.4 3614.5 5107.8 5350.3 6100.1 6157.1 6236.2 6131.6 6162.5 6162.9 6173.3 6223.5 4278.0 5012.2 5422.2 5474.0 5537.8 4376.3 5526.8 5538.4 5596.6 5634.7 5474.2 5508.1 5540.6 5590.5 5615.5 5375.5 5465.0 5471.1 5476.2 5510.0 4467.3 4900.3 5481.1 5481.8 5497.5 6113.8 6226.0 6238.0 6239.0 6241.8 6438.1 6530.3 6567.6 6660.7 6673.5 6743.8 6803.9 6822.6 6825.7 6830.1 6285.3 6299.7 6319.7 6322.4 6370.3 6658.2 6757.6 6771.2 6907.4 6939.2 6290.4 6475.1 6486.2 6553.4 6568.2 6481.0 6580.3 6662.1 6762.8 6778.9 6633.3 6644.9 6656.9 6662.9 6669.8 6449.7 6677.2 6683.8 6685.6 6692.0 5309.5 5345.7 5360.1 5393.4 5421.6 4788.5 4797.3 4804.5 4814.4 4875.8 4822.6 6069.8 6208.8 6289.2 6315.2 5746.4 6643.5 6709.3 6737.1 6801.0 6278.5 6283.5 6302.6 6323.5 6328.7 5806.7 6034.3 6035.2 6049.8 6057.1 6730.7 6797.2 6840.9 6886.9 6915.2 6827.2 6827.5 6841.0 6847.7 6856.7 5969.9 6857.8 6915.4 6935.8 6956.2 6104.3 6119.6 6522.0 6571.9 6619.5 6763.1 6770.6 6788.6 6797.7 6811.4 6543.0 6601.7 6607.2 6613.6 6625.2 6393.9 6469.9 6562.3 6587.0 6595.6 6409.4 6482.7 6493.7 6495.0 6509.7 6559.0 6585.3 6588.8 6589.8 6615.9 6294.4 6314.6 6360.1 6382.4 6457.9 05:22:46 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | [#1] ext.html Cycles:180 Average:44808.22 Median:338155.00 stddev:77422.04 (22.9%) stddev-sans-first:77568.43 05:22:46 INFO - PID 2529 | Values: 707.0 800.2 815.8 1141.2 1257.2 39202.2 40090.0 40091.8 40122.8 40210.0 40292.0 40764.4 40866.8 40876.0 41003.8 7978.0 8148.2 8443.7 8656.4 8662.0 8423.6 8499.7 8545.5 8545.6 8557.3 8496.9 8499.1 8535.7 8572.3 8588.5 8496.4 8526.0 8545.9 8567.7 8570.8 7194.7 8437.0 8480.3 8499.9 8517.3 37580.6 37627.6 37674.8 37739.9 37833.6 3068.6 3566.2 3566.5 3601.8 3616.5 24598.8 30582.5 30724.7 30747.5 30752.0 3062.4 3094.8 3095.8 3112.0 3128.7 241438.0 242830.0 282314.6 301547.0 302425.0 47465.5 47624.5 47644.0 47781.8 47828.0 49374.8 49446.8 49642.5 55363.7 55481.0 13862.5 14753.0 14787.4 14789.7 14906.3 11681.7 11768.2 11783.5 11816.5 11880.5 14108.4 14112.6 14160.2 14166.1 14262.6 6270.5 6276.5 6290.2 6323.6 6324.3 845.4 1006.0 1026.9 1040.9 1047.5 19613.8 23055.5 23127.0 24367.6 24544.5 1073.8 1085.0 1100.7 1152.8 1152.9 12311.7 12567.9 12726.8 12774.8 12805.0 335391.6 337631.0 337635.0 338014.0 338296.0 54411.5 54441.7 54457.2 54623.7 54882.0 1494.0 1551.2 1579.1 1580.3 1580.9 22804.7 22838.3 23574.5 24101.0 24588.4 38654.0 38696.8 38698.8 38773.6 38819.0 267462.0 267507.0 268329.0 268334.0 268911.0 35989.8 36042.6 36203.0 36259.3 36276.9 43900.0 44247.8 45627.3 45679.5 45795.0 29508.0 29797.7 30533.8 30696.5 31226.7 29219.8 29859.9 30767.0 30932.7 31228.1 35480.6 37072.7 37752.0 37782.0 38012.4 26594.5 29854.9 30984.4 31311.7 31589.5 22619.9 24312.5 24503.5 24575.6 24618.3 05:22:46 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | [#2] jquery.html Cycles:180 Average:38812.28 Median:2266.79 stddev:96523.18 (4258.1%) stddev-sans-first:96750.94 05:22:46 INFO - PID 2529 | Values: 433.8 437.4 438.1 442.0 455.4 21665.9 22524.7 22537.4 23793.4 24781.9 19966.6 24078.8 24402.4 24539.5 24561.8 6091.4 6219.2 6232.4 6233.2 6238.2 6062.5 6072.2 6137.8 6150.6 6154.0 5944.7 6171.7 6186.5 6211.6 6233.8 6175.5 6176.9 6194.5 6226.1 6230.1 5509.2 5524.9 5534.3 5541.3 5545.5 28184.6 29044.9 29442.6 29445.4 29541.7 2229.5 2270.7 2271.8 2274.0 2279.2 14483.9 18769.4 18815.7 18823.7 18899.5 1874.4 2170.8 2203.0 2226.7 2232.3 154883.7 160943.2 189144.2 203788.0 209414.0 12973.5 13228.9 13269.5 13280.2 13424.3 17001.3 17140.7 17160.1 17161.7 17180.1 8927.1 9237.1 9243.9 9253.4 9293.2 8854.6 8899.6 8916.2 8973.5 8985.3 8283.5 8313.3 8351.8 8383.9 8400.9 2253.5 2261.7 2262.8 2274.5 2439.0 941.8 949.9 950.1 960.6 967.9 20673.6 21283.7 21343.7 21388.7 21853.0 979.6 979.6 1008.5 1023.2 1040.2 8193.5 9607.7 10228.7 10250.2 10982.8 551309.0 553009.0 553681.0 562364.0 565376.0 149878.0 155245.0 155252.0 155528.0 156414.0 979.7 1111.8 1164.8 1240.8 1243.9 17396.3 20602.9 21945.6 21948.2 23418.3 60828.0 69457.7 70697.0 70719.6 71683.0 93869.0 94873.0 95075.5 95133.5 95689.0 10009.1 10224.9 10230.8 10238.3 10247.7 14609.5 17533.0 17946.3 18018.5 18040.4 13432.6 14667.6 14687.3 14797.5 14805.9 12866.9 12904.7 13127.7 13199.5 13451.9 14893.3 14938.9 14940.6 15101.4 15130.5 9847.8 10351.9 10370.3 10437.4 10461.8 14823.9 14860.5 14914.1 14971.3 15043.3 05:22:46 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | [#3] mootools.html Cycles:180 Average:7114.71 Median:4169.74 stddev:4435.67 (106.4%) stddev-sans-first:4426.85 05:22:46 INFO - PID 2529 | Values: 1335.6 1382.6 1388.4 1392.2 1403.9 11593.4 11961.3 12083.8 12116.8 12307.6 11905.2 11948.3 12076.0 12081.7 12095.9 2201.9 2482.8 2521.1 2564.3 2564.8 2131.7 2487.2 2526.1 2539.4 2540.9 2498.2 2509.6 2514.3 2547.6 2553.5 2286.2 2299.7 2500.6 2508.4 2518.8 2449.6 2502.6 2511.0 2563.2 2564.9 11202.3 11742.9 11846.8 11864.4 11870.8 6288.2 6431.4 6463.5 6516.6 6520.8 4119.1 4220.4 4298.5 4312.0 4312.2 1045.8 1163.7 1243.0 1270.8 1279.9 15491.0 15948.0 16551.4 16573.2 16721.8 5214.1 5530.6 5714.3 5753.9 5781.2 10148.8 10874.5 12059.8 12276.2 12300.5 6460.3 6486.7 6502.4 6567.9 6574.5 6031.8 6201.5 6214.1 6230.8 6248.7 4344.3 4386.6 4401.2 4429.7 4453.2 1274.3 1297.6 1297.7 1305.1 1310.8 810.8 822.9 828.2 830.7 841.1 6298.7 6759.7 7553.7 7697.2 7862.7 770.9 941.1 952.2 956.9 967.4 4708.6 4843.9 4992.5 5195.9 5215.5 14532.8 14834.3 15189.3 15344.5 15350.2 12469.3 14880.9 15195.2 15198.6 15328.4 6441.9 6934.3 6974.7 8557.0 8824.8 6079.9 6482.4 7124.6 7504.0 7572.3 12616.1 12680.3 12938.5 12943.3 13246.6 11032.9 13318.8 13383.2 13699.0 13731.9 7491.7 9315.6 9710.7 9725.4 9828.9 10438.2 10733.6 10746.7 10775.8 10824.1 8808.6 9098.0 9118.7 9165.5 9269.7 8778.8 8822.5 8840.9 8913.5 9063.8 8185.9 8319.9 8329.9 8340.7 8357.7 6017.1 6125.9 6398.7 6453.0 6509.0 6164.8 6175.6 6507.3 6545.2 6554.3 05:22:46 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | [#4] prototype.html Cycles:180 Average:5457.39 Median:6112.80 stddev:1745.61 (28.6%) stddev-sans-first:1707.68 05:22:46 INFO - PID 2529 | Values: 337.7 403.3 403.5 406.1 417.9 4776.7 5183.8 5624.5 6408.3 6432.0 6169.7 6232.3 6245.0 6260.4 6278.4 4535.7 4634.7 4679.3 4696.7 4736.9 3896.0 4498.5 4677.9 4684.1 4692.7 3906.5 4637.5 4644.1 4650.4 4654.3 3845.7 4661.2 4671.9 4677.2 4698.8 3528.0 3586.1 3782.8 3818.7 4066.1 5317.5 5414.0 6301.9 6355.9 6376.3 5516.8 5528.6 5552.0 5565.5 5567.9 6843.8 6851.0 6858.3 6891.6 6916.5 3674.2 3797.4 3849.5 4571.6 4586.8 6927.6 7119.2 7200.5 7295.4 7340.9 3907.7 3978.5 4246.0 4592.0 4628.8 4747.7 4751.7 4754.3 4785.3 4790.8 5271.5 5485.9 5489.5 5509.8 5513.3 6849.8 6857.9 6886.7 6909.7 6941.0 1602.5 1604.9 1610.5 1617.6 1629.0 1039.3 1057.9 1079.3 1079.9 1081.2 6291.5 6410.3 6473.5 6477.5 6586.9 5685.5 6223.3 7120.8 7129.5 7143.5 5460.8 5513.6 6429.6 6433.0 6458.1 3408.0 3592.7 3684.9 3725.6 3736.5 6351.7 7310.6 7332.9 7337.8 7340.2 6055.9 7286.5 7308.8 7319.2 7332.8 5674.7 6768.2 6772.4 6801.4 7222.3 6277.0 6575.6 6690.1 7062.4 7136.3 5758.7 5946.5 6460.4 6921.5 7035.9 5775.3 6999.8 7260.6 7266.8 7364.3 4651.4 4665.6 4683.8 4688.1 4709.3 6430.3 6674.7 6721.0 6891.5 7127.1 6844.0 6859.9 6872.7 6889.5 6908.7 5661.9 6864.8 6878.5 6905.3 6907.3 6182.9 6645.2 6766.4 6774.8 6798.5 5757.5 6891.6 6908.1 6908.4 6941.0 5646.6 6669.4 6719.2 6736.4 6756.3 05:22:46 INFO - PID 2529 | 05:22:46 INFO - PID 2529 | [#5] yui.html Cycles:180 Average:25231.78 Median:17630.91 stddev:39415.18 (223.6%) stddev-sans-first:39483.04 05:22:46 INFO - PID 2529 | Values: 796.0 806.5 831.5 843.8 858.7 16502.0 17002.2 17017.4 17034.1 17041.9 15104.5 15236.2 15460.3 16002.1 16039.5 1000.5 1013.0 1014.9 1019.7 1023.1 1021.6 1023.6 1025.1 1028.4 1032.7 1019.9 1063.0 1066.5 1067.1 1069.7 1035.6 1058.0 1065.1 1067.3 1072.7 767.5 786.5 789.2 789.7 790.6 16989.2 16993.7 17044.6 17107.6 17331.7 14259.6 16872.7 17351.9 17862.0 17876.6 14256.4 14788.6 14877.9 14904.7 14907.2 3529.4 3573.5 3577.6 3595.7 3596.6 129116.0 163030.0 163500.0 166058.0 166371.0 11125.8 11306.5 12559.8 13869.4 13904.1 26287.9 26908.3 26917.2 26961.1 27190.7 7668.2 7740.6 7750.0 7752.0 7764.8 3839.3 3882.6 3896.5 3915.4 3920.8 4374.5 4425.3 4484.5 4517.3 4525.5 814.2 870.1 876.6 892.6 905.2 633.8 643.3 645.3 664.6 673.5 11295.3 12594.0 12946.0 13721.1 14033.6 645.6 657.9 659.0 664.4 666.4 4410.3 5540.8 5690.9 5717.1 5865.8 132436.0 132507.0 136754.3 141659.0 145331.0 117860.4 125046.4 125054.0 125875.7 127922.0 95172.5 99516.5 99534.5 99575.0 99775.0 11741.6 12334.5 12335.9 12712.9 12739.4 41593.0 42439.4 42524.0 43308.6 43594.8 41908.8 54986.9 55337.4 55532.9 56534.9 16529.3 17311.3 17617.0 17761.0 17856.9 18631.4 19279.3 19406.0 19432.0 19460.2 17341.6 17387.1 18415.9 18446.0 18458.3 17402.1 17492.7 18131.5 18192.6 18334.7 17382.7 17644.8 17832.6 17914.2 18024.3 11419.6 11436.1 11915.3 12335.6 12340.1 12671.5 12773.9 13475.9 13565.1 13581.3 05:22:46 INFO - PID 2529 | -------- Summary: end -------- 05:22:46 INFO - PID 2529 | 05:22:47 INFO - TEST-INFO | 2529: exit 0 05:22:47 INFO - TEST-OK | dromaeo_css | took 1142716ms 05:22:47 INFO - TEST-START | kraken 05:22:47 INFO - operating with platform_type : mac_ 05:22:47 INFO - Initialising browser for kraken test... 05:22:47 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58698/getInfo.html -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpKf5dPm/profile 05:22:47 INFO - TEST-INFO | started process 2536 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:58698/getInfo.html -foreground) 05:23:20 INFO - TEST-INFO | 2536: killed by SIGTERM 05:23:20 INFO - Browser initialized. 05:23:20 INFO - Running cycle 1/1 for kraken test... 05:23:20 INFO - TEST-INFO | started process 2540 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/T/tmpKf5dPm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:23:23 INFO - PID 2540 | 2017-06-06 05:23:23.078 plugin-container[2541:41693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 05:23:23 INFO - PID 2540 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:23 INFO - PID 2540 | 2017-06-06 05:23:23.079 plugin-container[2541:41693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 05:23:23 INFO - PID 2540 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:23 INFO - PID 2540 | 2017-06-06 05:23:23.079 plugin-container[2541:41693] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:23 INFO - PID 2540 | 2017-06-06 05:23:23.669 plugin-container[2542:41739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 05:23:23 INFO - PID 2540 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:23 INFO - PID 2540 | 2017-06-06 05:23:23.670 plugin-container[2542:41739] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 05:23:23 INFO - PID 2540 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:23:23 INFO - PID 2540 | 2017-06-06 05:23:23.670 plugin-container[2542:41739] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:23:33 INFO - PID 2540 | RSS: Main: 268304384 05:23:33 INFO - PID 2540 | 05:23:35 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58698/tests/kraken/driver.html?testName=audio-beat-detection) 05:23:36 INFO - PID 2540 | RSS: Main: 275857408 05:23:36 INFO - PID 2540 | 05:23:39 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58698/tests/kraken/driver.html?testName=audio-dft) 05:23:39 INFO - PID 2540 | RSS: Main: 284430336 05:23:39 INFO - PID 2540 | 05:23:43 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58698/tests/kraken/driver.html?testName=audio-fft) 05:23:43 INFO - PID 2540 | RSS: Main: 282828800 05:23:43 INFO - PID 2540 | 05:23:45 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58698/tests/kraken/driver.html?testName=audio-oscillator) 05:23:46 INFO - PID 2540 | RSS: Main: 283828224 05:23:46 INFO - PID 2540 | 05:23:48 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58698/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:23:48 INFO - PID 2540 | RSS: Main: 285331456 05:23:48 INFO - PID 2540 | 05:23:51 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58698/tests/kraken/driver.html?testName=imaging-darkroom) 05:23:52 INFO - PID 2540 | RSS: Main: 290017280 05:23:52 INFO - PID 2540 | 05:23:55 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58698/tests/kraken/driver.html?testName=imaging-desaturate) 05:23:55 INFO - PID 2540 | RSS: Main: 293638144 05:23:55 INFO - PID 2540 | 05:23:59 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58698/tests/kraken/driver.html?testName=json-parse-financial) 05:23:59 INFO - PID 2540 | RSS: Main: 293212160 05:23:59 INFO - PID 2540 | 05:24:01 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58698/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:24:02 INFO - PID 2540 | RSS: Main: 292540416 05:24:02 INFO - PID 2540 | 05:24:03 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58698/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:24:04 INFO - PID 2540 | RSS: Main: 292401152 05:24:04 INFO - PID 2540 | 05:24:06 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58698/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:24:07 INFO - PID 2540 | RSS: Main: 292306944 05:24:07 INFO - PID 2540 | 05:24:10 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58698/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:24:10 INFO - PID 2540 | RSS: Main: 291909632 05:24:10 INFO - PID 2540 | 05:24:14 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58698/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:24:14 INFO - PID 2540 | RSS: Main: 292122624 05:24:14 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | Cycle 1(1): loaded http://localhost:58698/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58698/tests/kraken/driver.html?testName=ai-astar) 05:24:16 INFO - PID 2540 | RSS: Main: 293625856 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | __start_tp_report 05:24:16 INFO - PID 2540 | _x_x_mozilla_page_load 05:24:16 INFO - PID 2540 | _x_x_mozilla_page_load_details 05:24:16 INFO - PID 2540 | |i|pagename|runs| 05:24:16 INFO - PID 2540 | |0;ai-astar;103;114;112;113;115;113;115;114;113;113 05:24:16 INFO - PID 2540 | |1;audio-beat-detection;147;110;111;156;111;111;112;115;164;136 05:24:16 INFO - PID 2540 | |2;audio-dft;196;205;196;205;208;221;210;222;199;190 05:24:16 INFO - PID 2540 | |3;audio-fft;79;57;59;84;64;60;65;58;80;59 05:24:16 INFO - PID 2540 | |4;audio-oscillator;86;70;82;70;63;83;106;103;71;88 05:24:16 INFO - PID 2540 | |5;imaging-gaussian-blur;89;89;89;88;90;88;88;88;89;98 05:24:16 INFO - PID 2540 | |6;imaging-darkroom;96;97;96;95;96;108;119;97;96;97 05:24:16 INFO - PID 2540 | |7;imaging-desaturate;89;146;141;94;92;89;92;91;119;138 05:24:16 INFO - PID 2540 | |8;json-parse-financial;50;50;49;54;60;81;77;77;61;53 05:24:16 INFO - PID 2540 | |9;json-stringify-tinderbox;58;56;66;62;64;62;57;57;58;56 05:24:16 INFO - PID 2540 | |10;stanford-crypto-aes;84;87;86;87;89;86;90;88;109;89 05:24:16 INFO - PID 2540 | |11;stanford-crypto-ccm;138;143;141;138;148;133;130;172;153;150 05:24:16 INFO - PID 2540 | |12;stanford-crypto-pbkdf2;185;189;185;192;191;187;186;185;187;233 05:24:16 INFO - PID 2540 | |13;stanford-crypto-sha256-iterative;70;82;57;91;66;86;105;92;76;62 05:24:16 INFO - PID 2540 | __end_tp_report 05:24:16 INFO - PID 2540 | __start_cc_report 05:24:16 INFO - PID 2540 | _x_x_mozilla_cycle_collect,1221 05:24:16 INFO - PID 2540 | __end_cc_report 05:24:16 INFO - PID 2540 | __startTimestamp1496751856764__endTimestamp 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | ------- Summary: start ------- 05:24:16 INFO - PID 2540 | Number of tests: 14 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.50 stddev:3.47 (3.1%) stddev-sans-first:1.01 05:24:16 INFO - PID 2540 | Values: 103.0 114.0 112.0 113.0 115.0 113.0 115.0 114.0 113.0 113.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:125.50 stddev:21.39 (17.0%) stddev-sans-first:21.46 05:24:16 INFO - PID 2540 | Values: 147.0 110.0 111.0 156.0 111.0 111.0 112.0 115.0 164.0 136.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#2] audio-dft Cycles:10 Average:205.20 Median:206.50 stddev:10.55 (5.1%) stddev-sans-first:10.65 05:24:16 INFO - PID 2540 | Values: 196.0 205.0 196.0 205.0 208.0 221.0 210.0 222.0 199.0 190.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#3] audio-fft Cycles:10 Average:66.50 Median:64.50 stddev:10.38 (16.1%) stddev-sans-first:9.98 05:24:16 INFO - PID 2540 | Values: 79.0 57.0 59.0 84.0 64.0 60.0 65.0 58.0 80.0 59.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#4] audio-oscillator Cycles:10 Average:82.20 Median:76.50 stddev:14.30 (18.7%) stddev-sans-first:15.10 05:24:16 INFO - PID 2540 | Values: 86.0 70.0 82.0 70.0 63.0 83.0 106.0 103.0 71.0 88.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:89.00 stddev:3.03 (3.4%) stddev-sans-first:3.20 05:24:16 INFO - PID 2540 | Values: 89.0 89.0 89.0 88.0 90.0 88.0 88.0 88.0 89.0 98.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#6] imaging-darkroom Cycles:10 Average:99.70 Median:96.00 stddev:7.75 (8.1%) stddev-sans-first:8.10 05:24:16 INFO - PID 2540 | Values: 96.0 97.0 96.0 95.0 96.0 108.0 119.0 97.0 96.0 97.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#7] imaging-desaturate Cycles:10 Average:109.10 Median:90.00 stddev:24.18 (26.9%) stddev-sans-first:24.53 05:24:16 INFO - PID 2540 | Values: 89.0 146.0 141.0 94.0 92.0 89.0 92.0 91.0 119.0 138.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#8] json-parse-financial Cycles:10 Average:61.20 Median:60.50 stddev:12.52 (20.7%) stddev-sans-first:12.61 05:24:16 INFO - PID 2540 | Values: 50.0 50.0 49.0 54.0 60.0 81.0 77.0 77.0 61.0 53.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:3.60 (6.0%) stddev-sans-first:3.77 05:24:16 INFO - PID 2540 | Values: 58.0 56.0 66.0 62.0 64.0 62.0 57.0 57.0 58.0 56.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:87.50 stddev:7.07 (8.1%) stddev-sans-first:7.22 05:24:16 INFO - PID 2540 | Values: 84.0 87.0 86.0 87.0 89.0 86.0 90.0 88.0 109.0 89.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:145.50 stddev:12.08 (8.3%) stddev-sans-first:12.57 05:24:16 INFO - PID 2540 | Values: 138.0 143.0 141.0 138.0 148.0 133.0 130.0 172.0 153.0 150.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.00 Median:188.00 stddev:14.62 (7.8%) stddev-sans-first:15.29 05:24:16 INFO - PID 2540 | Values: 185.0 189.0 185.0 192.0 191.0 187.0 186.0 185.0 187.0 233.0 05:24:16 INFO - PID 2540 | 05:24:16 INFO - PID 2540 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.70 Median:79.00 stddev:15.20 (19.2%) stddev-sans-first:15.79 05:24:16 INFO - PID 2540 | Values: 70.0 82.0 57.0 91.0 66.0 86.0 105.0 92.0 76.0 62.0 05:24:16 INFO - PID 2540 | -------- Summary: end -------- 05:24:16 INFO - PID 2540 | 05:24:17 INFO - TEST-INFO | 2540: exit 0 05:24:17 INFO - TEST-OK | kraken | took 90414ms 05:24:17 INFO - SUITE-END | took 1233s 05:24:17 INFO - Completed test suite (00:20:33) 05:24:17 INFO - javascript benchmark 05:24:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8090.797051441842, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3549.0062782420932, 3558.4662300654904, 3565.3047655707132, 3608.3685355062876, 3614.5481814773143, 5107.846764597063, 5350.250964864912, 6100.084987251903, 6157.076438534216, 6236.1892953915985, 6131.590761709979, 6162.537809664282, 6162.876742465148, 6173.259208894928, 6223.502119830414, 4277.957220427803, 5012.223105418666, 5422.240886275925, 5474.014677358074, 5537.833864984057, 4376.2997920332755, 5526.751296191661, 5538.418466061066, 5596.58025648078, 5634.7182640867995, 5474.178873169017, 5508.091164957777, 5540.612157149001, 5590.468905453975, 5615.466530679579, 5375.462453754632, 5465.0, 5471.06991811393, 5476.205950137248, 5510.035743744868, 4467.285234362486, 4900.289458028602, 5481.123020316731, 5481.780728770845, 5497.532709719679, 6113.750943094551, 6226.003839385722, 6237.968810155966, 6238.968805155991, 6241.8127456175935, 6438.066480360369, 6530.346965303479, 6567.573107748029, 6660.666966651648, 6673.532852700313, 6743.831404214934, 6803.863922721567, 6822.590644561293, 6825.658717064127, 6830.146231721035, 6285.340039295854, 6299.653519056405, 6319.652419116901, 6322.36776322364, 6370.33111523288, 6658.234303055114, 6757.628330441873, 6771.153605799275, 6907.447404207652, 6939.167299923941, 6290.370962903765, 6475.125858009108, 6486.2216534016225, 6553.442957348583, 6568.1789776277965, 6481.02784582307, 6580.276169621337, 6662.067310576524, 6762.763303284361, 6778.8644227116165, 6633.336666333328, 6644.900326495106, 6656.900146497806, 6662.866742665216, 6669.7665581704405, 6449.709763060673, 6677.165354330708, 6683.832904177434, 6685.6322902240845, 6692.0, 5309.548688361532, 5345.65253258537, 5360.061989151961, 5393.406725260218, 5421.647592906448, 4788.545088216614, 4797.280407938837, 4804.495527969513, 4814.374131362971, 4875.780589873464, 4822.565969062801, 6069.846729121453, 6208.81373558794, 6289.150964619809, 6315.210598675166, 5746.4253574643035, 6643.534952553371, 6709.329067093349, 6737.056812046318, 6801.0, 6278.497720182374, 6283.465905398001, 6302.590331628429, 6323.462505686976, 6328.6519241442165, 5806.738696758656, 6034.306054803666, 6035.155078289043, 6049.8487537811025, 6057.121717350959, 6730.7307707692125, 6797.184337879387, 6840.931590684029, 6886.8622627548175, 6915.170179578483, 6827.214870289781, 6827.52207345481, 6840.965795170992, 6847.6576171190245, 6856.725730970706, 5969.880602388015, 6857.759978400732, 6915.446764258948, 6935.757248496278, 6956.200036995608, 6104.26748790148, 6119.624418755179, 6522.0, 6571.868562628625, 6619.536632435684, 6763.086983257394, 6770.627615481196, 6788.626625535643, 6797.6941037654415, 6811.352921472452, 6543.0, 6601.702923368364, 6607.240167380813, 6613.603183808987, 6625.17185351831, 6393.87212255743, 6469.8706025880165, 6562.343765623399, 6586.967065164644, 6595.604263744191, 6409.4231519163495, 6482.708278127401, 6493.707783149675, 6494.993276042221, 6509.707063182073, 6559.048937904073, 6585.3085426029575, 6588.8352791180605, 6589.769358072444, 6615.867682646416, 6294.370562943669, 6314.589551679126, 6360.109584658059, 6382.36176382358, 6457.903131453126], "value": 6083.421624430069, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [707.0, 800.2237829305571, 815.844989452004, 1141.212563331302, 1257.182831159746, 39202.21595568087, 40090.0, 40091.799541002256, 40122.79324637144, 40210.0, 40292.0, 40764.388534171936, 40866.79566602163, 40876.0, 41003.79498102505, 7978.042634883807, 8148.166662500949, 8443.746687599381, 8656.394052416334, 8662.0, 8423.578821058953, 8499.660013599449, 8545.487270763742, 8545.572721363907, 8557.272631826303, 8496.915030849705, 8499.065102838682, 8535.743927682179, 8572.271356934636, 8588.527630980294, 8496.362772792034, 8525.95737021311, 8545.871811922827, 8567.74296771098, 8570.785730356729, 7194.676239569232, 8436.957815210884, 8480.279176269964, 8499.872501912476, 8517.318614510825, 37580.62419375798, 37627.623723762685, 37674.81162594197, 37739.86780396565, 37833.621663783284, 3068.5704001439817, 3566.197605538759, 3566.4828599853136, 3601.7838929664304, 3616.4936908832783, 24598.754012459824, 30582.541261881088, 30724.692753072406, 30747.538786918216, 30752.0, 3062.3569050499423, 3094.7678924080783, 3095.798773079765, 3112.0, 3128.655847856734, 241438.0, 242830.0, 282314.5884270586, 301547.0, 302425.0, 47465.5253447468, 47624.523754762704, 47644.0, 47781.76109119432, 47828.04343913102, 49374.753126234136, 49446.752766235935, 49642.50357496451, 55363.72318138384, 55481.0, 13862.514811981533, 14753.0, 14787.408503659948, 14789.704205916036, 14906.254687265593, 11681.707957301136, 11768.235064720937, 11783.469743861375, 11816.46825892837, 11880.524779008916, 14108.435662573587, 14112.576622701336, 14160.150390976574, 14166.079204851652, 14262.643433914234, 6270.467010304175, 6276.46650034743, 6290.21372328459, 6323.588966717149, 6324.335944725737, 845.4377838737171, 1005.969820905374, 1026.8973102689672, 1040.9271351005357, 1047.4553232319304, 19613.80386196148, 23055.53888922246, 23127.027689046936, 24367.634485482733, 24544.50910981806, 1073.8174510333263, 1084.983725244122, 1100.7028102412303, 1152.7924973504682, 1152.8558930133734, 12311.6922076947, 12567.93716031414, 12726.80909786354, 12774.808377874338, 12804.97560195182, 335391.6460835409, 337631.0, 337635.0, 338014.0, 338296.0, 54411.45588544143, 54441.72779136079, 54457.183142252106, 54623.726881365335, 54882.0, 1493.9775903361458, 1551.2088834694162, 1579.1393690438738, 1580.2967679382793, 1580.913049782273, 22804.657930131394, 22838.31485055451, 23574.52850943006, 24101.0, 24588.385290367885, 38654.0, 38696.8390948272, 38698.80650596729, 38773.64292249758, 38819.0, 267462.0, 267507.0, 268329.0, 268334.0, 268911.0, 35989.82005090063, 36042.63957360393, 36203.0, 36259.28629278177, 36276.911692649264, 43900.0, 44247.77876110599, 45627.31559026684, 45679.54320456886, 45795.0, 29507.967221147155, 29797.70202298036, 30533.847330763205, 30696.539551907186, 31226.687733122377, 29219.831206752344, 29859.850700746356, 30767.0, 30932.69067309298, 31228.12631242133, 35480.64519354773, 37072.70245541393, 37752.0, 37782.0, 38012.429813553375, 26594.468110637292, 29854.850725746233, 30984.380312394078, 31311.686883131788, 31589.52615710812, 22619.88690056539, 24312.51374972526, 24503.50992980166, 24575.631365529174, 24618.261452156465], "value": 17643.246613383704, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.81346021210885, 437.43789230838377, 438.1237524950101, 441.9823207071717, 455.37158720965044, 21665.91670416479, 22524.66213006804, 22537.436564085885, 23793.40516487089, 24781.876090619568, 19966.60066798667, 24078.75921240787, 24402.413843100185, 24539.509209815795, 24561.75438245617, 6091.360407157251, 6219.222597175353, 6232.376762323776, 6233.2208473940755, 6238.220222472191, 6062.4543791058795, 6072.240969878765, 6137.754489820401, 6150.569460137792, 6153.969230153843, 5944.7324870380935, 6171.691415429233, 6186.474149697258, 6211.565190436671, 6233.753249350126, 6175.536834737391, 6176.907346389808, 6194.5044396448175, 6226.097215903714, 6230.065490176465, 5509.173623956399, 5524.861878453031, 5534.252875861787, 5541.33503979521, 5545.473180047889, 28184.57723134134, 29044.8547757262, 29442.558361624593, 29445.411091778045, 29541.704582954324, 2229.498362868358, 2270.738865030526, 2271.818254539633, 2274.0, 2279.247848210087, 14483.855161448462, 18769.436916892515, 18815.71776423355, 18823.71764423535, 18899.52751181218, 1874.4470381237568, 2170.7720689327707, 2202.9559408811892, 2226.743924448677, 2232.2633530934754, 154883.67674484893, 160943.19528402283, 189144.16283755755, 203788.0, 209414.0, 12973.481060757653, 13228.933855330853, 13269.469221231042, 13280.203187808762, 13424.26166560829, 17001.319947201973, 17140.742888856676, 17160.141992900306, 17161.742573861404, 17180.140992950302, 8927.062658420902, 9237.12247336502, 9243.90756092444, 9253.444793312423, 9293.163615274658, 8854.557272136368, 8899.599518021705, 8916.242119419792, 8973.50645714479, 8985.281177505914, 8283.544405057779, 8313.33493320533, 8351.8329633407, 8383.916160838435, 8400.915990840012, 2253.5380247049316, 2261.7398999114985, 2262.841601087941, 2274.454131008546, 2438.9756102439105, 941.75985123793, 949.8670186173941, 950.0509483280574, 960.6301573893961, 967.9449400153866, 20673.586528269352, 21283.680744788842, 21343.67984480234, 21388.679169812465, 21853.0, 979.6032606794117, 979.6424305128719, 1008.5007921078966, 1023.2325755683238, 1040.1834559870617, 8193.46742461738, 9607.663731769355, 10228.693139205836, 10250.231232657432, 10982.835257471144, 551309.0, 553009.0, 553681.0, 562364.0, 565376.0, 149878.0, 155245.0, 155252.0, 155528.0, 156414.0, 979.7305740921304, 1111.8332250162541, 1164.8019836627793, 1240.8200810882372, 1243.8631750507618, 17396.347347002014, 20602.896985514977, 21945.561088778453, 21948.231811886508, 23418.297451076494, 60828.0, 69457.65271173611, 70697.0, 70719.64640176766, 71683.0, 93869.0, 94873.0, 95075.52462237644, 95133.5243323779, 95689.0, 10009.149222315893, 10224.948875255575, 10230.846537302095, 10238.33450825694, 10247.692569222936, 14609.488667896572, 17533.0, 17946.282148713904, 18018.549536261697, 18040.368587099387, 13432.597022089352, 14667.559973200821, 14687.265636717908, 14797.482088126864, 14805.851941480447, 12866.935665321613, 12904.741905162033, 13127.73744525085, 13199.538016169772, 13451.865481345452, 14893.329800159236, 14938.850611494181, 14940.551783446514, 15101.39594416255, 15130.54608361751, 9847.753806154904, 10351.948240258751, 10370.27408081427, 10437.425941573287, 10461.790764184825, 14823.851761482678, 14860.479883204469, 14914.105153690813, 14971.32629031718, 15043.323050462974], "value": 11239.874274195758, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1335.5659410691524, 1382.5714028651112, 1388.4376827384913, 1392.2133994293224, 1403.880670143038, 11593.42032898355, 11961.2823230606, 12083.758324833498, 12116.75766484671, 12307.63077107686, 11905.16663833532, 11948.342841143756, 12076.0, 12081.697957551065, 12095.939520302387, 2201.8678879267213, 2482.7889629381434, 2521.10500772226, 2564.3332733489333, 2564.8076394270506, 2131.6909048188004, 2487.1543675150447, 2526.0527302261653, 2539.3651587103222, 2540.8602526861014, 2498.2130628851946, 2509.573372526675, 2514.346269969812, 2547.605121206216, 2553.4510080332725, 2286.1884031168943, 2299.678045073691, 2500.5624015797252, 2508.423062695572, 2518.8110891683104, 2449.632555116729, 2502.5620516409554, 2511.0207019262502, 2563.218218443374, 2564.9355517460417, 11202.327860328407, 11742.882571174265, 11846.822297665542, 11864.406779661069, 11870.762584748343, 6288.245410550718, 6431.389018043278, 6463.48292136628, 6516.57642253252, 6520.836979075513, 4119.073208528087, 4220.409142720022, 4298.505671847746, 4311.9784401078105, 4312.22379971605, 1045.7973330669715, 1163.7032556697989, 1243.030436259719, 1270.8284381608457, 1279.8976081913424, 15491.0, 15947.999999999884, 16551.420700275554, 16573.17134143288, 16721.83278167227, 5214.113600687892, 5530.557555395559, 5714.342850572238, 5753.8561535961935, 5781.161731548902, 10148.797024059626, 10874.45627718611, 12059.75880482403, 12276.202046867103, 12300.507979680713, 6460.321666224979, 6486.67566621667, 6502.414782669583, 6567.868642627216, 6574.506911981621, 6031.819045428644, 6201.472874805692, 6214.130021796862, 6230.7819226327765, 6248.718807653667, 4344.3483477478, 4386.583274588909, 4401.22978478765, 4429.712068715523, 4453.240969816904, 1274.2736640115045, 1297.5977446991462, 1297.7339645372865, 1305.0733978874916, 1310.7640624687458, 810.8418858322689, 822.9094799572042, 828.1552816127517, 830.6967956695884, 841.0748177005245, 6298.716557754912, 6759.662016899234, 7553.697852085965, 7697.153313135551, 7862.72480463181, 770.8535378278109, 941.1012483078644, 952.1620973543376, 956.8851737791509, 967.351874244258, 4708.576228139484, 4843.927341089887, 4992.525710057539, 5195.948040519622, 5215.452377500384, 14532.782008269884, 14834.332455039548, 15189.316480758393, 15344.462943796914, 15350.23248837576, 12469.314187719581, 14880.925595371953, 15195.164265965477, 15198.620034499005, 15328.386864525517, 6441.935580644134, 6934.271901450376, 6974.651267436608, 8556.957215214008, 8824.779380515538, 6079.878402432015, 6482.444213388847, 7124.5725256484775, 7503.962480187564, 7572.280633339824, 12616.053795965523, 12680.302583358003, 12938.482460701467, 12943.288119153536, 13246.602601921957, 11032.944835275772, 13318.800217996544, 13383.19700817954, 13699.0, 13731.862681373059, 7491.726406511015, 9315.627374904929, 9710.660126895526, 9725.367851089657, 9828.852567211641, 10438.21713371485, 10733.624323148651, 10746.677599672023, 10775.838362424727, 10824.134069274282, 8808.647654093764, 9098.0, 9118.726438206864, 9165.495897725688, 9269.67556135559, 8778.780530486789, 8822.51476168817, 8840.911590884267, 8913.465192088495, 9063.818723625622, 8185.918140818516, 8319.916800831914, 8329.916700832913, 8340.666373344999, 8357.707480238163, 6017.127516510169, 6125.938740612715, 6398.744050237939, 6452.9677351612945, 6508.967455162694, 6164.845878852885, 6175.567710260419, 6507.284198738041, 6545.21457425112, 6554.311797261219], "value": 5407.739928124096, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.741627654844, 403.2580052703026, 403.4976454314377, 406.1308799169779, 417.8788151436083, 4776.665633405663, 5183.844484665461, 5624.521915637166, 6408.308510425621, 6431.967840160799, 6169.722362493687, 6232.252129744437, 6245.002398848544, 6260.436560709535, 6278.403551662596, 4535.70517916336, 4634.745089020103, 4679.321498382733, 4696.671233013691, 4736.85789426316, 3895.987043368716, 4498.52765459627, 4677.929831052537, 4684.086603112394, 4692.671512994092, 3906.453096566483, 4637.489876113625, 4644.140833945731, 4650.4186976627925, 4654.301854721802, 3845.653891149782, 4661.184292748772, 4671.906561868744, 4677.158111539921, 4698.765061746899, 3527.9823600882087, 3586.1393265616066, 3782.8273235297147, 3818.713596480275, 4066.085130845566, 5317.457937198246, 5413.998410294069, 6301.873962520724, 6355.872882542323, 6376.26672932614, 5516.7517461714315, 5528.640638358494, 5552.0, 5565.526930210896, 5567.916481252784, 6843.7604683836325, 6851.0, 6858.279880612564, 6891.552049116792, 6916.515843890929, 3674.191677830873, 3797.373433383488, 3849.5188101487315, 4571.588557029883, 4586.830358258622, 6927.58434493932, 7119.184607924998, 7200.495965282433, 7295.416366690652, 7340.853182936365, 3907.7264591478324, 3978.542467616261, 4245.97877010613, 4591.95408045922, 4628.768561571908, 4747.738874361943, 4751.667383283137, 4754.334393184957, 4785.3300537924715, 4790.760461976957, 5271.4728527147745, 5485.890282194414, 5489.459298752283, 5509.779608815683, 5513.338399392099, 6849.760258390932, 6857.897131543031, 6886.724531018703, 6909.654517274116, 6940.9652951734915, 1602.5016609470183, 1604.9197540122948, 1610.5007447691253, 1617.5713435939485, 1628.9755653665204, 1039.2569312941132, 1057.9153667706564, 1079.3416016230094, 1079.9352038877691, 1081.2377274021796, 6291.496680265568, 6410.294867564658, 6473.514486413538, 6477.514186435942, 6586.868262634721, 5685.459881311269, 6223.315435302113, 7120.750773722895, 7129.465290103263, 7143.535480259302, 5460.836174914759, 5513.641613295204, 6429.646369449725, 6432.967835160888, 6458.093857764025, 3407.9659203408146, 3592.7125829933543, 3684.907877303035, 3725.627437256307, 3736.4955730976512, 6351.714172862232, 7310.59791711461, 7332.853342933112, 7337.789264771253, 7340.15588207363, 6055.878882422327, 7286.453515986323, 7308.817279568053, 7319.2314806944505, 7332.810227551033, 5674.687892165971, 6768.187817461936, 6772.3926288379325, 6801.387875091265, 7222.277772222946, 6276.959988003826, 6575.572587781779, 6690.149641509866, 7062.435005199469, 7136.3220494052985, 5758.740856661377, 5946.524278057658, 6460.415833666383, 6921.501024334022, 7035.854064510373, 5775.27932821451, 6999.825004374931, 7260.578400226589, 7266.818329541592, 7364.3003914628025, 4651.372064771348, 4665.6034237090225, 4683.812647494062, 4688.109259240733, 4709.270063140219, 6430.324815894263, 6674.699638516376, 6720.966395167993, 6891.517593768388, 7127.144742630918, 6844.0, 6859.897101543581, 6872.725090996304, 6889.517733758589, 6908.689108990208, 5661.886762264649, 6864.759733409307, 6878.518503704692, 6905.309469053054, 6907.309269073254, 6182.938170618416, 6645.245436328459, 6766.4248538874745, 6774.848275793029, 6798.5383142622595, 5757.453041961007, 6891.552049116792, 6908.065741592362, 6908.374817569292, 6940.9652951734915, 5646.576506761927, 6669.43226857673, 6719.177945262903, 6736.360045795642, 6756.256811750605], "value": 4913.604964937153, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.9999999999997, 806.5402720449346, 831.5384961346452, 843.7890527368158, 858.6737039924826, 16502.009879407244, 17002.234899429524, 17017.404390846325, 17034.063126528035, 17041.91479042606, 15104.546863594082, 15236.238188090607, 15460.304286307115, 16002.119883406407, 16039.518814435556, 1000.4947501511745, 1012.9746756331077, 1014.9137323327526, 1019.7399663085903, 1023.0843395161326, 1021.6117875207448, 1023.6110278094313, 1025.1286406554407, 1028.444639894456, 1032.664384075175, 1019.9235057370689, 1062.9946850265776, 1066.4720963123266, 1067.0769784136705, 1069.6577095329499, 1035.6168217759478, 1058.0, 1065.1106326217619, 1067.2849191041983, 1072.7479042425025, 767.4819496839611, 786.5241528875006, 789.2226157235119, 789.6643926331344, 790.5809920742016, 16989.15054247295, 16993.66012679752, 17044.573885652833, 17107.57231069221, 17331.740023899652, 14259.643508912257, 16872.734544909057, 17351.91324043384, 17862.0175890326, 17876.55308617282, 14256.429742810275, 14788.556343309718, 14877.925610371987, 14904.70190596182, 14907.180105094216, 3529.4176460883923, 3573.4997100405963, 3577.5528058992627, 3595.7303202259936, 3596.5684117905757, 129116.0, 163030.0, 163500.0, 166057.99999999878, 166371.0, 11125.777484450266, 11306.547738090549, 12559.811602825965, 13869.445222191202, 13904.096233744775, 26287.868560657073, 26908.327291817863, 26917.192484225503, 26961.0563630272, 27190.728092719215, 7668.156502784689, 7740.6129693515095, 7750.031246094239, 7752.0, 7764.844703105906, 3839.308924393636, 3882.5535063467496, 3896.493455850778, 3915.393114067324, 3920.8039598019986, 4374.4531933508315, 4425.3140763181755, 4484.484284307347, 4517.344984977226, 4525.456945166601, 814.1980149552686, 870.1037930931151, 876.5748611923212, 892.6295587331185, 905.2305540290832, 633.8196603683315, 643.340575909687, 645.3127419298432, 664.5879554676035, 673.5217995223348, 11295.322280663186, 12594.0, 12946.0, 13721.108127971649, 14033.578992630237, 645.6384424722171, 657.9111819904348, 659.0376258717097, 664.4319107163402, 666.3736088077288, 4410.338449232577, 5540.778368865281, 5690.9146362804595, 5717.142428635655, 5865.824025279248, 132436.0, 132507.0, 136754.31622841823, 141659.0, 145331.0, 117860.41069794596, 125046.3747681274, 125054.0, 125875.74124258824, 127922.0, 95172.52413738026, 99516.50241748746, 99534.5023274879, 99574.99999999854, 99775.0, 11741.647750567497, 12334.50661973547, 12335.938320308342, 12712.872871271169, 12739.426725797175, 41593.0, 42439.3634095495, 42524.0, 43308.56691433171, 43594.78202608967, 41908.79045604752, 54986.90026199534, 55337.44662553484, 55532.88934221374, 56534.86930261454, 16529.256183472015, 17311.307547698456, 17617.0, 17761.0, 17856.910715446338, 18631.44105676832, 19279.325223617107, 19406.0, 19432.0, 19460.221591136196, 17341.618384414916, 17387.13064346803, 18415.90792046031, 18446.0, 18458.26166953307, 17402.129893505527, 17492.737608935622, 18131.456056318333, 18192.636147277244, 18334.72497912559, 17382.652346953244, 17644.82355176483, 17832.643347133246, 17914.193861276013, 18024.2790288387, 11419.60031398897, 11436.1422893285, 11915.285082895054, 12335.629931102081, 12340.074494412775, 12671.493140274655, 12773.872261277269, 13475.932620336836, 13565.050446469048, 13581.320933953066], "value": 8750.100377947883, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 112.0, 113.0, 115.0, 113.0, 115.0, 114.0, 113.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 110.0, 111.0, 156.0, 111.0, 111.0, 112.0, 115.0, 164.0, 136.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 205.0, 196.0, 205.0, 208.0, 221.0, 210.0, 222.0, 199.0, 190.0], "value": 205.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 59.0, 84.0, 64.0, 60.0, 65.0, 58.0, 80.0, 59.0], "value": 66.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 82.0, 70.0, 63.0, 83.0, 106.0, 103.0, 71.0, 88.0], "value": 82.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 88.0, 90.0, 88.0, 88.0, 88.0, 89.0, 98.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 95.0, 96.0, 108.0, 119.0, 97.0, 96.0, 97.0], "value": 99.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 146.0, 141.0, 94.0, 92.0, 89.0, 92.0, 91.0, 119.0, 138.0], "value": 109.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 49.0, 54.0, 60.0, 81.0, 77.0, 77.0, 61.0, 53.0], "value": 61.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 66.0, 62.0, 64.0, 62.0, 57.0, 57.0, 58.0, 56.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 86.0, 87.0, 89.0, 86.0, 90.0, 88.0, 109.0, 89.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 141.0, 138.0, 148.0, 133.0, 130.0, 172.0, 153.0, 150.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 189.0, 185.0, 192.0, 191.0, 187.0, 186.0, 185.0, 187.0, 233.0], "value": 192.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 57.0, 91.0, 66.0, 86.0, 105.0, 92.0, 76.0, 62.0], "value": 78.7, "unit": "score"}]}]} 05:24:18 INFO - Return code: 0 05:24:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:24:18 INFO - # TBPL SUCCESS # 05:24:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:24:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:24:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:24:18 INFO - 2017-06-06 05:24:18.132 screenresolution[2548:42126] starting screenresolution argv=screenresolution get 05:24:18 INFO - 2017-06-06 05:24:18.135 screenresolution[2548:42126] Display 0: 1600x1200x32@60 05:24:18 INFO - 2017-06-06 05:24:18.141 screenresolution[2549:42129] starting screenresolution argv=screenresolution list 05:24:18 INFO - Available Modes on Display 0 05:24:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:24:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:24:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:24:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:24:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:24:18 INFO - Intel Iris: 05:24:18 INFO - Chipset Model: Intel Iris 05:24:18 INFO - Type: GPU 05:24:18 INFO - Bus: Built-In 05:24:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:24:18 INFO - Vendor: Intel (0x8086) 05:24:18 INFO - Device ID: 0x0a2e 05:24:18 INFO - Revision ID: 0x0009 05:24:18 INFO - Displays: 05:24:18 INFO - Display: 05:24:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:24:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:24:18 INFO - Display Serial Number: 1600x1200 60 05:24:18 INFO - Main Display: Yes 05:24:18 INFO - Mirror: Off 05:24:18 INFO - Online: Yes 05:24:18 INFO - Rotation: Supported 05:24:18 INFO - Return code: 0 05:24:18 INFO - Running post-action listener: _resource_record_post_action 05:24:18 INFO - [mozharness: 2017-06-06 12:24:18.382500Z] Finished run-tests step (success) 05:24:18 INFO - Running post-run listener: _resource_record_post_run 05:24:18 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 1946112; Write bytes: 572339200; Read time: 14; Write time: 1813 05:24:18 INFO - TinderboxPrint: CPU usage
53.3% 05:24:18 INFO - TinderboxPrint: I/O read bytes / time
1,946,112 / 14 05:24:18 INFO - TinderboxPrint: I/O write bytes / time
572,339,200 / 1,813 05:24:18 INFO - TinderboxPrint: CPU idle
2,344.5 (46.7%) 05:24:18 INFO - TinderboxPrint: CPU system
1,019.4 (20.3%) 05:24:18 INFO - TinderboxPrint: CPU user
1,657.8 (33.0%) 05:24:18 INFO - TinderboxPrint: Swap in / out
248,274,944 / 0 05:24:18 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 235627008; Write bytes: 233203712; Read time: 16835; Write time: 522 05:24:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 05:24:18 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 1081856; Write bytes: 319831040; Read time: 12; Write time: 1243 05:24:18 INFO - Running post-run listener: _upload_blobber_files 05:24:18 INFO - Blob upload gear active. 05:24:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:24:18 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 05:24:18 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'] 05:24:18 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 05:24:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:24:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:24:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:24:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:20 INFO - (blobuploader) - INFO - Done attempting. 05:24:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:24:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:24:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:20 INFO - (blobuploader) - INFO - Done attempting. 05:24:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:24:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:24:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:21 INFO - (blobuploader) - INFO - Done attempting. 05:24:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:24:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:24:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:24:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:24:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:24:24 INFO - (blobuploader) - INFO - Done attempting. 05:24:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:24:24 INFO - Return code: 0 05:24:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:24:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:24:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963cfa3e76dcf2df7665258ac42df562ae8025ae99e6b718103693862613f3664d57fc25e8a2faaf87bc2ef1aaea28df891001ebcf1abe9c7191baca49d090c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02b8ad125de7f5669c0bb16d5ef713d4d6793067d8aee923abe3d5ac8b2046645588ea3b71c13a19af73cb98c25e40945ad059d766efba35b8c7813a0ba4357d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70b621e5d8b124ede8aa060acc1bc6615021d6028d5e92fa3b848450759d35a5577b60266bc07e3fc20c2c18867db9494b99e0b132fff38230829213f550aa0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d688b1225defb24c669cfacac13a351878f64693a4ea29afc589260452c6f74fc3f77acfdc5e42e7a7344dd3e49d0b086e1271f82b369f1411a14df2f3e728da"} 05:24:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:24:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:24:24 INFO - Contents: 05:24:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/963cfa3e76dcf2df7665258ac42df562ae8025ae99e6b718103693862613f3664d57fc25e8a2faaf87bc2ef1aaea28df891001ebcf1abe9c7191baca49d090c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02b8ad125de7f5669c0bb16d5ef713d4d6793067d8aee923abe3d5ac8b2046645588ea3b71c13a19af73cb98c25e40945ad059d766efba35b8c7813a0ba4357d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70b621e5d8b124ede8aa060acc1bc6615021d6028d5e92fa3b848450759d35a5577b60266bc07e3fc20c2c18867db9494b99e0b132fff38230829213f550aa0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d688b1225defb24c669cfacac13a351878f64693a4ea29afc589260452c6f74fc3f77acfdc5e42e7a7344dd3e49d0b086e1271f82b369f1411a14df2f3e728da"} 05:24:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:24:24 INFO - Copying logs to upload dir... 05:24:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:24:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.104401 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-06 05:24:24.155176) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 05:24:24.158823) ========= 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.tFCUaFiY5s/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.z9R6girkiu/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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/963cfa3e76dcf2df7665258ac42df562ae8025ae99e6b718103693862613f3664d57fc25e8a2faaf87bc2ef1aaea28df891001ebcf1abe9c7191baca49d090c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02b8ad125de7f5669c0bb16d5ef713d4d6793067d8aee923abe3d5ac8b2046645588ea3b71c13a19af73cb98c25e40945ad059d766efba35b8c7813a0ba4357d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70b621e5d8b124ede8aa060acc1bc6615021d6028d5e92fa3b848450759d35a5577b60266bc07e3fc20c2c18867db9494b99e0b132fff38230829213f550aa0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d688b1225defb24c669cfacac13a351878f64693a4ea29afc589260452c6f74fc3f77acfdc5e42e7a7344dd3e49d0b086e1271f82b369f1411a14df2f3e728da"} build_url:https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011204 build_url: 'https://queue.taskcluster.net/v1/task/cE1wU3foSe-vXL2xYUHdEA/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/963cfa3e76dcf2df7665258ac42df562ae8025ae99e6b718103693862613f3664d57fc25e8a2faaf87bc2ef1aaea28df891001ebcf1abe9c7191baca49d090c3", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02b8ad125de7f5669c0bb16d5ef713d4d6793067d8aee923abe3d5ac8b2046645588ea3b71c13a19af73cb98c25e40945ad059d766efba35b8c7813a0ba4357d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70b621e5d8b124ede8aa060acc1bc6615021d6028d5e92fa3b848450759d35a5577b60266bc07e3fc20c2c18867db9494b99e0b132fff38230829213f550aa0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d688b1225defb24c669cfacac13a351878f64693a4ea29afc589260452c6f74fc3f77acfdc5e42e7a7344dd3e49d0b086e1271f82b369f1411a14df2f3e728da"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-06 05:24:24.192317) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 05:24:24.192672) ========= 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.tFCUaFiY5s/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.z9R6girkiu/Listeners TMPDIR=/var/folders/z3/6pjmk_m51bs66d77l9w689q000000w/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.005283 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-06 05:24:24.241260) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 05:24:24.241594) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-06 05:24:24.241908) ========= ========= Total master_lag: 0.18 =========