builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0077 starttime: 1496338451.03 results: success (0) revision: 62005e6aecdf95c9cffe5fb825d93123ec49c4b3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:11.027100) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:11.027859) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:11.028390) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:11.054163) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:11.054557) ========= 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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 10:34:11-- 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% 155M=0s 2017-06-01 10:34:11 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167202 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:11.245824) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:11.246215) ========= 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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.027487 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:11.295415) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 10:34:11.295772) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 62005e6aecdf95c9cffe5fb825d93123ec49c4b3 --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 62005e6aecdf95c9cffe5fb825d93123ec49c4b3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uOkkqGihNA/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.TfZGEPmNtZ/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 10:34:11,388 truncating revision to first 12 chars 2017-06-01 10:34:11,388 Setting DEBUG logging. 2017-06-01 10:34:11,388 attempt 1/10 2017-06-01 10:34:11,388 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/62005e6aecdf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 10:34:13,011 unpacking tar archive at: mozilla-central-62005e6aecdf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.887745 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 10:34:13.206471) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:13.206802) ========= script_repo_revision: 62005e6aecdf95c9cffe5fb825d93123ec49c4b3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:13.207186) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:13.207453) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 10:34:13.227278) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-01 10:34:13.227678) ========= /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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:34:13 INFO - MultiFileLogger online at 20170601 10:34:13 in /builds/slave/test 10:34:13 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 10:34:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:34:13 INFO - {'append_to_log': False, 10:34:13 INFO - 'base_work_dir': '/builds/slave/test', 10:34:13 INFO - 'blob_upload_branch': 'Firefox', 10:34:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:34:13 INFO - 'branch': 'Firefox', 10:34:13 INFO - 'buildbot_json_path': 'buildprops.json', 10:34:13 INFO - 'config_files': ('talos/mac_config.py',), 10:34:13 INFO - 'default_actions': ('clobber', 10:34:13 INFO - 'read-buildbot-config', 10:34:13 INFO - 'download-and-extract', 10:34:13 INFO - 'populate-webroot', 10:34:13 INFO - 'create-virtualenv', 10:34:13 INFO - 'install', 10:34:13 INFO - 'setup-mitmproxy', 10:34:13 INFO - 'run-tests'), 10:34:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:34:13 INFO - 'download_minidump_stackwalk': True, 10:34:13 INFO - 'download_symbols': 'ondemand', 10:34:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:34:13 INFO - 'tooltool.py': '/tools/tooltool.py', 10:34:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:34:13 INFO - '/tools/misc-python/virtualenv.py')}, 10:34:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:34:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:34:13 INFO - 'gecko_profile': False, 10:34:13 INFO - 'gecko_profile_interval': 0, 10:34:13 INFO - 'installer_path': 'installer.exe', 10:34:13 INFO - 'log_level': 'info', 10:34:13 INFO - 'log_name': 'talos', 10:34:13 INFO - 'log_to_console': True, 10:34:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:34:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:34:13 INFO - 'opt_config_files': (), 10:34:13 INFO - 'pip_index': False, 10:34:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:34:13 INFO - 'cmd': ('bash', 10:34:13 INFO - '-c', 10:34:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:34:13 INFO - 'enabled': True, 10:34:13 INFO - 'halt_on_failure': False, 10:34:13 INFO - 'name': 'check_screen_resolution'},), 10:34:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:34:13 INFO - 'cmd': ('bash', 10:34:13 INFO - '-c', 10:34:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:34:13 INFO - 'enabled': True, 10:34:13 INFO - 'halt_on_failure': False, 10:34:13 INFO - 'name': 'check_screen_resolution'},), 10:34:13 INFO - 'run_cmd_checks_enabled': True, 10:34:13 INFO - 'suite': 'dromaeojs-e10s', 10:34:13 INFO - 'system_bits': '32', 10:34:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:34:13 INFO - 'title': 't-yosemite-r7-0077', 10:34:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:34:13 INFO - 'use_talos_json': True, 10:34:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:34:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:34:13 INFO - 'work_dir': 'build'} 10:34:13 INFO - [mozharness: 2017-06-01 17:34:13.437863Z] Running clobber step. 10:34:13 INFO - Running pre-action listener: _resource_record_pre_action 10:34:13 INFO - Running main action method: clobber 10:34:13 INFO - rmtree: /builds/slave/test/build 10:34:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:34:16 INFO - Running post-action listener: _resource_record_post_action 10:34:16 INFO - [mozharness: 2017-06-01 17:34:16.054242Z] Finished clobber step (success) 10:34:16 INFO - [mozharness: 2017-06-01 17:34:16.054370Z] Running read-buildbot-config step. 10:34:16 INFO - Running pre-action listener: _resource_record_pre_action 10:34:16 INFO - Running main action method: read_buildbot_config 10:34:16 INFO - Using buildbot properties: 10:34:16 INFO - { 10:34:16 INFO - "project": "", 10:34:16 INFO - "product": "firefox", 10:34:16 INFO - "who": "ryanvm@gmail.com", 10:34:16 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:34:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:34:16 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:34:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:34:16 INFO - "stage_platform": "macosx64", 10:34:16 INFO - "basedir": "/builds/slave/test", 10:34:16 INFO - "buildnumber": 71, 10:34:16 INFO - "platform": "yosemite_r7", 10:34:16 INFO - "parent_task_id": "dFotKEGeTROhmnwrwTTe-g", 10:34:16 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:34:16 INFO - "slavebuilddir": "test", 10:34:16 INFO - "taskId": "UxNUTZqCTaKuoo5IusXr9A", 10:34:16 INFO - "branch": "mozilla-central", 10:34:16 INFO - "script_repo_revision": "production", 10:34:16 INFO - "revision": "62005e6aecdf95c9cffe5fb825d93123ec49c4b3", 10:34:16 INFO - "slavename": "t-yosemite-r7-0077", 10:34:16 INFO - "repo_path": "mozilla-central" 10:34:16 INFO - } 10:34:16 INFO - Finding installer, test and symbols from parent task. 10:34:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UxNUTZqCTaKuoo5IusXr9A'}, attempt #1 10:34:16 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UxNUTZqCTaKuoo5IusXr9A'}, attempt #1 10:34:17 INFO - Task dependencies: LSC0LXiZSSCgLyZ-5buzNA dFotKEGeTROhmnwrwTTe-g 10:34:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g'}, attempt #1 10:34:17 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:34:17 INFO - Set test_url: https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/test_packages.json 10:34:17 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/target.crashreporter-symbols.zip 10:34:17 INFO - Running post-action listener: _resource_record_post_action 10:34:17 INFO - [mozharness: 2017-06-01 17:34:17.779918Z] Finished read-buildbot-config step (success) 10:34:17 INFO - [mozharness: 2017-06-01 17:34:17.780056Z] Running download-and-extract step. 10:34:17 INFO - Running pre-action listener: _resource_record_pre_action 10:34:17 INFO - Running main action method: download_and_extract 10:34:17 INFO - mkdir: /builds/slave/test/build/tests 10:34:17 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')]} 10:34:17 INFO - trying https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:34:17 INFO - Downloading https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/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 10:34:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/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 10:34:18 INFO - Downloaded 1325 bytes. 10:34:18 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:34:18 INFO - Using the following test package requirements: 10:34:18 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:34:18 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:34:18 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:34:18 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:34:18 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'jsshell-mac.zip'], 10:34:18 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:34:18 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:34:18 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:34:18 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:34:18 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:34:18 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:34:18 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:34:18 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:34:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:34:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:34:18 INFO - Fetch https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:34:20 INFO - Content-Length response header: 57630239 10:34:20 INFO - Bytes received: 57630239 10:34:38 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 10:34:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:34:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:34:38 INFO - Fetch https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:34:40 INFO - Content-Length response header: 57630239 10:34:40 INFO - Bytes received: 57630239 10:34:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:34:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:34:58 INFO - Fetch https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:34:59 INFO - Content-Length response header: 14447425 10:34:59 INFO - Bytes received: 14447425 10:34: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')]} 10:34:59 INFO - trying https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:34:59 INFO - Downloading https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:34:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/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 10:35:01 INFO - Downloaded 67815270 bytes. 10:35:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:35:01 INFO - mkdir: /builds/slave/test/properties 10:35:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:35:01 INFO - Writing to file /builds/slave/test/properties/build_url 10:35:01 INFO - Contents: 10:35:01 INFO - build_url:https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:35:01 INFO - Running post-action listener: _resource_record_post_action 10:35:01 INFO - Running post-action listener: set_extra_try_arguments 10:35:01 INFO - [mozharness: 2017-06-01 17:35:01.674573Z] Finished download-and-extract step (success) 10:35:01 INFO - [mozharness: 2017-06-01 17:35:01.674710Z] Running populate-webroot step. 10:35:01 INFO - Running pre-action listener: _resource_record_pre_action 10:35:01 INFO - Running main action method: populate_webroot 10:35:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:35:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:35:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:35:01 INFO - '--tppagecycles', 10:35:01 INFO - '7'], 10:35:01 INFO - 'tests': ['tsvgm']}}, 10:35:01 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:35:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:35:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:35:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:35:01 INFO - 'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:35:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:35:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:35:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:35:01 INFO - 'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['damp', 'tps']}, 10:35:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:35:01 INFO - 'tests': ['damp', 'tps']}, 10:35:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['dromaeo_dom']}, 10:35:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:35:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:35:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:35:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:35:01 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 10:35:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['a11yr', 10:35:01 INFO - 'ts_paint', 10:35:01 INFO - 'tpaint', 10:35:01 INFO - 'sessionrestore', 10:35:01 INFO - 'sessionrestore_no_auto_restore', 10:35:01 INFO - 'tabpaint']}, 10:35:01 INFO - 'other-e10s': {'tests': ['a11yr', 10:35:01 INFO - 'ts_paint', 10:35:01 INFO - 'tpaint', 10:35:01 INFO - 'sessionrestore', 10:35:01 INFO - 'sessionrestore_no_auto_restore', 10:35:01 INFO - 'tabpaint']}, 10:35:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:35:01 INFO - 'ts_paint', 10:35:01 INFO - 'tpaint', 10:35:01 INFO - 'sessionrestore', 10:35:01 INFO - 'sessionrestore_no_auto_restore', 10:35:01 INFO - 'tabpaint']}, 10:35:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:35:01 INFO - 'ts_paint', 10:35:01 INFO - 'tpaint', 10:35:01 INFO - 'sessionrestore', 10:35:01 INFO - 'sessionrestore_no_auto_restore', 10:35:01 INFO - 'tabpaint']}, 10:35:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['a11yr', 10:35:01 INFO - 'ts_paint', 10:35:01 INFO - 'tpaint', 10:35:01 INFO - 'sessionrestore', 10:35:01 INFO - 'sessionrestore_no_auto_restore', 10:35:01 INFO - 'tabpaint']}, 10:35:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['a11yr', 10:35:01 INFO - 'ts_paint', 10:35:01 INFO - 'tpaint', 10:35:01 INFO - 'sessionrestore', 10:35:01 INFO - 'sessionrestore_no_auto_restore', 10:35:01 INFO - 'tabpaint']}, 10:35:01 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:35:01 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:35:01 INFO - 'bloom_basic_ref']}, 10:35:01 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 10:35:01 INFO - 'talos_options': ['--mitmproxy', 10:35:01 INFO - 'mitmproxy-recording-1.mp', 10:35:01 INFO - '--firstNonBlankPaint'], 10:35:01 INFO - 'tests': ['Quantum_1']}, 10:35:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['tsvgx', 10:35:01 INFO - 'tsvgr_opacity', 10:35:01 INFO - 'tart', 10:35:01 INFO - 'tscrollx', 10:35:01 INFO - 'cart', 10:35:01 INFO - 'tsvg_static']}, 10:35:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:35:01 INFO - 'tsvgr_opacity', 10:35:01 INFO - 'tart', 10:35:01 INFO - 'tscrollx', 10:35:01 INFO - 'cart', 10:35:01 INFO - 'tsvg_static']}, 10:35:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:35:01 INFO - 'talos_options': ['--disable-e10s'], 10:35:01 INFO - 'tests': ['tp5o']}, 10:35:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:35:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:35:01 INFO - 'talos_options': ['--disable-e10s', 10:35:01 INFO - '--xperf_path', 10:35:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:35:01 INFO - 'tests': ['tp5n']}, 10:35:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:35:01 INFO - 'talos_options': ['--xperf_path', 10:35:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:35:01 INFO - 'tests': ['tp5n']}}, 10:35:01 INFO - 'talos.zip': {'path': '', 10:35:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:35:01 INFO - Running post-action listener: _resource_record_post_action 10:35:01 INFO - [mozharness: 2017-06-01 17:35:01.697377Z] Finished populate-webroot step (success) 10:35:01 INFO - [mozharness: 2017-06-01 17:35:01.697491Z] Running create-virtualenv step. 10:35:01 INFO - Running pre-action listener: _resource_record_pre_action 10:35:01 INFO - Running main action method: create_virtualenv 10:35:01 INFO - Creating virtualenv /builds/slave/test/build/venv 10:35:01 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 10:35:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:35:01 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 10:35:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:35:01 INFO - Using real prefix '/tools/python27' 10:35:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:35:02 INFO - Installing distribute.............................................................................................................................................................................................done. 10:35:05 INFO - Installing pip.................done. 10:35:05 INFO - Return code: 0 10:35:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:35:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:35:05 INFO - Reading from file tmpfile_stdout 10:35:05 INFO - Output received: 10:35:05 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) 10:35:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:35: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')]} 10:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35: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')]} 10:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35: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', 'pip>=1.5'] in /builds/slave/test/build 10:35: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 pip>=1.5 10:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:05 INFO - 'HOME': '/Users/cltbld', 10:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:05 INFO - 'LOGNAME': 'cltbld', 10:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:05 INFO - 'MOZ_NO_REMOTE': '1', 10:35:05 INFO - 'NO_EM_RESTART': '1', 10:35:05 INFO - 'PAGER': '/bin/cat', 10:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:05 INFO - 'PWD': '/builds/slave/test', 10:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:05 INFO - 'SHELL': '/bin/bash', 10:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:05 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:05 INFO - 'USER': 'cltbld', 10:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:05 INFO - 'XPC_FLAGS': '0x0', 10:35:05 INFO - 'XPC_SERVICE_NAME': '0', 10:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:05 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 10:35:05 INFO - Cleaning up... 10:35:05 INFO - Return code: 0 10:35:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:35: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')]} 10:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35: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')]} 10:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35: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', '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 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:35: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 psutil>=3.1.1 10:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:05 INFO - 'HOME': '/Users/cltbld', 10:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:05 INFO - 'LOGNAME': 'cltbld', 10:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:05 INFO - 'MOZ_NO_REMOTE': '1', 10:35:05 INFO - 'NO_EM_RESTART': '1', 10:35:05 INFO - 'PAGER': '/bin/cat', 10:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:05 INFO - 'PWD': '/builds/slave/test', 10:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:05 INFO - 'SHELL': '/bin/bash', 10:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:05 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:05 INFO - 'USER': 'cltbld', 10:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:05 INFO - 'XPC_FLAGS': '0x0', 10:35:05 INFO - 'XPC_SERVICE_NAME': '0', 10:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:06 INFO - Downloading/unpacking psutil>=3.1.1 10:35:06 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 10:35:06 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 10:35:06 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 10:35:06 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 10:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:35:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:35:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:35:08 INFO - Installing collected packages: psutil 10:35:08 INFO - Running setup.py install for psutil 10:35:08 INFO - building 'psutil._psutil_osx' extension 10:35:08 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 10:35:08 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 10:35:08 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 10:35:09 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 10:35:09 INFO - building 'psutil._psutil_posix' extension 10:35:09 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 10:35:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:35:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:35:09 INFO - ^ 10:35:09 INFO - 1 warning generated. 10:35:09 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 10:35:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:35:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:35:09 INFO - Successfully installed psutil 10:35:09 INFO - Cleaning up... 10:35:09 INFO - Return code: 0 10:35:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:35:09 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')]} 10:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35:09 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')]} 10:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35:10 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 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35:10 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 10:35:10 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 10:35:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:10 INFO - 'HOME': '/Users/cltbld', 10:35:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:10 INFO - 'LOGNAME': 'cltbld', 10:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:10 INFO - 'MOZ_NO_REMOTE': '1', 10:35:10 INFO - 'NO_EM_RESTART': '1', 10:35:10 INFO - 'PAGER': '/bin/cat', 10:35:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:10 INFO - 'PWD': '/builds/slave/test', 10:35:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:10 INFO - 'SHELL': '/bin/bash', 10:35:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:10 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:10 INFO - 'USER': 'cltbld', 10:35:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:10 INFO - 'XPC_FLAGS': '0x0', 10:35:10 INFO - 'XPC_SERVICE_NAME': '0', 10:35:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:35: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 10:35: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 10:35: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 10:35: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 10:35:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:35:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:35:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:35:12 INFO - Installing collected packages: mozsystemmonitor 10:35:12 INFO - Running setup.py install for mozsystemmonitor 10:35:12 INFO - Successfully installed mozsystemmonitor 10:35:12 INFO - Cleaning up... 10:35:12 INFO - Return code: 0 10:35:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:12 INFO - 'HOME': '/Users/cltbld', 10:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:12 INFO - 'LOGNAME': 'cltbld', 10:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:12 INFO - 'MOZ_NO_REMOTE': '1', 10:35:12 INFO - 'NO_EM_RESTART': '1', 10:35:12 INFO - 'PAGER': '/bin/cat', 10:35:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:12 INFO - 'PWD': '/builds/slave/test', 10:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:12 INFO - 'SHELL': '/bin/bash', 10:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:12 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:12 INFO - 'USER': 'cltbld', 10:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:12 INFO - 'XPC_FLAGS': '0x0', 10:35:12 INFO - 'XPC_SERVICE_NAME': '0', 10:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:12 INFO - Downloading/unpacking jsonschema==2.5.1 10:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:35:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:14 INFO - Downloading functools32-3.2.3-2.tar.gz 10:35:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:35:14 INFO - warning: no files found matching '*.txt' 10:35:14 INFO - no previously-included directories found matching 'build' 10:35:14 INFO - no previously-included directories found matching 'dist' 10:35:14 INFO - no previously-included directories found matching '.git*' 10:35:14 INFO - Installing collected packages: jsonschema, functools32 10:35:14 INFO - Running setup.py install for functools32 10:35:14 INFO - warning: no files found matching '*.txt' 10:35:14 INFO - no previously-included directories found matching 'build' 10:35:14 INFO - no previously-included directories found matching 'dist' 10:35:14 INFO - no previously-included directories found matching '.git*' 10:35:14 INFO - Successfully installed jsonschema functools32 10:35:14 INFO - Cleaning up... 10:35:14 INFO - Return code: 0 10:35:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:35:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:14 INFO - 'HOME': '/Users/cltbld', 10:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:14 INFO - 'LOGNAME': 'cltbld', 10:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:14 INFO - 'MOZ_NO_REMOTE': '1', 10:35:14 INFO - 'NO_EM_RESTART': '1', 10:35:14 INFO - 'PAGER': '/bin/cat', 10:35:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:14 INFO - 'PWD': '/builds/slave/test', 10:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:14 INFO - 'SHELL': '/bin/bash', 10:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:14 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:14 INFO - 'USER': 'cltbld', 10:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:14 INFO - 'XPC_FLAGS': '0x0', 10:35:14 INFO - 'XPC_SERVICE_NAME': '0', 10:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:35:15 INFO - Cleaning up... 10:35:15 INFO - Return code: 0 10:35:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:35: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')]} 10:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35: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')]} 10:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35:15 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 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35:15 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 10:35:15 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 10:35:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:15 INFO - 'HOME': '/Users/cltbld', 10:35:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:15 INFO - 'LOGNAME': 'cltbld', 10:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:15 INFO - 'MOZ_NO_REMOTE': '1', 10:35:15 INFO - 'NO_EM_RESTART': '1', 10:35:15 INFO - 'PAGER': '/bin/cat', 10:35:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:15 INFO - 'PWD': '/builds/slave/test', 10:35:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:15 INFO - 'SHELL': '/bin/bash', 10:35:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:15 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:15 INFO - 'USER': 'cltbld', 10:35:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:15 INFO - 'XPC_FLAGS': '0x0', 10:35:15 INFO - 'XPC_SERVICE_NAME': '0', 10:35:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:15 INFO - Downloading/unpacking blobuploader==1.2.4 10:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:16 INFO - Downloading blobuploader-1.2.4.tar.gz 10:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:35:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:35:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:35:17 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 10:35:17 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 10:35:17 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 10:35:17 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 10:35:17 INFO - Downloading docopt-0.6.1.tar.gz 10:35:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:35:17 INFO - Installing collected packages: blobuploader, requests, docopt 10:35:17 INFO - Running setup.py install for blobuploader 10:35:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:35:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:35:17 INFO - Running setup.py install for requests 10:35:18 INFO - Running setup.py install for docopt 10:35:18 INFO - Successfully installed blobuploader requests docopt 10:35:18 INFO - Cleaning up... 10:35:18 INFO - Return code: 0 10:35:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35:18 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 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35:18 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 10:35:18 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 10:35:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:18 INFO - 'HOME': '/Users/cltbld', 10:35:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:18 INFO - 'LOGNAME': 'cltbld', 10:35:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:18 INFO - 'MOZ_NO_REMOTE': '1', 10:35:18 INFO - 'NO_EM_RESTART': '1', 10:35:18 INFO - 'PAGER': '/bin/cat', 10:35:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:18 INFO - 'PWD': '/builds/slave/test', 10:35:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:18 INFO - 'SHELL': '/bin/bash', 10:35:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:18 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:18 INFO - 'USER': 'cltbld', 10:35:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:18 INFO - 'XPC_FLAGS': '0x0', 10:35:18 INFO - 'XPC_SERVICE_NAME': '0', 10:35:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:35:18 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-qTaOIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:35:18 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-2ozFzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-TTM0SH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-XwA6KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fW73HR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Sm_hdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-q5plmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-J17Zto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-_meQm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-AR97W2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-c0GOpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:35:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:35:19 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-4wakWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:35:20 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-23T5LO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:35:20 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-vTxPKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:35:20 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-bZhEDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:35:20 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-7cfMiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:35:20 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-AfUya0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:35:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:35:20 INFO - Running setup.py install for manifestparser 10:35:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:35:20 INFO - Running setup.py install for mozcrash 10:35:20 INFO - Running setup.py install for mozdebug 10:35:20 INFO - Running setup.py install for mozdevice 10:35:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:35:21 INFO - Running setup.py install for mozfile 10:35:21 INFO - Running setup.py install for mozhttpd 10:35:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:35:21 INFO - Running setup.py install for mozinfo 10:35:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:35:21 INFO - Running setup.py install for mozInstall 10:35:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:35:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:35:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:35:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:35:21 INFO - Running setup.py install for mozleak 10:35:21 INFO - Running setup.py install for mozlog 10:35:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:35:22 INFO - Running setup.py install for moznetwork 10:35:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:35:22 INFO - Running setup.py install for mozprocess 10:35:22 INFO - Running setup.py install for mozprofile 10:35:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:35:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:35:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:35:22 INFO - Running setup.py install for mozrunner 10:35:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:35:22 INFO - Running setup.py install for mozscreenshot 10:35:22 INFO - Running setup.py install for moztest 10:35:22 INFO - Running setup.py install for mozversion 10:35:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:35:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:35:23 INFO - Cleaning up... 10:35:23 INFO - Return code: 0 10:35:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35:23 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 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35:23 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 10:35:23 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 10:35:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:23 INFO - 'HOME': '/Users/cltbld', 10:35:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:23 INFO - 'LOGNAME': 'cltbld', 10:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:23 INFO - 'MOZ_NO_REMOTE': '1', 10:35:23 INFO - 'NO_EM_RESTART': '1', 10:35:23 INFO - 'PAGER': '/bin/cat', 10:35:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:23 INFO - 'PWD': '/builds/slave/test', 10:35:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:23 INFO - 'SHELL': '/bin/bash', 10:35:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:23 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:23 INFO - 'USER': 'cltbld', 10:35:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:23 INFO - 'XPC_FLAGS': '0x0', 10:35:23 INFO - 'XPC_SERVICE_NAME': '0', 10:35:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:35:23 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-z2XSoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:35:23 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)) 10:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:35:23 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-7vQozW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:35:23 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)) 10:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:35:23 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Ak2UAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:35:23 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)) 10:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:35:23 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-cGoU94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:35:23 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)) 10:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:35:23 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-bRxwTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:35:23 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)) 10:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:35:23 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-hN45T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:35:23 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)) 10:35:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-OJpc3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:35:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-CyTul0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:35:24 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)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-vZkNlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:35:24 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)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-yOSA1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:35:24 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)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-ZAg0OH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:35:24 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)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Hu94Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:35:24 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)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-zg1yW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:35:24 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)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-WOoQwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:35:24 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)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-zR1duL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:35:24 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)) 10:35:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:35:24 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-7fXBgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:35:25 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)) 10:35:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:35:25 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-rga_2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:35:25 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)) 10:35:25 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)) 10:35:25 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)) 10:35:25 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)) 10:35:25 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)) 10:35:25 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)) 10:35:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:26 INFO - Downloading blessings-1.6.tar.gz 10:35:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:35:26 INFO - Installing collected packages: blessings 10:35:26 INFO - Running setup.py install for blessings 10:35:26 INFO - Successfully installed blessings 10:35:26 INFO - Cleaning up... 10:35:27 INFO - Return code: 0 10:35:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:35:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:35:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:35:27 INFO - Reading from file tmpfile_stdout 10:35:27 INFO - Current package versions: 10:35:27 INFO - blessings == 1.6 10:35:27 INFO - blobuploader == 1.2.4 10:35:27 INFO - docopt == 0.6.1 10:35:27 INFO - functools32 == 3.2.3-2 10:35:27 INFO - jsonschema == 2.5.1 10:35:27 INFO - manifestparser == 1.1 10:35:27 INFO - mozInstall == 1.12 10:35:27 INFO - mozcrash == 1.0 10:35:27 INFO - mozdebug == 0.1 10:35:27 INFO - mozdevice == 0.51 10:35:27 INFO - mozfile == 1.2 10:35:27 INFO - mozhttpd == 0.7 10:35:27 INFO - mozinfo == 0.9 10:35:27 INFO - mozleak == 0.1 10:35:27 INFO - mozlog == 3.4 10:35:27 INFO - moznetwork == 0.27 10:35:27 INFO - mozprocess == 0.25 10:35:27 INFO - mozprofile == 0.28 10:35:27 INFO - mozrunner == 6.13 10:35:27 INFO - mozscreenshot == 0.1 10:35:27 INFO - mozsystemmonitor == 0.3 10:35:27 INFO - moztest == 0.8 10:35:27 INFO - mozversion == 1.4 10:35:27 INFO - psutil == 3.1.1 10:35:27 INFO - requests == 1.2.3 10:35:27 INFO - wsgiref == 0.1.2 10:35:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35:27 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 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35:27 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 10:35:27 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 10:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:27 INFO - 'HOME': '/Users/cltbld', 10:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:27 INFO - 'LOGNAME': 'cltbld', 10:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:27 INFO - 'MOZ_NO_REMOTE': '1', 10:35:27 INFO - 'NO_EM_RESTART': '1', 10:35:27 INFO - 'PAGER': '/bin/cat', 10:35:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:27 INFO - 'PWD': '/builds/slave/test', 10:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:27 INFO - 'SHELL': '/bin/bash', 10:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:27 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:27 INFO - 'USER': 'cltbld', 10:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:27 INFO - 'XPC_FLAGS': '0x0', 10:35:27 INFO - 'XPC_SERVICE_NAME': '0', 10:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:27 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)) 10:35:27 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)) 10:35:27 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)) 10:35:27 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)) 10:35:27 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)) 10:35:27 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)) 10:35:27 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)) 10:35:27 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)) 10:35:27 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)) 10:35:27 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 10:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:35:28 INFO - Downloading marionette_driver-1.4.0.tar.gz 10:35:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 10:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:35:29 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)) 10:35:29 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)) 10:35:29 INFO - Installing collected packages: marionette-driver 10:35:29 INFO - Running setup.py install for marionette-driver 10:35:29 INFO - Successfully installed marionette-driver 10:35:29 INFO - Cleaning up... 10:35:29 INFO - Return code: 0 10:35:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:35:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:35:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:35:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:35:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107428c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10742c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99db575520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, '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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 10:35:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:35:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:35:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:29 INFO - 'HOME': '/Users/cltbld', 10:35:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:29 INFO - 'LOGNAME': 'cltbld', 10:35:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:29 INFO - 'MOZ_NO_REMOTE': '1', 10:35:29 INFO - 'NO_EM_RESTART': '1', 10:35:29 INFO - 'PAGER': '/bin/cat', 10:35:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:29 INFO - 'PWD': '/builds/slave/test', 10:35:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:29 INFO - 'SHELL': '/bin/bash', 10:35:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:29 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:29 INFO - 'USER': 'cltbld', 10:35:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:29 INFO - 'XPC_FLAGS': '0x0', 10:35:29 INFO - 'XPC_SERVICE_NAME': '0', 10:35:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:35:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:35:29 INFO - Cleaning up... 10:35:29 INFO - Return code: 0 10:35:29 INFO - Running post-action listener: _resource_record_post_action 10:35:29 INFO - Running post-action listener: _start_resource_monitoring 10:35:29 INFO - Starting resource monitoring. 10:35:29 INFO - [mozharness: 2017-06-01 17:35:29.632803Z] Finished create-virtualenv step (success) 10:35:29 INFO - [mozharness: 2017-06-01 17:35:29.633748Z] Running install step. 10:35:29 INFO - Running pre-action listener: _resource_record_pre_action 10:35:29 INFO - Running main action method: install 10:35:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:35:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:35:29 INFO - Reading from file tmpfile_stdout 10:35:29 INFO - Detecting whether we're running mozinstall >=1.0... 10:35:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:35:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:35:29 INFO - Reading from file tmpfile_stdout 10:35:30 INFO - Output received: 10:35:30 INFO - Usage: mozinstall [options] installer 10:35:30 INFO - Options: 10:35:30 INFO - -h, --help show this help message and exit 10:35:30 INFO - -d DEST, --destination=DEST 10:35:30 INFO - Directory to install application into. [default: 10:35:30 INFO - "/builds/slave/test"] 10:35:30 INFO - --app=APP Application being installed. [default: firefox] 10:35:30 INFO - mkdir: /builds/slave/test/build/application 10:35:30 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'] 10:35:30 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 10:35:51 INFO - Reading from file tmpfile_stdout 10:35:51 INFO - Output received: 10:35:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:35:51 INFO - Running post-action listener: _resource_record_post_action 10:35:51 INFO - [mozharness: 2017-06-01 17:35:51.538010Z] Finished install step (success) 10:35:51 INFO - [mozharness: 2017-06-01 17:35:51.538144Z] Running setup-mitmproxy step. 10:35:51 INFO - Running pre-action listener: _resource_record_pre_action 10:35:51 INFO - Running main action method: setup_mitmproxy 10:35:51 INFO - Skipping: mitmproxy is not required 10:35:51 INFO - Running post-action listener: _resource_record_post_action 10:35:51 INFO - [mozharness: 2017-06-01 17:35:51.538802Z] Finished setup-mitmproxy step (success) 10:35:51 INFO - [mozharness: 2017-06-01 17:35:51.538897Z] Running run-tests step. 10:35:51 INFO - Running pre-action listener: _resource_record_pre_action 10:35:51 INFO - Running main action method: run_tests 10:35:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:35:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:35:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:35:51 INFO - 2017-06-01 10:35:51.553 screenresolution[1621:7372] starting screenresolution argv=screenresolution get 10:35:51 INFO - 2017-06-01 10:35:51.556 screenresolution[1621:7372] Display 0: 1600x1200x32@60 10:35:51 INFO - 2017-06-01 10:35:51.561 screenresolution[1622:7374] starting screenresolution argv=screenresolution list 10:35:51 INFO - Available Modes on Display 0 10:35:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:35:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:35:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:35:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:35:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:35:51 INFO - Intel Iris: 10:35:51 INFO - Chipset Model: Intel Iris 10:35:51 INFO - Type: GPU 10:35:51 INFO - Bus: Built-In 10:35:51 INFO - VRAM (Dynamic, Max): 1536 MB 10:35:51 INFO - Vendor: Intel (0x8086) 10:35:51 INFO - Device ID: 0x0a2e 10:35:51 INFO - Revision ID: 0x0009 10:35:51 INFO - Displays: 10:35:51 INFO - Display: 10:35:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:35:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:35:51 INFO - Display Serial Number: 1600x1200 60 10:35:51 INFO - Main Display: Yes 10:35:51 INFO - Mirror: Off 10:35:51 INFO - Online: Yes 10:35:51 INFO - Rotation: Supported 10:35:51 INFO - Return code: 0 10:35:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:35:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:35:51 INFO - Python 2.7.3 10:35:51 INFO - Return code: 0 10:35:51 INFO - grabbing minidump binary from tooltool 10:35:51 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')]} 10:35:51 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 0x1073b1c60>, '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 0x107146cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10716fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:35:51 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 10:35:51 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 10:35:51 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 10:35:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:35:51 INFO - Return code: 0 10:35:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:35:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:35:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:35:51 INFO - ENV: RUST_BACKTRACE is now 1 10:35:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:35:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:35:51 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/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--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 10:35:51 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/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0077 --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 10:35:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uOkkqGihNA/Render', 10:35:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:35:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:35:51 INFO - 'HOME': '/Users/cltbld', 10:35:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:35:51 INFO - 'LOGNAME': 'cltbld', 10:35:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:35:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:35:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:35:51 INFO - 'MOZ_NO_REMOTE': '1', 10:35:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:35:51 INFO - 'NO_EM_RESTART': '1', 10:35:51 INFO - 'PAGER': '/bin/cat', 10:35:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:35:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:35:51 INFO - 'PWD': '/builds/slave/test', 10:35:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:35:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:35:51 INFO - 'RUST_BACKTRACE': '1', 10:35:51 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 10:35:51 INFO - 'SHELL': '/bin/bash', 10:35:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TfZGEPmNtZ/Listeners', 10:35:51 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 10:35:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:35:51 INFO - 'USER': 'cltbld', 10:35:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:35:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:35:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:35:51 INFO - 'XPC_FLAGS': '0x0', 10:35:51 INFO - 'XPC_SERVICE_NAME': '0', 10:35:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:35:51 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/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--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 10:35:52 INFO - mozversion application_buildid: 20170601085718 10:35:52 INFO - mozversion application_changeset: 62005e6aecdf95c9cffe5fb825d93123ec49c4b3 10:35:52 INFO - mozversion application_display_name: Nightly 10:35:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:35:52 INFO - mozversion application_name: Firefox 10:35:52 INFO - mozversion application_remotingname: firefox 10:35:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:35:52 INFO - mozversion application_vendor: Mozilla 10:35:52 INFO - mozversion application_version: 55.0a1 10:35:52 INFO - mozversion platform_buildid: 20170601085718 10:35:52 INFO - mozversion platform_changeset: 62005e6aecdf95c9cffe5fb825d93123ec49c4b3 10:35:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:35:52 INFO - mozversion platform_version: 55.0a1 10:35:52 INFO - using testdate: 1496338552 10:35:52 INFO - actual date: 1496338552 10:35:52 INFO - starting webserver on 'localhost:49228' 10:35:52 INFO - SUITE-START | Running 2 tests 10:35:52 INFO - TEST-START | dromaeo_css 10:35:52 INFO - operating with platform_type : mac_ 10:35:52 INFO - Initialising browser for dromaeo_css test... 10:35:52 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpQILGyq/profile 10:35:52 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 10:36:26 INFO - TEST-INFO | 1632: killed by SIGTERM 10:36:26 INFO - Browser initialized. 10:36:26 INFO - Running cycle 1/1 for dromaeo_css test... 10:36:26 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpQILGyq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:36:28 INFO - PID 1642 | 2017-06-01 10:36:28.122 plugin-container[1643:7942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 10:36:28 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:36:28 INFO - PID 1642 | 2017-06-01 10:36:28.123 plugin-container[1643:7942] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x481b, name = 'com.apple.CFPasteboardClient' 10:36:28 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:36:28 INFO - PID 1642 | 2017-06-01 10:36:28.123 plugin-container[1643:7942] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:36:28 INFO - PID 1642 | 2017-06-01 10:36:28.822 plugin-container[1644:7989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x483f, name = 'com.apple.tsm.portname' 10:36:28 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:36:28 INFO - PID 1642 | 2017-06-01 10:36:28.822 plugin-container[1644:7989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490f, name = 'com.apple.CFPasteboardClient' 10:36:28 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:36:28 INFO - PID 1642 | 2017-06-01 10:36:28.822 plugin-container[1644:7989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:36:38 INFO - PID 1642 | RSS: Main: 260448256 10:36:38 INFO - PID 1642 | 10:39:40 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 10:39:42 INFO - PID 1642 | RSS: Main: 271507456 10:39:42 INFO - PID 1642 | 10:42:43 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 10:42:44 INFO - PID 1642 | RSS: Main: 283803648 10:42:44 INFO - PID 1642 | 10:45:46 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 10:45:47 INFO - PID 1642 | RSS: Main: 284618752 10:45:47 INFO - PID 1642 | 10:48:48 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 10:48:49 INFO - PID 1642 | RSS: Main: 297508864 10:48:49 INFO - PID 1642 | 10:51:52 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 10:51:53 INFO - PID 1642 | RSS: Main: 297553920 10:51:53 INFO - PID 1642 | 10:54:54 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 10:54:55 INFO - PID 1642 | RSS: Main: 295571456 10:54:55 INFO - PID 1642 | 10:54:55 INFO - PID 1642 | __start_tp_report 10:54:55 INFO - PID 1642 | _x_x_mozilla_page_load 10:54:55 INFO - PID 1642 | _x_x_mozilla_page_load_details 10:54:55 INFO - PID 1642 | |i|pagename|runs| 10:54:55 INFO - PID 1642 | |0;dojo.html;3284.622268439129;3599.8740044098445;3618.1316484043837;3634.67287944085;3634.8546058157667;5097.490250974901;5525.839573689525;6143.293521245058;6203.720832562534;6204.844878878019;6146.016637338027;6164.167837341957;6168.198134242553;6188.288346840103;6259.374062593738;5383.488568585978;5424.91862622059;5432.483914028161;5447.945520544784;5448.237246785453;4782.852115492292;5524.364698059714;5528.530074943635;5554.944450555483;5557.5831812614015;4362.978185109086;4605.263157894736;5406.459354064601;5447.373552041487;5534.584906132016;5436.21174929637;5438.646487978269;5454.34547854256;5466.180072989044;5472.972635136839;5372.973135134338;5382.085045542267;5388.568914486832;5418.431064738224;5445.591580631469;6138.756267604225;6231.3145553989025;6234.127222188898;6246.6252024878195;6248.87502249957;6447.6131432114225;6579.506537009695;6623.437007854338;6657.533972621918;6679.231888332855;6443.226812782449;6784.932150678479;6796.898046529306;6803.2856550062525;6809.455243580551;6301.275353334379;6302.558820882586;6303.212098487735;6315.273743519509;6343.270523889812;6716.764913228014;6790;6830.7609233676585;6914.412274956685;6929.618870962045;6279.434850863353;6474.546781725328;6531.673416329259;6563;6568.981807819796;6592.17597800275;6604.471642268607;6639.03733958583;6765.661716914135;6772.627505487246;5332.773462103738;6571.57284776488;6611.272759996396;6651;6672.399484046459;6440.0661904023655;6526.477881769447;6675.86648267032;6678.49911256648;6682.899756503656;4378.299472084451;5303.655262407931;5328.0675881720545;5379.811706590251;5382.407935127133;4738.881527961828;4764.309175169581;4776.283557466339;4834.7824347904425;4852.078105160008;5461.781528738886;6271.27880293763;6331.113644089831;6334.8416289592205;6348.555601107878;6483.578567393199;6626.966865165643;6656.434203092695;6753.223379311344;6769.458443324522;5784.971075144597;5816.6330912236435;6281.528885333527;6317;6348.111264422984;4999.350084488993;5970.970145149333;5996.2504686914135;5997.910031349533;6009.939900600938;6677.3656502632175;6796.252412234551;6825.863482730417;6938.722451102;6953.130858642669;6699.330066993261;6740.730370785113;6762.661866906733;6782.93217067823;6793.286704895915;6786.59280443175;6958.965205173941;6963.09479767627;6968.860622787617;6980.441564674916;6446.29090800021;6585.407313341822;6597.703103360456;6635.933640663725;6659.700313485808;6627.204735431779;6682.131322927989;6729.596224226562;6742.359475849787;6795.25252222265;6556.409923106943;6565.474762019123;6572.8356791080605;6573.704183311666;6573.868522629425;6301.7164227608955;6451.612903225821;6463.676816159267;6488.967555162194;6541.2477565078725;6309.873802523832;6410.871782564416;6412.935870641234;6415;6435.485161187187;6405.903911441425;6465.127207826883;6471.352864713491;6498.480121590167;6523.7716679915975;6382.36176382358;6393.6483493408305;6394.616323020634;6406.871862562816;6423.389777971085 10:54:55 INFO - PID 1642 | |1;ext.html;788.8816677498374;816.1185919207255;838.0530001098759;1244.359155035157;1302.316283950925;37561;38681.419778703304;38687;38699.80650096753;38827.61172388275;39212.411813822815;39531.20937581247;39553.80223098888;39558;39652.603473965326;7791.415643826722;8205.01539815223;8410.957945210266;8432.578371081452;8453.408261421702;8281.751547453556;8378.832423351529;8381.539015354154;8401.663933442655;8403.327733781283;8335.66657333706;8347.165283471664;8370.958145209295;8406.87389689152;8416.83166336673;8324.62539185738;8354.373421993345;8390.874136887951;8411.15888411157;8437.746867593982;8171.101178870379;8192;8208.548529830858;8235.50586964778;8237;36332;36469.81765091184;36700.816495917614;36739.4489082664;36814.26371472582;3142.245860993368;3554.129238336624;3580.785152890809;3581.87463438781;3589.9768565958575;24036.879815600983;29912.8504357479;30159;30319.090427287218;30367.544486832714;2510.8870100845324;2792.8968057617126;3014.849257537114;3101.984490077558;3120.8595613197463;230462.8476857605;245028;247609;291576;292149.99999999785;47371.52628473671;47478.76260618675;47643.523564764604;47779.28331075037;47836.760816195696;49339;49553.50446495561;49624.255636165486;49739.75130124326;50004.74997625061;14390.42438302477;14436.133831969906;14572.489962851248;14592.781108283385;14619.049761765451;11632.825507617392;11769.411529423494;11773.587924422603;11794.646160615195;11796.351200684045;11899.048076153887;14061.01572889888;14067.366968486444;14087.295635218197;14173.433062677377;6056.06131049688;6087.634741915499;6130.969345153246;6139.907901381483;6142.6314421134875;913.4291068082448;926.8923636254746;968.6125549780004;1010.6664800615779;1031.783325501638;24070.51858962811;24350.878245609012;24359.75640243575;24401.755982439947;24404.511909761706;978.8238117138841;1061.3737894642045;1088.9346639201674;1125.859267591551;1145.73075327298;9870.851937220947;12404.077368007973;12415.379231038412;12424.503019879285;12446.315452650191;321905;326372.36813815776;326581;327102;329038;43860.78069609631;52181.95636087257;53654.92690146175;53734.462655373725;54062.45937540653;1432.2767002663797;1466.65533599604;1498.977515337271;1501.2493753123438;1511.2670354877876;23326.066957322197;23466.53066938686;23530.76469235286;23542.52914941726;23562.882185588962;36672.44991325079;36899;36985.445218321205;37025.518979241606;37082.258354832215;256643;259621;260807;260942;262246;33802.830985844914;34914.6034158641;35235.29529409449;35260.47109293311;35281.64718352886;43951.34072988844;43986.78006609946;44020.11959760851;44052.33921491244;44112.33831492466;28415.573766393933;29868.551971720008;30693.693063069975;31063.378732425677;32032.878849240165;26029.219123426326;29684;29897.850510747307;30698.846505767327;30722.846385767927;32099.518507221943;33169.170770730925;33757.83121084379;33799.32401352008;36995.815020924725;29906.401871962003;30847.84576077105;30950.380992380477;31041.068767937;31903.36193276168;24422.877885611168;24551.649659268198;24589.50820983606;24862.37844053913;24886.38238514491 10:54:55 INFO - PID 1642 | |2;jquery.html;396.397475836728;425.143336177602;429.73571253678995;437.684866895835;451.4898165073467;21994.45013874654;22805.5176413533;22875.542489150226;22937.77062229377;24167.516649666995;23826.808659566996;24286.392840179004;24336.878315608443;24339.026438942423;24393.75606243937;6146.969265153679;6202.038684003986;6276.623402595859;6288.213973253343;6289.622622642633;5931.555133364994;6173.135760993465;6220.222472190976;6220.595661282025;6239.906401403982;6151.323354431009;6221;6224.502039836825;6239.032949892774;6243.719032643519;6185.257769067718;6227.06594010902;6230.314665386803;6239.2200974877915;6240.282367527747;5558.944410555923;5570.777168913279;5570.916436253459;5581.553475721932;5584;28250.576241356397;29126.27184320388;29599.556006659917;29603.703962960524;29642.25894352637;2171.457135716071;2203.9559208815895;2209.9779002209934;2210.9778902210933;2216.3018649125474;18550.628987420176;18908.716369254336;18960;19072.523186920298;19091.140898659592;2125.3092744857827;2162.664786958024;2185.311626837557;2202.7466841313294;2206.4483879030245;137790;138569;172469.1376543122;173383;176366.23633763628;25794.613080803803;25968.480630387665;26363.868180658974;26493.072742454307;26799.06203282876;43403.78298108489;43422.78288608537;43486;43508.78245608815;43569.782151089676;11252.381119038531;11313.83029254562;11356.772864542829;11356.829647555292;11416.714582135513;8926.642934282685;8936.46381217129;8996.50519221436;9075.909240907506;9101.635934562544;12284.508619655293;12375.814362784566;12445.813312800316;12449.75100497985;12537.247765134121;4534.229181039232;4782.9150198122015;4950;5055.393352797614;5064.18972964324;944.5560586524323;949.8907625623003;971.4941840147743;986.8174387738292;1013.8479228115694;20286.492837679176;20635.96820158986;21614.29821272341;21699.891500542395;22058.227962021254;896.394933419939;995.5514726073545;1004.8693669823023;1007.5163921317809;1024.2267088348399;10771.976662217077;11338.489767960462;11378.829317560243;11395.829062564067;11548.59579914699;521340;525057;528963;529587;529721;140476.29761851125;142289.5771042297;143496.5650343483;143834.2808285973;144874;1143.3368646185193;1153.5443499817525;1222;1240.9317487538453;1249.7188132670076;21076.578468430853;21785.56428871382;23088.26900270071;23148.768512314662;23241.53516929618;52848.73575632097;61013;62707;63549.36450635619;65122.67438662776;82252;82285.58857205676;82600.93497662607;82915.17084829316;85295;10225.977402259714;10348.53431595595;10364.326318789255;10368.133735927304;10377.066064054272;17434.825651743322;18509.444716658523;18733.531661708566;18754.156062976926;18763.15565799515;13279.402426891007;15517.301721422336;15595.454159104376;15710.685786283982;15718.135502547013;13323.200607963554;14098.929505352407;14110.858891410955;14126.646833829236;14156.78764818508;15559.14424706652;15708.68582628364;15709.921450392674;16005.279762410486;16074.67850643004;10178.898211018091;10469.68590942273;10570.2072344576;10572.8942710575;10633.787324253317;15215.543533694006;15257;15304.08175509473;15416.306266218271;15436.691266174837 10:54:55 INFO - PID 1642 | |3;mootools.html;1122.8989390954812;1448.058761804826;1453.5276035288534;1463.6048266967919;1474.9262536873155;11514.827277590832;11932.224405413643;12016.218945768518;12069.974052205562;12312.076594255444;11845.230060046113;11880.82178767317;12022;12061.999999999978;12070.81893771592;2474.901003959844;2538.8984440622353;2541.7712405883467;2543.605741110131;2549.949001019988;2483.217786397288;2521.2184222890965;2538.111660918673;2541.186820217531;2544.122277814151;2489.726130125685;2513.949721005579;2513.949721005579;2514.5222407742563;2517.785988191006;2459.999999999991;2479.938001549967;2486.502699460097;2488.601823708216;2491.1281051631872;2297.3797074789713;2334.7665233476687;2473.418746594549;2480.2559232230437;2481.677381940336;10248.975102489765;11070.889291107067;11614.883851161465;11689.76620467586;11709.590164344207;5194.480551944813;6527.5757075789925;6618.635975021371;6625.569337993015;6640.1035860158745;4057.6753859691153;4211.473565804275;4271.658267338606;4309.095090031097;4313.245182093121;1273.160283390501;1273.4969686973593;1276.961691149267;1298.3832679477266;1298.779207534712;13364;13468.39392227352;16166.27251773673;16322.020678759312;16464.670706585803;5501.697406642593;5558.388577256498;5651.180578816049;5724.370319264877;5740.655560666373;11597.710057248636;11807.291562506278;12315.568955086528;12319.014478841671;12366.072544559014;6380.617162970237;6489.1888513935755;6498.090267362573;6512.120863683437;6527.869442611216;5007.37407824022;5997.970010150008;6027.698615069229;6098.176746139303;6152.661603611755;4356.302991521405;4389.231884420214;4418.270985287391;4434;4449.911001780011;1274.8788865057804;1285.1967520299813;1296.5786119511008;1306.2489068785296;1307.4443361571484;788.4874831359662;798.4769975665948;814.7352110564091;819.6270696832927;842.7640260727006;6192.164057852132;6214.689265536796;6425.710843012076;6475.384838440341;6523.739050437929;569.866081470854;601.6931365003792;613.1201725523798;919.7562645898843;958.2525630008604;3556.5732112146707;4009.7770180094767;4239.830406783756;4257.701960862772;4306.956930430656;14743.189124598035;14841.925790370979;15111.168885711171;15119;15142;12059.397030148457;14909.776353354708;14978.325975331136;15014.774778378332;15017.924910375377;6693.196816381968;7799.727009554638;8352.248297653241;8368.916310836814;8455.619497122521;6134.834381467507;6694.230163531256;6984.930150698631;7024.789256322319;7100.964495177491;11538.711532211762;13007;13194.604161875159;13416.530421435204;13572;11605.477753500943;13696.863031369958;13828.377723002288;13878.653033674238;13938.30308484592;10382.013708697661;10620.09729173029;10804.081653059578;10809.35143891369;10860.294080884718;10989.230753847154;11034.73100593442;11123;11178.832317515407;11182.161337899854;9131.452112873249;9243.630254789734;9301;9380.484073375757;9450.243980481408;8964.3276754245;9029.322800790098;9043.593038313424;9165.17513423795;9286.442813431217;7961.6019199040975;8162.0613629431;8254.752357429286;8262.545559994018;8273.958630207051;6109.2974307955155;6135.447809697149;6150.569460137747;6174.845628859314;6179.84550386244;5705.201271822031;5987.43119403656;6048.274207095177;6136.447719705248;6201.1628430163155 10:54:55 INFO - PID 1642 | |4;prototype.html;387.2622653844426;393.94681717968086;395.4444006171328;396.3579002016733;410.8664683977707;6256.592266739981;6303.810885673432;6309.274433440156;6359.583358323836;6473.5144864135145;6129.87740245196;6222.875542489148;6237.469815065725;6252.218472690914;6287.962486189785;3675.4563083504927;4585.243434833248;4585.679002469828;4624.167649823031;4627.051454451829;4109.2808758467145;4132.628063474287;4588.105319462706;4622.58396744293;4637.791299391535;4542.6138778203895;4544.568266014723;4565.2467342888385;4579.9771001144945;4599.241125214336;4002.899927501807;4567.406237189178;4572.702774319675;4573.725576465423;4624.28323609841;3511.5961664408665;3789.0716774390175;3932.9577661919616;3937.3109705801658;3971.185906889081;4822.926141759042;5102.9489705103215;6164.229471316085;6187.907181392283;6202.224721909762;4580.862574122782;4589.495155532889;5400.810971616014;5465.836024919219;5491.478309560585;5627.915581266284;5827.79602713903;5926.259217597801;6403.807885763435;6742.561733487308;4201.7268877522865;4485.080558485535;4487.573680500347;4488.326750987346;4514.796834142471;6421.903671444886;6821.795346139624;7088.432925365958;7211.567305961659;7236.348728614397;4262.914741705148;4529.689858172356;4537.909241815145;4545;4563.498015218323;4624.44506659203;4638.814447422133;4642.675012749142;4655.883602909887;4674.439067311944;5274.7890084396195;5407.594430417735;5419.539339156137;5434.755436005389;5435.239066530688;6486.189226346707;6654.401103900672;6689.5986240825705;6769.815282325573;6783.321667833177;1447.7321695486369;1588.4996226188714;1599.7040547498518;1613.2498388249521;1618.8425275927768;894.5571941888677;1033.3748082410184;1035.7773078788098;1054.3673795722505;1064.6220591689994;5943.465088142088;6424.775132870421;6434.871302573923;6481.773137940149;6552.180977377828;6315.273743519554;6631.602103873783;7054.765416052273;7057.400120989773;7087.574745515286;5025.698458092526;5508.55931525477;6257.186565746424;6458.773942911976;6463.2244130703675;3427.7429192810637;3662.8168591570316;3663.065918190844;3671.2290419012306;3674.3018826423427;7202.639868006579;7228.34944854955;7228.746993855189;7232.566046037255;7269.382102521345;6510.641914694643;6515.837104072436;6768.69540870662;7213.639318034182;7235.2764723527225;7032.718691252395;7215.989761433509;7217.133943926762;7232.168300645493;7233.927660723325;5550.807431064626;6877.793666189822;6994.733212740326;7038.824029399306;7048.709169520029;5745.396733342939;5901.911471327848;6641.0038494226255;6916.619585922823;6930.7227710891;5808.82573522795;6633.60198388079;6947.93052069493;7201.243869393638;7269.382102521134;4218.7468751874985;4500.662450316174;4543.000539881221;4545.318202269554;4578;6790.913453847361;7010.544314619533;7014.40377567892;7028.402585780266;7047;6495.707693153914;6805.319468053155;6846.863062738617;6876.793696189123;6899.20659124187;5765.308163020464;5824.737886795019;6493.967530162319;6895.4138898194215;6935.526585352521;5959;6657.53397262187;6710.328967103251;6714.791337559286;6732.427743641845;6265.624062556261;6823.351781580742;6841.794746157623;6845.836207844778;6914.723411063501;5991.880162396815;6624;6634.237062737847;6649.567778094409;6680.2651708313015 10:54:55 INFO - PID 1642 | |5;yui.html;721.0374150509067;779.2791667707369;831.9417640765145;864.2455814696169;877.9990810476056;10751.139908807296;14635.19506427146;15965.441209557668;16793;16838.663226735458;16909.661806763856;17437.912810435962;17600.735988960143;17658;17724.734128988075;954.8472244440891;979.2753362511726;986.1765425869407;1011.5448048378222;1020.2297265564489;1020.4132623741341;1022.7187523431041;1022.9079382855541;1023.1916785739294;1026.4713672458652;852.2244757270885;993.7863359377733;1061.1669839176247;1063.1760385701066;1064.074255397805;1039.0907955538903;1054.8365003424444;1058.201058201057;1058.841173823925;1059.4914441068254;763.2329508843632;774.020863607537;781.6482582837689;785.2383188307333;789.6288744290174;16868.915655421766;17438.56403589908;17609.29562817486;17703.5574110647;18137.909310453495;15191.772123418157;17443.215055322413;17628.20673069702;17793.19930603126;17998.37005704794;12842.550510732079;14707.264636768226;14816.555503334917;14904.850951490454;14992;3485.2680937003006;3519.3313270478784;3542.2738338640515;3544.805035723034;3547.1486843157713;146138;149016.25491872578;149507.99999999892;150194;150791.2460437691;13332.333383330795;13434;13448.058635895393;13500.5274815381;13716.451341946211;26254;26420.075297364885;26421.075262365724;26692.59961100585;26770.732292677214;7547.320741133324;7623;7641.923580764233;7644.808879777939;7649.196834332426;3194.4888817789506;3318.883839065621;3535.0278673364514;3692.2615476904725;3706.499622550975;4180.519240287345;4224.9155016899485;4245.596668316505;4260;4273.252180868336;898.7483504618717;934;939.990600094004;944.6032666280179;945.1257586732245;629.6064959400375;644.8682562103478;659.7394029358472;670.3330186464499;694.9687264073128;10062.943390943992;10302.948485257526;11765.647030589096;12325.198862073936;12347.444365003596;642.6593905230235;648.4261428635691;649.1398896462207;660.8513084555937;676.9424598909147;5586.441355864462;5587.497125258665;5841.357450680422;5995.130706047686;6232.439080482778;124305.37847310887;127180;128444;129196.06205906918;131783;111560.44219779012;112821.30768038487;113495;114502;114554.42722786499;91573;94558.0544194563;97694.51152744333;98297;101738.49130754446;11913.225640333698;12238.816417753918;12459.75080498403;12523.874761252271;12614.936925315315;38173.61826381701;44269;44592.33111503265;45038.423655171915;45125.54874451213;51454.48545514497;60519;61224.693876530335;61596.152115436606;62482;16596.917015414845;16908.32366705318;17144.914275428542;17377.65244695074;17389.91305043467;15925.442609508611;18127;18696.252149914395;18708.345207917657;18770.812291876908;17544.210510526802;17658.735118972967;17726.202320895332;17819.46541603754;17935.282588696828;14345.713085738436;16930.32278708887;17051.232694528528;17474.563135921704;17564.473065808048;16662;16739;16863.325466981183;17033.31866725317;17229.74155387645;11638.476268567765;11869.643910682693;11882.643520694393;12049.927268546464;12060.035197184028;12018.33899135521;12912.289824059391;12945.288009159587;12983.41574629125;12997.15518491295 10:54:55 INFO - PID 1642 | __end_tp_report 10:54:55 INFO - PID 1642 | __start_cc_report 10:54:55 INFO - PID 1642 | _x_x_mozilla_cycle_collect,455 10:54:55 INFO - PID 1642 | __end_cc_report 10:54:55 INFO - PID 1642 | __startTimestamp1496339695091__endTimestamp 10:54:55 INFO - PID 1642 | 10:54:55 INFO - PID 1642 | ------- Summary: start ------- 10:54:55 INFO - PID 1642 | Number of tests: 6 10:54:55 INFO - PID 1642 | 10:54:55 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6118.58 Median:6405.90 stddev:758.76 (11.8%) stddev-sans-first:727.78 10:54:55 INFO - PID 1642 | Values: 3284.6 3599.9 3618.1 3634.7 3634.9 5097.5 5525.8 6143.3 6203.7 6204.8 6146.0 6164.2 6168.2 6188.3 6259.4 5383.5 5424.9 5432.5 5447.9 5448.2 4782.9 5524.4 5528.5 5554.9 5557.6 4363.0 4605.3 5406.5 5447.4 5534.6 5436.2 5438.6 5454.3 5466.2 5473.0 5373.0 5382.1 5388.6 5418.4 5445.6 6138.8 6231.3 6234.1 6246.6 6248.9 6447.6 6579.5 6623.4 6657.5 6679.2 6443.2 6784.9 6796.9 6803.3 6809.5 6301.3 6302.6 6303.2 6315.3 6343.3 6716.8 6790.0 6830.8 6914.4 6929.6 6279.4 6474.5 6531.7 6563.0 6569.0 6592.2 6604.5 6639.0 6765.7 6772.6 5332.8 6571.6 6611.3 6651.0 6672.4 6440.1 6526.5 6675.9 6678.5 6682.9 4378.3 5303.7 5328.1 5379.8 5382.4 4738.9 4764.3 4776.3 4834.8 4852.1 5461.8 6271.3 6331.1 6334.8 6348.6 6483.6 6627.0 6656.4 6753.2 6769.5 5785.0 5816.6 6281.5 6317.0 6348.1 4999.4 5971.0 5996.3 5997.9 6009.9 6677.4 6796.3 6825.9 6938.7 6953.1 6699.3 6740.7 6762.7 6782.9 6793.3 6786.6 6959.0 6963.1 6968.9 6980.4 6446.3 6585.4 6597.7 6635.9 6659.7 6627.2 6682.1 6729.6 6742.4 6795.3 6556.4 6565.5 6572.8 6573.7 6573.9 6301.7 6451.6 6463.7 6489.0 6541.2 6309.9 6410.9 6412.9 6415.0 6435.5 6405.9 6465.1 6471.4 6498.5 6523.8 6382.4 6393.6 6394.6 6406.9 6423.4 10:54:55 INFO - PID 1642 | 10:54:55 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:43429.79 Median:33778.58 stddev:74556.97 (220.7%) stddev-sans-first:74697.38 10:54:55 INFO - PID 1642 | Values: 788.9 816.1 838.1 1244.4 1302.3 37561.0 38681.4 38687.0 38699.8 38827.6 39212.4 39531.2 39553.8 39558.0 39652.6 7791.4 8205.0 8411.0 8432.6 8453.4 8281.8 8378.8 8381.5 8401.7 8403.3 8335.7 8347.2 8371.0 8406.9 8416.8 8324.6 8354.4 8390.9 8411.2 8437.7 8171.1 8192.0 8208.5 8235.5 8237.0 36332.0 36469.8 36700.8 36739.4 36814.3 3142.2 3554.1 3580.8 3581.9 3590.0 24036.9 29912.9 30159.0 30319.1 30367.5 2510.9 2792.9 3014.8 3102.0 3120.9 230462.8 245028.0 247609.0 291576.0 292150.0 47371.5 47478.8 47643.5 47779.3 47836.8 49339.0 49553.5 49624.3 49739.8 50004.7 14390.4 14436.1 14572.5 14592.8 14619.0 11632.8 11769.4 11773.6 11794.6 11796.4 11899.0 14061.0 14067.4 14087.3 14173.4 6056.1 6087.6 6131.0 6139.9 6142.6 913.4 926.9 968.6 1010.7 1031.8 24070.5 24350.9 24359.8 24401.8 24404.5 978.8 1061.4 1088.9 1125.9 1145.7 9870.9 12404.1 12415.4 12424.5 12446.3 321905.0 326372.4 326581.0 327102.0 329038.0 43860.8 52182.0 53654.9 53734.5 54062.5 1432.3 1466.7 1499.0 1501.2 1511.3 23326.1 23466.5 23530.8 23542.5 23562.9 36672.4 36899.0 36985.4 37025.5 37082.3 256643.0 259621.0 260807.0 260942.0 262246.0 33802.8 34914.6 35235.3 35260.5 35281.6 43951.3 43986.8 44020.1 44052.3 44112.3 28415.6 29868.6 30693.7 31063.4 32032.9 26029.2 29684.0 29897.9 30698.8 30722.8 32099.5 33169.2 33757.8 33799.3 36995.8 29906.4 30847.8 30950.4 31041.1 31903.4 24422.9 24551.6 24589.5 24862.4 24886.4 10:54:55 INFO - PID 1642 | 10:54:55 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:38039.97 Median:2213.64 stddev:90087.25 (4069.6%) stddev-sans-first:90295.63 10:54:55 INFO - PID 1642 | Values: 396.4 425.1 429.7 437.7 451.5 21994.5 22805.5 22875.5 22937.8 24167.5 23826.8 24286.4 24336.9 24339.0 24393.8 6147.0 6202.0 6276.6 6288.2 6289.6 5931.6 6173.1 6220.2 6220.6 6239.9 6151.3 6221.0 6224.5 6239.0 6243.7 6185.3 6227.1 6230.3 6239.2 6240.3 5558.9 5570.8 5570.9 5581.6 5584.0 28250.6 29126.3 29599.6 29603.7 29642.3 2171.5 2204.0 2210.0 2211.0 2216.3 18550.6 18908.7 18960.0 19072.5 19091.1 2125.3 2162.7 2185.3 2202.7 2206.4 137790.0 138569.0 172469.1 173383.0 176366.2 25794.6 25968.5 26363.9 26493.1 26799.1 43403.8 43422.8 43486.0 43508.8 43569.8 11252.4 11313.8 11356.8 11356.8 11416.7 8926.6 8936.5 8996.5 9075.9 9101.6 12284.5 12375.8 12445.8 12449.8 12537.2 4534.2 4782.9 4950.0 5055.4 5064.2 944.6 949.9 971.5 986.8 1013.8 20286.5 20636.0 21614.3 21699.9 22058.2 896.4 995.6 1004.9 1007.5 1024.2 10772.0 11338.5 11378.8 11395.8 11548.6 521340.0 525057.0 528963.0 529587.0 529721.0 140476.3 142289.6 143496.6 143834.3 144874.0 1143.3 1153.5 1222.0 1240.9 1249.7 21076.6 21785.6 23088.3 23148.8 23241.5 52848.7 61013.0 62707.0 63549.4 65122.7 82252.0 82285.6 82600.9 82915.2 85295.0 10226.0 10348.5 10364.3 10368.1 10377.1 17434.8 18509.4 18733.5 18754.2 18763.2 13279.4 15517.3 15595.5 15710.7 15718.1 13323.2 14098.9 14110.9 14126.6 14156.8 15559.1 15708.7 15709.9 16005.3 16074.7 10178.9 10469.7 10570.2 10572.9 10633.8 15215.5 15257.0 15304.1 15416.3 15436.7 10:54:55 INFO - PID 1642 | 10:54:55 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7077.59 Median:2541.48 stddev:4448.34 (175.0%) stddev-sans-first:4438.31 10:54:55 INFO - PID 1642 | Values: 1122.9 1448.1 1453.5 1463.6 1474.9 11514.8 11932.2 12016.2 12070.0 12312.1 11845.2 11880.8 12022.0 12062.0 12070.8 2474.9 2538.9 2541.8 2543.6 2549.9 2483.2 2521.2 2538.1 2541.2 2544.1 2489.7 2513.9 2513.9 2514.5 2517.8 2460.0 2479.9 2486.5 2488.6 2491.1 2297.4 2334.8 2473.4 2480.3 2481.7 10249.0 11070.9 11614.9 11689.8 11709.6 5194.5 6527.6 6618.6 6625.6 6640.1 4057.7 4211.5 4271.7 4309.1 4313.2 1273.2 1273.5 1277.0 1298.4 1298.8 13364.0 13468.4 16166.3 16322.0 16464.7 5501.7 5558.4 5651.2 5724.4 5740.7 11597.7 11807.3 12315.6 12319.0 12366.1 6380.6 6489.2 6498.1 6512.1 6527.9 5007.4 5998.0 6027.7 6098.2 6152.7 4356.3 4389.2 4418.3 4434.0 4449.9 1274.9 1285.2 1296.6 1306.2 1307.4 788.5 798.5 814.7 819.6 842.8 6192.2 6214.7 6425.7 6475.4 6523.7 569.9 601.7 613.1 919.8 958.3 3556.6 4009.8 4239.8 4257.7 4307.0 14743.2 14841.9 15111.2 15119.0 15142.0 12059.4 14909.8 14978.3 15014.8 15017.9 6693.2 7799.7 8352.2 8368.9 8455.6 6134.8 6694.2 6984.9 7024.8 7101.0 11538.7 13007.0 13194.6 13416.5 13572.0 11605.5 13696.9 13828.4 13878.7 13938.3 10382.0 10620.1 10804.1 10809.4 10860.3 10989.2 11034.7 11123.0 11178.8 11182.2 9131.5 9243.6 9301.0 9380.5 9450.2 8964.3 9029.3 9043.6 9165.2 9286.4 7961.6 8162.1 8254.8 8262.5 8274.0 6109.3 6135.4 6150.6 6174.8 6179.8 5705.2 5987.4 6048.3 6136.4 6201.2 10:54:55 INFO - PID 1642 | 10:54:55 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5419.99 Median:6147.05 stddev:1728.04 (28.1%) stddev-sans-first:1691.10 10:54:55 INFO - PID 1642 | Values: 387.3 393.9 395.4 396.4 410.9 6256.6 6303.8 6309.3 6359.6 6473.5 6129.9 6222.9 6237.5 6252.2 6288.0 3675.5 4585.2 4585.7 4624.2 4627.1 4109.3 4132.6 4588.1 4622.6 4637.8 4542.6 4544.6 4565.2 4580.0 4599.2 4002.9 4567.4 4572.7 4573.7 4624.3 3511.6 3789.1 3933.0 3937.3 3971.2 4822.9 5102.9 6164.2 6187.9 6202.2 4580.9 4589.5 5400.8 5465.8 5491.5 5627.9 5827.8 5926.3 6403.8 6742.6 4201.7 4485.1 4487.6 4488.3 4514.8 6421.9 6821.8 7088.4 7211.6 7236.3 4262.9 4529.7 4537.9 4545.0 4563.5 4624.4 4638.8 4642.7 4655.9 4674.4 5274.8 5407.6 5419.5 5434.8 5435.2 6486.2 6654.4 6689.6 6769.8 6783.3 1447.7 1588.5 1599.7 1613.2 1618.8 894.6 1033.4 1035.8 1054.4 1064.6 5943.5 6424.8 6434.9 6481.8 6552.2 6315.3 6631.6 7054.8 7057.4 7087.6 5025.7 5508.6 6257.2 6458.8 6463.2 3427.7 3662.8 3663.1 3671.2 3674.3 7202.6 7228.3 7228.7 7232.6 7269.4 6510.6 6515.8 6768.7 7213.6 7235.3 7032.7 7216.0 7217.1 7232.2 7233.9 5550.8 6877.8 6994.7 7038.8 7048.7 5745.4 5901.9 6641.0 6916.6 6930.7 5808.8 6633.6 6947.9 7201.2 7269.4 4218.7 4500.7 4543.0 4545.3 4578.0 6790.9 7010.5 7014.4 7028.4 7047.0 6495.7 6805.3 6846.9 6876.8 6899.2 5765.3 5824.7 6494.0 6895.4 6935.5 5959.0 6657.5 6710.3 6714.8 6732.4 6265.6 6823.4 6841.8 6845.8 6914.7 5991.9 6624.0 6634.2 6649.6 6680.3 10:54:55 INFO - PID 1642 | 10:54:55 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:24430.79 Median:17618.75 stddev:37122.39 (210.7%) stddev-sans-first:37183.84 10:54:55 INFO - PID 1642 | Values: 721.0 779.3 831.9 864.2 878.0 10751.1 14635.2 15965.4 16793.0 16838.7 16909.7 17437.9 17600.7 17658.0 17724.7 954.8 979.3 986.2 1011.5 1020.2 1020.4 1022.7 1022.9 1023.2 1026.5 852.2 993.8 1061.2 1063.2 1064.1 1039.1 1054.8 1058.2 1058.8 1059.5 763.2 774.0 781.6 785.2 789.6 16868.9 17438.6 17609.3 17703.6 18137.9 15191.8 17443.2 17628.2 17793.2 17998.4 12842.6 14707.3 14816.6 14904.9 14992.0 3485.3 3519.3 3542.3 3544.8 3547.1 146138.0 149016.3 149508.0 150194.0 150791.2 13332.3 13434.0 13448.1 13500.5 13716.5 26254.0 26420.1 26421.1 26692.6 26770.7 7547.3 7623.0 7641.9 7644.8 7649.2 3194.5 3318.9 3535.0 3692.3 3706.5 4180.5 4224.9 4245.6 4260.0 4273.3 898.7 934.0 940.0 944.6 945.1 629.6 644.9 659.7 670.3 695.0 10062.9 10302.9 11765.6 12325.2 12347.4 642.7 648.4 649.1 660.9 676.9 5586.4 5587.5 5841.4 5995.1 6232.4 124305.4 127180.0 128444.0 129196.1 131783.0 111560.4 112821.3 113495.0 114502.0 114554.4 91573.0 94558.1 97694.5 98297.0 101738.5 11913.2 12238.8 12459.8 12523.9 12614.9 38173.6 44269.0 44592.3 45038.4 45125.5 51454.5 60519.0 61224.7 61596.2 62482.0 16596.9 16908.3 17144.9 17377.7 17389.9 15925.4 18127.0 18696.3 18708.3 18770.8 17544.2 17658.7 17726.2 17819.5 17935.3 14345.7 16930.3 17051.2 17474.6 17564.5 16662.0 16739.0 16863.3 17033.3 17229.7 11638.5 11869.6 11882.6 12049.9 12060.0 12018.3 12912.3 12945.3 12983.4 12997.2 10:54:55 INFO - PID 1642 | -------- Summary: end -------- 10:54:55 INFO - PID 1642 | 10:54:55 INFO - TEST-INFO | 1642: exit 0 10:54:55 INFO - TEST-OK | dromaeo_css | took 1143558ms 10:54:55 INFO - TEST-START | kraken 10:54:55 INFO - operating with platform_type : mac_ 10:54:55 INFO - Initialising browser for kraken test... 10:54:55 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpe9HVsa/profile 10:54:55 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 10:55:29 INFO - TEST-INFO | 1652: killed by SIGTERM 10:55:29 INFO - Browser initialized. 10:55:29 INFO - Running cycle 1/1 for kraken test... 10:55:29 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpe9HVsa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:55:31 INFO - PID 1656 | 2017-06-01 10:55:31.615 plugin-container[1657:10595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 10:55:31 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:31 INFO - PID 1656 | 2017-06-01 10:55:31.616 plugin-container[1657:10595] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 10:55:31 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:31 INFO - PID 1656 | 2017-06-01 10:55:31.616 plugin-container[1657:10595] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:55:32 INFO - PID 1656 | 2017-06-01 10:55:32.362 plugin-container[1658:10641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 10:55:32 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:32 INFO - PID 1656 | 2017-06-01 10:55:32.363 plugin-container[1658:10641] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4627, name = 'com.apple.CFPasteboardClient' 10:55:32 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:32 INFO - PID 1656 | 2017-06-01 10:55:32.363 plugin-container[1658:10641] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:55:41 INFO - PID 1656 | RSS: Main: 260583424 10:55:41 INFO - PID 1656 | 10:55:44 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 10:55:45 INFO - PID 1656 | RSS: Main: 270860288 10:55:45 INFO - PID 1656 | 10:55:47 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 10:55:48 INFO - PID 1656 | RSS: Main: 282423296 10:55:48 INFO - PID 1656 | 10:55:51 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 10:55:52 INFO - PID 1656 | RSS: Main: 284319744 10:55:52 INFO - PID 1656 | 10:55:54 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 10:55:55 INFO - PID 1656 | RSS: Main: 287088640 10:55:55 INFO - PID 1656 | 10:55:56 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:55:57 INFO - PID 1656 | RSS: Main: 287494144 10:55:57 INFO - PID 1656 | 10:56:00 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 10:56:01 INFO - PID 1656 | RSS: Main: 292311040 10:56:01 INFO - PID 1656 | 10:56:04 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 10:56:04 INFO - PID 1656 | RSS: Main: 296460288 10:56:04 INFO - PID 1656 | 10:56:07 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 10:56:08 INFO - PID 1656 | RSS: Main: 294973440 10:56:08 INFO - PID 1656 | 10:56:09 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:56:10 INFO - PID 1656 | RSS: Main: 294260736 10:56:10 INFO - PID 1656 | 10:56:12 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:56:12 INFO - PID 1656 | RSS: Main: 294260736 10:56:12 INFO - PID 1656 | 10:56:15 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:56:15 INFO - PID 1656 | RSS: Main: 294072320 10:56:15 INFO - PID 1656 | 10:56:18 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:56:19 INFO - PID 1656 | RSS: Main: 293695488 10:56:19 INFO - PID 1656 | 10:56:22 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:56:22 INFO - PID 1656 | RSS: Main: 293924864 10:56:22 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 10:56:24 INFO - PID 1656 | RSS: Main: 295563264 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | __start_tp_report 10:56:24 INFO - PID 1656 | _x_x_mozilla_page_load 10:56:24 INFO - PID 1656 | _x_x_mozilla_page_load_details 10:56:24 INFO - PID 1656 | |i|pagename|runs| 10:56:24 INFO - PID 1656 | |0;ai-astar;119;166;118;114;116;116;114;117;116;115 10:56:24 INFO - PID 1656 | |1;audio-beat-detection;126;111;112;147;114;111;108;148;159;110 10:56:24 INFO - PID 1656 | |2;audio-dft;207;203;237;215;210;232;195;197;197;195 10:56:24 INFO - PID 1656 | |3;audio-fft;70;56;58;87;56;59;57;60;74;58 10:56:24 INFO - PID 1656 | |4;audio-oscillator;77;72;85;72;70;73;89;70;69;99 10:56:24 INFO - PID 1656 | |5;imaging-gaussian-blur;90;89;100;89;90;88;89;90;98;88 10:56:24 INFO - PID 1656 | |6;imaging-darkroom;99;97;96;96;156;96;96;98;98;98 10:56:24 INFO - PID 1656 | |7;imaging-desaturate;93;90;91;94;94;90;89;93;91;91 10:56:24 INFO - PID 1656 | |8;json-parse-financial;49;50;49;50;52;52;52;65;65;77 10:56:24 INFO - PID 1656 | |9;json-stringify-tinderbox;56;60;57;75;79;61;56;57;56;53 10:56:24 INFO - PID 1656 | |10;stanford-crypto-aes;87;89;88;85;86;89;94;112;122;99 10:56:24 INFO - PID 1656 | |11;stanford-crypto-ccm;136;137;149;138;135;138;136;139;137;178 10:56:24 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;187;184;188;182;191;186;188;188;185;189 10:56:24 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;68;71;67;64;65;69;66;69;69;69 10:56:24 INFO - PID 1656 | __end_tp_report 10:56:24 INFO - PID 1656 | __start_cc_report 10:56:24 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1193 10:56:24 INFO - PID 1656 | __end_cc_report 10:56:24 INFO - PID 1656 | __startTimestamp1496339784818__endTimestamp 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | ------- Summary: start ------- 10:56:24 INFO - PID 1656 | Number of tests: 14 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:121.10 Median:116.50 stddev:15.86 (13.6%) stddev-sans-first:16.80 10:56:24 INFO - PID 1656 | Values: 119.0 166.0 118.0 114.0 116.0 116.0 114.0 117.0 116.0 115.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:124.60 Median:120.00 stddev:19.33 (16.1%) stddev-sans-first:20.50 10:56:24 INFO - PID 1656 | Values: 126.0 111.0 112.0 147.0 114.0 111.0 108.0 148.0 159.0 110.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:208.80 Median:208.50 stddev:15.16 (7.3%) stddev-sans-first:16.07 10:56:24 INFO - PID 1656 | Values: 207.0 203.0 237.0 215.0 210.0 232.0 195.0 197.0 197.0 195.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:63.50 Median:59.50 stddev:10.29 (17.3%) stddev-sans-first:10.64 10:56:24 INFO - PID 1656 | Values: 70.0 56.0 58.0 87.0 56.0 59.0 57.0 60.0 74.0 58.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:77.60 Median:75.00 stddev:10.09 (13.5%) stddev-sans-first:10.70 10:56:24 INFO - PID 1656 | Values: 77.0 72.0 85.0 72.0 70.0 73.0 89.0 70.0 69.0 99.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:89.50 stddev:4.25 (4.8%) stddev-sans-first:4.49 10:56:24 INFO - PID 1656 | Values: 90.0 89.0 100.0 89.0 90.0 88.0 89.0 90.0 98.0 88.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:103.00 Median:97.50 stddev:18.65 (19.1%) stddev-sans-first:19.73 10:56:24 INFO - PID 1656 | Values: 99.0 97.0 96.0 96.0 156.0 96.0 96.0 98.0 98.0 98.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:92.00 stddev:1.78 (1.9%) stddev-sans-first:1.81 10:56:24 INFO - PID 1656 | Values: 93.0 90.0 91.0 94.0 94.0 90.0 89.0 93.0 91.0 91.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:52.00 stddev:9.55 (18.4%) stddev-sans-first:9.78 10:56:24 INFO - PID 1656 | Values: 49.0 50.0 49.0 50.0 52.0 52.0 52.0 65.0 65.0 77.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:61.00 Median:58.50 stddev:8.77 (15.0%) stddev-sans-first:9.11 10:56:24 INFO - PID 1656 | Values: 56.0 60.0 57.0 75.0 79.0 61.0 56.0 57.0 56.0 53.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:95.10 Median:88.50 stddev:12.48 (14.1%) stddev-sans-first:12.88 10:56:24 INFO - PID 1656 | Values: 87.0 89.0 88.0 85.0 86.0 89.0 94.0 112.0 122.0 99.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:142.30 Median:138.00 stddev:13.15 (9.5%) stddev-sans-first:13.75 10:56:24 INFO - PID 1656 | Values: 136.0 137.0 149.0 138.0 135.0 138.0 136.0 139.0 137.0 178.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.80 Median:188.00 stddev:2.62 (1.4%) stddev-sans-first:2.77 10:56:24 INFO - PID 1656 | Values: 187.0 184.0 188.0 182.0 191.0 186.0 188.0 188.0 185.0 189.0 10:56:24 INFO - PID 1656 | 10:56:24 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:69.00 stddev:2.16 (3.1%) stddev-sans-first:2.29 10:56:24 INFO - PID 1656 | Values: 68.0 71.0 67.0 64.0 65.0 69.0 66.0 69.0 69.0 69.0 10:56:24 INFO - PID 1656 | -------- Summary: end -------- 10:56:24 INFO - PID 1656 | 10:56:25 INFO - TEST-INFO | 1656: exit 0 10:56:25 INFO - TEST-OK | kraken | took 89984ms 10:56:25 INFO - SUITE-END | took 1233s 10:56:26 INFO - Completed test suite (00:20:33) 10:56:26 INFO - javascript benchmark 10:56:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8126.455385724065, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3284.622268439129, 3599.8740044098445, 3618.1316484043837, 3634.67287944085, 3634.8546058157667, 5097.490250974901, 5525.839573689525, 6143.293521245058, 6203.720832562534, 6204.844878878019, 6146.016637338027, 6164.167837341957, 6168.198134242553, 6188.288346840103, 6259.374062593738, 5383.488568585978, 5424.91862622059, 5432.483914028161, 5447.945520544784, 5448.237246785453, 4782.852115492292, 5524.364698059714, 5528.530074943635, 5554.944450555483, 5557.5831812614015, 4362.978185109086, 4605.263157894736, 5406.459354064601, 5447.373552041487, 5534.584906132016, 5436.21174929637, 5438.646487978269, 5454.34547854256, 5466.180072989044, 5472.972635136839, 5372.973135134338, 5382.085045542267, 5388.568914486832, 5418.431064738224, 5445.591580631469, 6138.756267604225, 6231.3145553989025, 6234.127222188898, 6246.6252024878195, 6248.87502249957, 6447.6131432114225, 6579.506537009695, 6623.437007854338, 6657.533972621918, 6679.231888332855, 6443.226812782449, 6784.932150678479, 6796.898046529306, 6803.2856550062525, 6809.455243580551, 6301.275353334379, 6302.558820882586, 6303.212098487735, 6315.273743519509, 6343.270523889812, 6716.764913228014, 6790.0, 6830.7609233676585, 6914.412274956685, 6929.618870962045, 6279.434850863353, 6474.546781725328, 6531.673416329259, 6563.0, 6568.981807819796, 6592.17597800275, 6604.471642268607, 6639.03733958583, 6765.661716914135, 6772.627505487246, 5332.773462103738, 6571.57284776488, 6611.272759996396, 6651.0, 6672.399484046459, 6440.0661904023655, 6526.477881769447, 6675.86648267032, 6678.49911256648, 6682.899756503656, 4378.299472084451, 5303.655262407931, 5328.0675881720545, 5379.811706590251, 5382.407935127133, 4738.881527961828, 4764.309175169581, 4776.283557466339, 4834.7824347904425, 4852.078105160008, 5461.781528738886, 6271.27880293763, 6331.113644089831, 6334.8416289592205, 6348.555601107878, 6483.578567393199, 6626.966865165643, 6656.434203092695, 6753.223379311344, 6769.458443324522, 5784.971075144597, 5816.6330912236435, 6281.528885333527, 6317.0, 6348.111264422984, 4999.350084488993, 5970.970145149333, 5996.2504686914135, 5997.910031349533, 6009.939900600938, 6677.3656502632175, 6796.252412234551, 6825.863482730417, 6938.722451102, 6953.130858642669, 6699.330066993261, 6740.730370785113, 6762.661866906733, 6782.93217067823, 6793.286704895915, 6786.59280443175, 6958.965205173941, 6963.09479767627, 6968.860622787617, 6980.441564674916, 6446.29090800021, 6585.407313341822, 6597.703103360456, 6635.933640663725, 6659.700313485808, 6627.204735431779, 6682.131322927989, 6729.596224226562, 6742.359475849787, 6795.25252222265, 6556.409923106943, 6565.474762019123, 6572.8356791080605, 6573.704183311666, 6573.868522629425, 6301.7164227608955, 6451.612903225821, 6463.676816159267, 6488.967555162194, 6541.2477565078725, 6309.873802523832, 6410.871782564416, 6412.935870641234, 6415.0, 6435.485161187187, 6405.903911441425, 6465.127207826883, 6471.352864713491, 6498.480121590167, 6523.7716679915975, 6382.36176382358, 6393.6483493408305, 6394.616323020634, 6406.871862562816, 6423.389777971085], "value": 6067.7735671674, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [788.8816677498374, 816.1185919207255, 838.0530001098759, 1244.359155035157, 1302.316283950925, 37561.0, 38681.419778703304, 38687.0, 38699.80650096753, 38827.61172388275, 39212.411813822815, 39531.20937581247, 39553.80223098888, 39558.0, 39652.603473965326, 7791.415643826722, 8205.01539815223, 8410.957945210266, 8432.578371081452, 8453.408261421702, 8281.751547453556, 8378.832423351529, 8381.539015354154, 8401.663933442655, 8403.327733781283, 8335.66657333706, 8347.165283471664, 8370.958145209295, 8406.87389689152, 8416.83166336673, 8324.62539185738, 8354.373421993345, 8390.874136887951, 8411.15888411157, 8437.746867593982, 8171.101178870379, 8192.0, 8208.548529830858, 8235.50586964778, 8237.0, 36332.0, 36469.81765091184, 36700.816495917614, 36739.4489082664, 36814.26371472582, 3142.245860993368, 3554.129238336624, 3580.785152890809, 3581.87463438781, 3589.9768565958575, 24036.879815600983, 29912.8504357479, 30159.0, 30319.090427287218, 30367.544486832714, 2510.8870100845324, 2792.8968057617126, 3014.849257537114, 3101.984490077558, 3120.8595613197463, 230462.8476857605, 245028.0, 247609.0, 291576.0, 292149.99999999785, 47371.52628473671, 47478.76260618675, 47643.523564764604, 47779.28331075037, 47836.760816195696, 49339.0, 49553.50446495561, 49624.255636165486, 49739.75130124326, 50004.74997625061, 14390.42438302477, 14436.133831969906, 14572.489962851248, 14592.781108283385, 14619.049761765451, 11632.825507617392, 11769.411529423494, 11773.587924422603, 11794.646160615195, 11796.351200684045, 11899.048076153887, 14061.01572889888, 14067.366968486444, 14087.295635218197, 14173.433062677377, 6056.06131049688, 6087.634741915499, 6130.969345153246, 6139.907901381483, 6142.6314421134875, 913.4291068082448, 926.8923636254746, 968.6125549780004, 1010.6664800615779, 1031.783325501638, 24070.51858962811, 24350.878245609012, 24359.75640243575, 24401.755982439947, 24404.511909761706, 978.8238117138841, 1061.3737894642045, 1088.9346639201674, 1125.859267591551, 1145.73075327298, 9870.851937220947, 12404.077368007973, 12415.379231038412, 12424.503019879285, 12446.315452650191, 321905.0, 326372.36813815776, 326581.0, 327102.0, 329038.0, 43860.78069609631, 52181.95636087257, 53654.92690146175, 53734.462655373725, 54062.45937540653, 1432.2767002663797, 1466.65533599604, 1498.977515337271, 1501.2493753123438, 1511.2670354877876, 23326.066957322197, 23466.53066938686, 23530.76469235286, 23542.52914941726, 23562.882185588962, 36672.44991325079, 36899.0, 36985.445218321205, 37025.518979241606, 37082.258354832215, 256643.0, 259621.0, 260807.0, 260942.0, 262246.0, 33802.830985844914, 34914.6034158641, 35235.29529409449, 35260.47109293311, 35281.64718352886, 43951.34072988844, 43986.78006609946, 44020.11959760851, 44052.33921491244, 44112.33831492466, 28415.573766393933, 29868.551971720008, 30693.693063069975, 31063.378732425677, 32032.878849240165, 26029.219123426326, 29684.0, 29897.850510747307, 30698.846505767327, 30722.846385767927, 32099.518507221943, 33169.170770730925, 33757.83121084379, 33799.32401352008, 36995.815020924725, 29906.401871962003, 30847.84576077105, 30950.380992380477, 31041.068767937, 31903.36193276168, 24422.877885611168, 24551.649659268198, 24589.50820983606, 24862.37844053913, 24886.38238514491], "value": 17270.306654512893, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.397475836728, 425.143336177602, 429.73571253678995, 437.684866895835, 451.4898165073467, 21994.45013874654, 22805.5176413533, 22875.542489150226, 22937.77062229377, 24167.516649666995, 23826.808659566996, 24286.392840179004, 24336.878315608443, 24339.026438942423, 24393.75606243937, 6146.969265153679, 6202.038684003986, 6276.623402595859, 6288.213973253343, 6289.622622642633, 5931.555133364994, 6173.135760993465, 6220.222472190976, 6220.595661282025, 6239.906401403982, 6151.323354431009, 6221.0, 6224.502039836825, 6239.032949892774, 6243.719032643519, 6185.257769067718, 6227.06594010902, 6230.314665386803, 6239.2200974877915, 6240.282367527747, 5558.944410555923, 5570.777168913279, 5570.916436253459, 5581.553475721932, 5584.0, 28250.576241356397, 29126.27184320388, 29599.556006659917, 29603.703962960524, 29642.25894352637, 2171.457135716071, 2203.9559208815895, 2209.9779002209934, 2210.9778902210933, 2216.3018649125474, 18550.628987420176, 18908.716369254336, 18960.0, 19072.523186920298, 19091.140898659592, 2125.3092744857827, 2162.664786958024, 2185.311626837557, 2202.7466841313294, 2206.4483879030245, 137790.0, 138569.0, 172469.1376543122, 173383.0, 176366.23633763628, 25794.613080803803, 25968.480630387665, 26363.868180658974, 26493.072742454307, 26799.06203282876, 43403.78298108489, 43422.78288608537, 43486.0, 43508.78245608815, 43569.782151089676, 11252.381119038531, 11313.83029254562, 11356.772864542829, 11356.829647555292, 11416.714582135513, 8926.642934282685, 8936.46381217129, 8996.50519221436, 9075.909240907506, 9101.635934562544, 12284.508619655293, 12375.814362784566, 12445.813312800316, 12449.75100497985, 12537.247765134121, 4534.229181039232, 4782.9150198122015, 4950.0, 5055.393352797614, 5064.18972964324, 944.5560586524323, 949.8907625623003, 971.4941840147743, 986.8174387738292, 1013.8479228115694, 20286.492837679176, 20635.96820158986, 21614.29821272341, 21699.891500542395, 22058.227962021254, 896.394933419939, 995.5514726073545, 1004.8693669823023, 1007.5163921317809, 1024.2267088348399, 10771.976662217077, 11338.489767960462, 11378.829317560243, 11395.829062564067, 11548.59579914699, 521340.0, 525057.0, 528963.0, 529587.0, 529721.0, 140476.29761851125, 142289.5771042297, 143496.5650343483, 143834.2808285973, 144874.0, 1143.3368646185193, 1153.5443499817525, 1222.0, 1240.9317487538453, 1249.7188132670076, 21076.578468430853, 21785.56428871382, 23088.26900270071, 23148.768512314662, 23241.53516929618, 52848.73575632097, 61013.0, 62707.0, 63549.36450635619, 65122.67438662776, 82252.0, 82285.58857205676, 82600.93497662607, 82915.17084829316, 85295.0, 10225.977402259714, 10348.53431595595, 10364.326318789255, 10368.133735927304, 10377.066064054272, 17434.825651743322, 18509.444716658523, 18733.531661708566, 18754.156062976926, 18763.15565799515, 13279.402426891007, 15517.301721422336, 15595.454159104376, 15710.685786283982, 15718.135502547013, 13323.200607963554, 14098.929505352407, 14110.858891410955, 14126.646833829236, 14156.78764818508, 15559.14424706652, 15708.68582628364, 15709.921450392674, 16005.279762410486, 16074.67850643004, 10178.898211018091, 10469.68590942273, 10570.2072344576, 10572.8942710575, 10633.787324253317, 15215.543533694006, 15257.0, 15304.08175509473, 15416.306266218271, 15436.691266174837], "value": 12240.338511782402, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1122.8989390954812, 1448.058761804826, 1453.5276035288534, 1463.6048266967919, 1474.9262536873155, 11514.827277590832, 11932.224405413643, 12016.218945768518, 12069.974052205562, 12312.076594255444, 11845.230060046113, 11880.82178767317, 12022.0, 12061.999999999978, 12070.81893771592, 2474.901003959844, 2538.8984440622353, 2541.7712405883467, 2543.605741110131, 2549.949001019988, 2483.217786397288, 2521.2184222890965, 2538.111660918673, 2541.186820217531, 2544.122277814151, 2489.726130125685, 2513.949721005579, 2513.949721005579, 2514.5222407742563, 2517.785988191006, 2459.999999999991, 2479.938001549967, 2486.502699460097, 2488.601823708216, 2491.1281051631872, 2297.3797074789713, 2334.7665233476687, 2473.418746594549, 2480.2559232230437, 2481.677381940336, 10248.975102489765, 11070.889291107067, 11614.883851161465, 11689.76620467586, 11709.590164344207, 5194.480551944813, 6527.5757075789925, 6618.635975021371, 6625.569337993015, 6640.1035860158745, 4057.6753859691153, 4211.473565804275, 4271.658267338606, 4309.095090031097, 4313.245182093121, 1273.160283390501, 1273.4969686973593, 1276.961691149267, 1298.3832679477266, 1298.779207534712, 13364.0, 13468.39392227352, 16166.27251773673, 16322.020678759312, 16464.670706585803, 5501.697406642593, 5558.388577256498, 5651.180578816049, 5724.370319264877, 5740.655560666373, 11597.710057248636, 11807.291562506278, 12315.568955086528, 12319.014478841671, 12366.072544559014, 6380.617162970237, 6489.1888513935755, 6498.090267362573, 6512.120863683437, 6527.869442611216, 5007.37407824022, 5997.970010150008, 6027.698615069229, 6098.176746139303, 6152.661603611755, 4356.302991521405, 4389.231884420214, 4418.270985287391, 4434.0, 4449.911001780011, 1274.8788865057804, 1285.1967520299813, 1296.5786119511008, 1306.2489068785296, 1307.4443361571484, 788.4874831359662, 798.4769975665948, 814.7352110564091, 819.6270696832927, 842.7640260727006, 6192.164057852132, 6214.689265536796, 6425.710843012076, 6475.384838440341, 6523.739050437929, 569.866081470854, 601.6931365003792, 613.1201725523798, 919.7562645898843, 958.2525630008604, 3556.5732112146707, 4009.7770180094767, 4239.830406783756, 4257.701960862772, 4306.956930430656, 14743.189124598035, 14841.925790370979, 15111.168885711171, 15119.0, 15142.0, 12059.397030148457, 14909.776353354708, 14978.325975331136, 15014.774778378332, 15017.924910375377, 6693.196816381968, 7799.727009554638, 8352.248297653241, 8368.916310836814, 8455.619497122521, 6134.834381467507, 6694.230163531256, 6984.930150698631, 7024.789256322319, 7100.964495177491, 11538.711532211762, 13007.0, 13194.604161875159, 13416.530421435204, 13572.0, 11605.477753500943, 13696.863031369958, 13828.377723002288, 13878.653033674238, 13938.30308484592, 10382.013708697661, 10620.09729173029, 10804.081653059578, 10809.35143891369, 10860.294080884718, 10989.230753847154, 11034.73100593442, 11123.0, 11178.832317515407, 11182.161337899854, 9131.452112873249, 9243.630254789734, 9301.0, 9380.484073375757, 9450.243980481408, 8964.3276754245, 9029.322800790098, 9043.593038313424, 9165.17513423795, 9286.442813431217, 7961.6019199040975, 8162.0613629431, 8254.752357429286, 8262.545559994018, 8273.958630207051, 6109.2974307955155, 6135.447809697149, 6150.569460137747, 6174.845628859314, 6179.84550386244, 5705.201271822031, 5987.43119403656, 6048.274207095177, 6136.447719705248, 6201.1628430163155], "value": 5342.020640723143, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.2622653844426, 393.94681717968086, 395.4444006171328, 396.3579002016733, 410.8664683977707, 6256.592266739981, 6303.810885673432, 6309.274433440156, 6359.583358323836, 6473.5144864135145, 6129.87740245196, 6222.875542489148, 6237.469815065725, 6252.218472690914, 6287.962486189785, 3675.4563083504927, 4585.243434833248, 4585.679002469828, 4624.167649823031, 4627.051454451829, 4109.2808758467145, 4132.628063474287, 4588.105319462706, 4622.58396744293, 4637.791299391535, 4542.6138778203895, 4544.568266014723, 4565.2467342888385, 4579.9771001144945, 4599.241125214336, 4002.899927501807, 4567.406237189178, 4572.702774319675, 4573.725576465423, 4624.28323609841, 3511.5961664408665, 3789.0716774390175, 3932.9577661919616, 3937.3109705801658, 3971.185906889081, 4822.926141759042, 5102.9489705103215, 6164.229471316085, 6187.907181392283, 6202.224721909762, 4580.862574122782, 4589.495155532889, 5400.810971616014, 5465.836024919219, 5491.478309560585, 5627.915581266284, 5827.79602713903, 5926.259217597801, 6403.807885763435, 6742.561733487308, 4201.7268877522865, 4485.080558485535, 4487.573680500347, 4488.326750987346, 4514.796834142471, 6421.903671444886, 6821.795346139624, 7088.432925365958, 7211.567305961659, 7236.348728614397, 4262.914741705148, 4529.689858172356, 4537.909241815145, 4545.0, 4563.498015218323, 4624.44506659203, 4638.814447422133, 4642.675012749142, 4655.883602909887, 4674.439067311944, 5274.7890084396195, 5407.594430417735, 5419.539339156137, 5434.755436005389, 5435.239066530688, 6486.189226346707, 6654.401103900672, 6689.5986240825705, 6769.815282325573, 6783.321667833177, 1447.7321695486369, 1588.4996226188714, 1599.7040547498518, 1613.2498388249521, 1618.8425275927768, 894.5571941888677, 1033.3748082410184, 1035.7773078788098, 1054.3673795722505, 1064.6220591689994, 5943.465088142088, 6424.775132870421, 6434.871302573923, 6481.773137940149, 6552.180977377828, 6315.273743519554, 6631.602103873783, 7054.765416052273, 7057.400120989773, 7087.574745515286, 5025.698458092526, 5508.55931525477, 6257.186565746424, 6458.773942911976, 6463.2244130703675, 3427.7429192810637, 3662.8168591570316, 3663.065918190844, 3671.2290419012306, 3674.3018826423427, 7202.639868006579, 7228.34944854955, 7228.746993855189, 7232.566046037255, 7269.382102521345, 6510.641914694643, 6515.837104072436, 6768.69540870662, 7213.639318034182, 7235.2764723527225, 7032.718691252395, 7215.989761433509, 7217.133943926762, 7232.168300645493, 7233.927660723325, 5550.807431064626, 6877.793666189822, 6994.733212740326, 7038.824029399306, 7048.709169520029, 5745.396733342939, 5901.911471327848, 6641.0038494226255, 6916.619585922823, 6930.7227710891, 5808.82573522795, 6633.60198388079, 6947.93052069493, 7201.243869393638, 7269.382102521134, 4218.7468751874985, 4500.662450316174, 4543.000539881221, 4545.318202269554, 4578.0, 6790.913453847361, 7010.544314619533, 7014.40377567892, 7028.402585780266, 7047.0, 6495.707693153914, 6805.319468053155, 6846.863062738617, 6876.793696189123, 6899.20659124187, 5765.308163020464, 5824.737886795019, 6493.967530162319, 6895.4138898194215, 6935.526585352521, 5959.0, 6657.53397262187, 6710.328967103251, 6714.791337559286, 6732.427743641845, 6265.624062556261, 6823.351781580742, 6841.794746157623, 6845.836207844778, 6914.723411063501, 5991.880162396815, 6624.0, 6634.237062737847, 6649.567778094409, 6680.2651708313015], "value": 4877.643472555014, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.0374150509067, 779.2791667707369, 831.9417640765145, 864.2455814696169, 877.9990810476056, 10751.139908807296, 14635.19506427146, 15965.441209557668, 16793.0, 16838.663226735458, 16909.661806763856, 17437.912810435962, 17600.735988960143, 17658.0, 17724.734128988075, 954.8472244440891, 979.2753362511726, 986.1765425869407, 1011.5448048378222, 1020.2297265564489, 1020.4132623741341, 1022.7187523431041, 1022.9079382855541, 1023.1916785739294, 1026.4713672458652, 852.2244757270885, 993.7863359377733, 1061.1669839176247, 1063.1760385701066, 1064.074255397805, 1039.0907955538903, 1054.8365003424444, 1058.201058201057, 1058.841173823925, 1059.4914441068254, 763.2329508843632, 774.020863607537, 781.6482582837689, 785.2383188307333, 789.6288744290174, 16868.915655421766, 17438.56403589908, 17609.29562817486, 17703.5574110647, 18137.909310453495, 15191.772123418157, 17443.215055322413, 17628.20673069702, 17793.19930603126, 17998.37005704794, 12842.550510732079, 14707.264636768226, 14816.555503334917, 14904.850951490454, 14992.0, 3485.2680937003006, 3519.3313270478784, 3542.2738338640515, 3544.805035723034, 3547.1486843157713, 146138.0, 149016.25491872578, 149507.99999999892, 150194.0, 150791.2460437691, 13332.333383330795, 13434.0, 13448.058635895393, 13500.5274815381, 13716.451341946211, 26254.0, 26420.075297364885, 26421.075262365724, 26692.59961100585, 26770.732292677214, 7547.320741133324, 7623.0, 7641.923580764233, 7644.808879777939, 7649.196834332426, 3194.4888817789506, 3318.883839065621, 3535.0278673364514, 3692.2615476904725, 3706.499622550975, 4180.519240287345, 4224.9155016899485, 4245.596668316505, 4260.0, 4273.252180868336, 898.7483504618717, 934.0, 939.990600094004, 944.6032666280179, 945.1257586732245, 629.6064959400375, 644.8682562103478, 659.7394029358472, 670.3330186464499, 694.9687264073128, 10062.943390943992, 10302.948485257526, 11765.647030589096, 12325.198862073936, 12347.444365003596, 642.6593905230235, 648.4261428635691, 649.1398896462207, 660.8513084555937, 676.9424598909147, 5586.441355864462, 5587.497125258665, 5841.357450680422, 5995.130706047686, 6232.439080482778, 124305.37847310887, 127180.0, 128444.0, 129196.06205906918, 131783.0, 111560.44219779012, 112821.30768038487, 113495.0, 114502.0, 114554.42722786499, 91573.0, 94558.0544194563, 97694.51152744333, 98297.0, 101738.49130754446, 11913.225640333698, 12238.816417753918, 12459.75080498403, 12523.874761252271, 12614.936925315315, 38173.61826381701, 44269.0, 44592.33111503265, 45038.423655171915, 45125.54874451213, 51454.48545514497, 60519.0, 61224.693876530335, 61596.152115436606, 62482.0, 16596.917015414845, 16908.32366705318, 17144.914275428542, 17377.65244695074, 17389.91305043467, 15925.442609508611, 18127.0, 18696.252149914395, 18708.345207917657, 18770.812291876908, 17544.210510526802, 17658.735118972967, 17726.202320895332, 17819.46541603754, 17935.282588696828, 14345.713085738436, 16930.32278708887, 17051.232694528528, 17474.563135921704, 17564.473065808048, 16662.0, 16739.0, 16863.325466981183, 17033.31866725317, 17229.74155387645, 11638.476268567765, 11869.643910682693, 11882.643520694393, 12049.927268546464, 12060.035197184028, 12018.33899135521, 12912.289824059391, 12945.288009159587, 12983.41574629125, 12997.15518491295], "value": 8616.605226999529, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1490.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 166.0, 118.0, 114.0, 116.0, 116.0, 114.0, 117.0, 116.0, 115.0], "value": 121.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 111.0, 112.0, 147.0, 114.0, 111.0, 108.0, 148.0, 159.0, 110.0], "value": 124.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 203.0, 237.0, 215.0, 210.0, 232.0, 195.0, 197.0, 197.0, 195.0], "value": 208.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 56.0, 58.0, 87.0, 56.0, 59.0, 57.0, 60.0, 74.0, 58.0], "value": 63.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 85.0, 72.0, 70.0, 73.0, 89.0, 70.0, 69.0, 99.0], "value": 77.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 100.0, 89.0, 90.0, 88.0, 89.0, 90.0, 98.0, 88.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 96.0, 96.0, 156.0, 96.0, 96.0, 98.0, 98.0, 98.0], "value": 103.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 91.0, 94.0, 94.0, 90.0, 89.0, 93.0, 91.0, 91.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 50.0, 52.0, 52.0, 52.0, 65.0, 65.0, 77.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 60.0, 57.0, 75.0, 79.0, 61.0, 56.0, 57.0, 56.0, 53.0], "value": 61.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 88.0, 85.0, 86.0, 89.0, 94.0, 112.0, 122.0, 99.0], "value": 95.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 137.0, 149.0, 138.0, 135.0, 138.0, 136.0, 139.0, 137.0, 178.0], "value": 142.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 188.0, 182.0, 191.0, 186.0, 188.0, 188.0, 185.0, 189.0], "value": 186.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 67.0, 64.0, 65.0, 69.0, 66.0, 69.0, 69.0, 69.0], "value": 67.7, "unit": "score"}]}]} 10:56:26 INFO - Return code: 0 10:56:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:56:26 INFO - # TBPL SUCCESS # 10:56:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:56:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:56:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:56:26 INFO - 2017-06-01 10:56:26.205 screenresolution[1664:11025] starting screenresolution argv=screenresolution get 10:56:26 INFO - 2017-06-01 10:56:26.208 screenresolution[1664:11025] Display 0: 1600x1200x32@60 10:56:26 INFO - 2017-06-01 10:56:26.214 screenresolution[1665:11028] starting screenresolution argv=screenresolution list 10:56:26 INFO - Available Modes on Display 0 10:56:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:56:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:56:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:56:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:56:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:56:26 INFO - Intel Iris: 10:56:26 INFO - Chipset Model: Intel Iris 10:56:26 INFO - Type: GPU 10:56:26 INFO - Bus: Built-In 10:56:26 INFO - VRAM (Dynamic, Max): 1536 MB 10:56:26 INFO - Vendor: Intel (0x8086) 10:56:26 INFO - Device ID: 0x0a2e 10:56:26 INFO - Revision ID: 0x0009 10:56:26 INFO - Displays: 10:56:26 INFO - Display: 10:56:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:56:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:56:26 INFO - Display Serial Number: 1600x1200 60 10:56:26 INFO - Main Display: Yes 10:56:26 INFO - Mirror: Off 10:56:26 INFO - Online: Yes 10:56:26 INFO - Rotation: Supported 10:56:26 INFO - Return code: 0 10:56:26 INFO - Running post-action listener: _resource_record_post_action 10:56:26 INFO - [mozharness: 2017-06-01 17:56:26.447948Z] Finished run-tests step (success) 10:56:26 INFO - Running post-run listener: _resource_record_post_run 10:56:26 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 47418368; Write bytes: 572120576; Read time: 513; Write time: 1869 10:56:26 INFO - TinderboxPrint: CPU usage
53.3% 10:56:26 INFO - TinderboxPrint: I/O read bytes / time
47,418,368 / 513 10:56:26 INFO - TinderboxPrint: I/O write bytes / time
572,120,576 / 1,869 10:56:26 INFO - TinderboxPrint: CPU idle
2,347.8 (46.7%) 10:56:26 INFO - TinderboxPrint: CPU system
1,017.5 (20.2%) 10:56:26 INFO - TinderboxPrint: CPU user
1,660.4 (33.0%) 10:56:26 INFO - TinderboxPrint: Swap in / out
287,617,024 / 0 10:56:26 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 226451968; Write bytes: 220767744; Read time: 16788; Write time: 461 10:56:26 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:56:26 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 46377984; Write bytes: 323020800; Read time: 495; Write time: 1340 10:56:26 INFO - Running post-run listener: _upload_blobber_files 10:56:26 INFO - Blob upload gear active. 10:56:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:56:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:56:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:56:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:56:27 INFO - (blobuploader) - INFO - Open directory for files ... 10:56:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:56:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:56:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:56:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:56:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:56:28 INFO - (blobuploader) - INFO - Done attempting. 10:56:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:56:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:56:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:56:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:56:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:56:29 INFO - (blobuploader) - INFO - Done attempting. 10:56:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:56:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:56:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:56:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:56:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:56:30 INFO - (blobuploader) - INFO - Done attempting. 10:56:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:56:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:56:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:56:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:56:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:56:32 INFO - (blobuploader) - INFO - Done attempting. 10:56:32 INFO - (blobuploader) - INFO - Iteration through files over. 10:56:32 INFO - Return code: 0 10:56:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:56:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:56:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a99f3713a3ac949b8d1a02e492990bc620a4f6057b3a81de602dcfaae48cf1617773b4491479e6c31732909ad53c083abade94415e10d02559b7110590234350", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71df50beb418722f90ca478614ed548b8b2b9153685bfd18753c6947e2d87b0b4caf4894f05421f4b869f4e02af8e97d91d563a9065a340e1c888e3a093281be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ecb900ec1db3099547e90e47fe24c1b5920b5c5d03cbd6ca2a88341f6affb07f21d81659976a8841b401ae8a26a166d69ff135a895c1f138cd3b8c881e4fabd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3698772beabf8c711a6db98af94f257d4410a6e43dfcc80a2976aad7c88b0b1c738628d401ddbd8b08704d03f0b62bcf36f2c5e9c35b005e93c0152939876010"} 10:56:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:56:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:56:32 INFO - Contents: 10:56:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a99f3713a3ac949b8d1a02e492990bc620a4f6057b3a81de602dcfaae48cf1617773b4491479e6c31732909ad53c083abade94415e10d02559b7110590234350", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71df50beb418722f90ca478614ed548b8b2b9153685bfd18753c6947e2d87b0b4caf4894f05421f4b869f4e02af8e97d91d563a9065a340e1c888e3a093281be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ecb900ec1db3099547e90e47fe24c1b5920b5c5d03cbd6ca2a88341f6affb07f21d81659976a8841b401ae8a26a166d69ff135a895c1f138cd3b8c881e4fabd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3698772beabf8c711a6db98af94f257d4410a6e43dfcc80a2976aad7c88b0b1c738628d401ddbd8b08704d03f0b62bcf36f2c5e9c35b005e93c0152939876010"} 10:56:32 INFO - Running post-run listener: copy_logs_to_upload_dir 10:56:32 INFO - Copying logs to upload dir... 10:56:32 INFO - mkdir: /builds/slave/test/build/upload/logs 10:56:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.405018 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-01 10:56:32.690444) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 10:56:32.694851) ========= 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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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/a99f3713a3ac949b8d1a02e492990bc620a4f6057b3a81de602dcfaae48cf1617773b4491479e6c31732909ad53c083abade94415e10d02559b7110590234350", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71df50beb418722f90ca478614ed548b8b2b9153685bfd18753c6947e2d87b0b4caf4894f05421f4b869f4e02af8e97d91d563a9065a340e1c888e3a093281be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ecb900ec1db3099547e90e47fe24c1b5920b5c5d03cbd6ca2a88341f6affb07f21d81659976a8841b401ae8a26a166d69ff135a895c1f138cd3b8c881e4fabd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3698772beabf8c711a6db98af94f257d4410a6e43dfcc80a2976aad7c88b0b1c738628d401ddbd8b08704d03f0b62bcf36f2c5e9c35b005e93c0152939876010"} build_url:https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010959 build_url: 'https://queue.taskcluster.net/v1/task/dFotKEGeTROhmnwrwTTe-g/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/a99f3713a3ac949b8d1a02e492990bc620a4f6057b3a81de602dcfaae48cf1617773b4491479e6c31732909ad53c083abade94415e10d02559b7110590234350", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/71df50beb418722f90ca478614ed548b8b2b9153685bfd18753c6947e2d87b0b4caf4894f05421f4b869f4e02af8e97d91d563a9065a340e1c888e3a093281be", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ecb900ec1db3099547e90e47fe24c1b5920b5c5d03cbd6ca2a88341f6affb07f21d81659976a8841b401ae8a26a166d69ff135a895c1f138cd3b8c881e4fabd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3698772beabf8c711a6db98af94f257d4410a6e43dfcc80a2976aad7c88b0b1c738628d401ddbd8b08704d03f0b62bcf36f2c5e9c35b005e93c0152939876010"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 10:56:32.737383) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:56:32.738024) ========= 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.uOkkqGihNA/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.TfZGEPmNtZ/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005035 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 10:56:32.788230) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 10:56:32.788573) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 10:56:32.788957) ========= ========= Total master_lag: 0.20 =========