builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0195 starttime: 1495553934.08 results: success (0) revision: 4f874bc1e42463e30bc5595e84e5aaa450d81167 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:54.083465) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:54.083893) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:54.084170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:54.107001) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:54.107300) ========= 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.OHhBaloqlk/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.anw9uJQ3zm/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-23 08:38:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.90M=0.001s 2017-05-23 08:38:54 (7.90 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.164739 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:54.295151) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:54.295457) ========= 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.OHhBaloqlk/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.anw9uJQ3zm/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.026268 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:54.347728) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 08:38:54.348216) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 4f874bc1e42463e30bc5595e84e5aaa450d81167 --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 4f874bc1e42463e30bc5595e84e5aaa450d81167 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OHhBaloqlk/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.anw9uJQ3zm/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-23 08:38:54,566 truncating revision to first 12 chars 2017-05-23 08:38:54,566 Setting DEBUG logging. 2017-05-23 08:38:54,566 attempt 1/10 2017-05-23 08:38:54,566 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/4f874bc1e424?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 08:38:55,727 unpacking tar archive at: mozilla-central-4f874bc1e424/testing/mozharness/ program finished with exit code 0 elapsedTime=1.431521 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 08:38:55.806380) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:55.807170) ========= script_repo_revision: 4f874bc1e42463e30bc5595e84e5aaa450d81167 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:55.808043) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:55.808347) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 08:38:55.827241) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 33 secs) (at 2017-05-23 08:38:55.827895) ========= /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.OHhBaloqlk/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.anw9uJQ3zm/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:38:56 INFO - MultiFileLogger online at 20170523 08:38:56 in /builds/slave/test 08:38:56 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 08:38:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:38:56 INFO - {'append_to_log': False, 08:38:56 INFO - 'base_work_dir': '/builds/slave/test', 08:38:56 INFO - 'blob_upload_branch': 'Firefox', 08:38:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:38:56 INFO - 'branch': 'Firefox', 08:38:56 INFO - 'buildbot_json_path': 'buildprops.json', 08:38:56 INFO - 'config_files': ('talos/mac_config.py',), 08:38:56 INFO - 'default_actions': ('clobber', 08:38:56 INFO - 'read-buildbot-config', 08:38:56 INFO - 'download-and-extract', 08:38:56 INFO - 'populate-webroot', 08:38:56 INFO - 'create-virtualenv', 08:38:56 INFO - 'install', 08:38:56 INFO - 'run-tests'), 08:38:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:38:56 INFO - 'download_minidump_stackwalk': True, 08:38:56 INFO - 'download_symbols': 'ondemand', 08:38:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:38:56 INFO - 'tooltool.py': '/tools/tooltool.py', 08:38:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:38:56 INFO - '/tools/misc-python/virtualenv.py')}, 08:38:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:38:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:38:56 INFO - 'gecko_profile': False, 08:38:56 INFO - 'gecko_profile_interval': 0, 08:38:56 INFO - 'installer_path': 'installer.exe', 08:38:56 INFO - 'log_level': 'info', 08:38:56 INFO - 'log_name': 'talos', 08:38:56 INFO - 'log_to_console': True, 08:38:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:38:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:38:56 INFO - 'opt_config_files': (), 08:38:56 INFO - 'pip_index': False, 08:38:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:38:56 INFO - 'cmd': ('bash', 08:38:56 INFO - '-c', 08:38:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:38:56 INFO - 'enabled': True, 08:38:56 INFO - 'halt_on_failure': False, 08:38:56 INFO - 'name': 'check_screen_resolution'},), 08:38:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:38:56 INFO - 'cmd': ('bash', 08:38:56 INFO - '-c', 08:38:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:38:56 INFO - 'enabled': True, 08:38:56 INFO - 'halt_on_failure': False, 08:38:56 INFO - 'name': 'check_screen_resolution'},), 08:38:56 INFO - 'run_cmd_checks_enabled': True, 08:38:56 INFO - 'suite': 'dromaeojs-e10s', 08:38:56 INFO - 'system_bits': '32', 08:38:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:38:56 INFO - 'title': 't-yosemite-r7-0195', 08:38:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:38:56 INFO - 'use_talos_json': True, 08:38:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:38:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:38:56 INFO - 'work_dir': 'build'} 08:38:56 INFO - [mozharness: 2017-05-23 15:38:56.174283Z] Running clobber step. 08:38:56 INFO - Running pre-action listener: _resource_record_pre_action 08:38:56 INFO - Running main action method: clobber 08:38:56 INFO - rmtree: /builds/slave/test/build 08:38:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:38:58 INFO - Running post-action listener: _resource_record_post_action 08:38:58 INFO - [mozharness: 2017-05-23 15:38:58.231625Z] Finished clobber step (success) 08:38:58 INFO - [mozharness: 2017-05-23 15:38:58.231750Z] Running read-buildbot-config step. 08:38:58 INFO - Running pre-action listener: _resource_record_pre_action 08:38:58 INFO - Running main action method: read_buildbot_config 08:38:58 INFO - Using buildbot properties: 08:38:58 INFO - { 08:38:58 INFO - "project": "", 08:38:58 INFO - "product": "firefox", 08:38:58 INFO - "who": "ryanvm@gmail.com", 08:38:58 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 08:38:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:38:58 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 08:38:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:38:58 INFO - "stage_platform": "macosx64", 08:38:58 INFO - "basedir": "/builds/slave/test", 08:38:58 INFO - "buildnumber": 0, 08:38:58 INFO - "platform": "yosemite_r7", 08:38:58 INFO - "parent_task_id": "G2NU0gjNTa-LUo2rvpPguA", 08:38:58 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:38:58 INFO - "slavebuilddir": "test", 08:38:58 INFO - "taskId": "ISzuHkHvSgyRlS98m5WmLw", 08:38:58 INFO - "branch": "mozilla-central", 08:38:58 INFO - "script_repo_revision": "production", 08:38:58 INFO - "revision": "4f874bc1e42463e30bc5595e84e5aaa450d81167", 08:38:58 INFO - "slavename": "t-yosemite-r7-0195", 08:38:58 INFO - "repo_path": "mozilla-central" 08:38:58 INFO - } 08:38:58 INFO - Finding installer, test and symbols from parent task. 08:38:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ISzuHkHvSgyRlS98m5WmLw'}, attempt #1 08:38:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ISzuHkHvSgyRlS98m5WmLw'}, attempt #1 08:38:59 INFO - Task dependencies: BpNlg4XMT9KmITwJyn7qnw G2NU0gjNTa-LUo2rvpPguA 08:38:59 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA'}, attempt #1 08:38:59 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:38:59 INFO - Set test_url: https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/test_packages.json 08:38:59 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/target.crashreporter-symbols.zip 08:38:59 INFO - Running post-action listener: _resource_record_post_action 08:38:59 INFO - [mozharness: 2017-05-23 15:38:59.964928Z] Finished read-buildbot-config step (success) 08:38:59 INFO - [mozharness: 2017-05-23 15:38:59.965046Z] Running download-and-extract step. 08:38:59 INFO - Running pre-action listener: _resource_record_pre_action 08:38:59 INFO - Running main action method: download_and_extract 08:38:59 INFO - mkdir: /builds/slave/test/build/tests 08:38: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')]} 08:38:59 INFO - trying https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 08:38:59 INFO - Downloading https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/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 08:38:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/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 08:39:01 INFO - Downloaded 1325 bytes. 08:39:01 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 08:39:01 INFO - Using the following test package requirements: 08:39:01 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 08:39:01 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:39:01 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 08:39:01 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 08:39:01 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'jsshell-mac.zip'], 08:39:01 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 08:39:01 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 08:39:01 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 08:39:01 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 08:39:01 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 08:39:01 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 08:39:01 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 08:39:01 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:39:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:39:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:39:01 INFO - Fetch https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:39:56 INFO - Content-Length response header: 57629561 08:39:56 INFO - Bytes received: 57629561 08:40:15 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 08:40:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 08:40:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:40:15 INFO - Fetch https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 08:40:19 INFO - Content-Length response header: 57629561 08:40:19 INFO - Bytes received: 57629561 08:40:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 08:40:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:40:37 INFO - Fetch https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 08:40:46 INFO - Content-Length response header: 14410000 08:40:46 INFO - Bytes received: 14410000 08:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:46 INFO - trying https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:40:46 INFO - Downloading https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 08:40:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/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 08:41:01 INFO - Downloaded 68013973 bytes. 08:41:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:41:01 INFO - mkdir: /builds/slave/test/properties 08:41:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:41:01 INFO - Writing to file /builds/slave/test/properties/build_url 08:41:01 INFO - Contents: 08:41:01 INFO - build_url:https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 08:41:01 INFO - Running post-action listener: _resource_record_post_action 08:41:01 INFO - Running post-action listener: set_extra_try_arguments 08:41:01 INFO - [mozharness: 2017-05-23 15:41:01.707879Z] Finished download-and-extract step (success) 08:41:01 INFO - [mozharness: 2017-05-23 15:41:01.708016Z] Running populate-webroot step. 08:41:01 INFO - Running pre-action listener: _resource_record_pre_action 08:41:01 INFO - Running main action method: populate_webroot 08:41:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:41:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:41:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:41:01 INFO - '--tppagecycles', 08:41:01 INFO - '7'], 08:41:01 INFO - 'tests': ['tsvgm']}}, 08:41:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:41:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:41:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:41:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:41:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:41:01 INFO - 'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:41:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:41:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:41:01 INFO - 'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['damp', 'tps']}, 08:41:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:41:01 INFO - 'tests': ['damp', 'tps']}, 08:41:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['dromaeo_dom']}, 08:41:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:41:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 08:41:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 08:41:01 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 08:41:01 INFO - 'tests': ['ts_paint', 'tp5o']}, 08:41:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['a11yr', 08:41:01 INFO - 'ts_paint', 08:41:01 INFO - 'tpaint', 08:41:01 INFO - 'sessionrestore', 08:41:01 INFO - 'sessionrestore_no_auto_restore', 08:41:01 INFO - 'tabpaint']}, 08:41:01 INFO - 'other-e10s': {'tests': ['a11yr', 08:41:01 INFO - 'ts_paint', 08:41:01 INFO - 'tpaint', 08:41:01 INFO - 'sessionrestore', 08:41:01 INFO - 'sessionrestore_no_auto_restore', 08:41:01 INFO - 'tabpaint']}, 08:41:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:41:01 INFO - 'ts_paint', 08:41:01 INFO - 'tpaint', 08:41:01 INFO - 'sessionrestore', 08:41:01 INFO - 'sessionrestore_no_auto_restore', 08:41:01 INFO - 'tabpaint']}, 08:41:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:41:01 INFO - 'ts_paint', 08:41:01 INFO - 'tpaint', 08:41:01 INFO - 'sessionrestore', 08:41:01 INFO - 'sessionrestore_no_auto_restore', 08:41:01 INFO - 'tabpaint']}, 08:41:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['a11yr', 08:41:01 INFO - 'ts_paint', 08:41:01 INFO - 'tpaint', 08:41:01 INFO - 'sessionrestore', 08:41:01 INFO - 'sessionrestore_no_auto_restore', 08:41:01 INFO - 'tabpaint']}, 08:41:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['a11yr', 08:41:01 INFO - 'ts_paint', 08:41:01 INFO - 'tpaint', 08:41:01 INFO - 'sessionrestore', 08:41:01 INFO - 'sessionrestore_no_auto_restore', 08:41:01 INFO - 'tabpaint']}, 08:41:01 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 08:41:01 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 08:41:01 INFO - 'bloom_basic_ref']}, 08:41:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['tsvgx', 08:41:01 INFO - 'tsvgr_opacity', 08:41:01 INFO - 'tart', 08:41:01 INFO - 'tscrollx', 08:41:01 INFO - 'cart', 08:41:01 INFO - 'tsvg_static']}, 08:41:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:41:01 INFO - 'tsvgr_opacity', 08:41:01 INFO - 'tart', 08:41:01 INFO - 'tscrollx', 08:41:01 INFO - 'cart', 08:41:01 INFO - 'tsvg_static']}, 08:41:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:41:01 INFO - 'talos_options': ['--disable-e10s'], 08:41:01 INFO - 'tests': ['tp5o']}, 08:41:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:41:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:41:01 INFO - 'talos_options': ['--disable-e10s', 08:41:01 INFO - '--xperf_path', 08:41:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:01 INFO - 'tests': ['tp5n']}, 08:41:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:41:01 INFO - 'talos_options': ['--xperf_path', 08:41:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:41:01 INFO - 'tests': ['tp5n']}}, 08:41:01 INFO - 'talos.zip': {'path': '', 08:41:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:41:01 INFO - Running post-action listener: _resource_record_post_action 08:41:01 INFO - [mozharness: 2017-05-23 15:41:01.727265Z] Finished populate-webroot step (success) 08:41:01 INFO - [mozharness: 2017-05-23 15:41:01.727398Z] Running create-virtualenv step. 08:41:01 INFO - Running pre-action listener: _resource_record_pre_action 08:41:01 INFO - Running main action method: create_virtualenv 08:41:01 INFO - Creating virtualenv /builds/slave/test/build/venv 08:41: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 08:41:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:41:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:41:01 INFO - Using real prefix '/tools/python27' 08:41:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:41:02 INFO - Installing distribute.............................................................................................................................................................................................done. 08:41:05 INFO - Installing pip.................done. 08:41:05 INFO - Return code: 0 08:41:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:41:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:41:05 INFO - Reading from file tmpfile_stdout 08:41:05 INFO - Output received: 08:41: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) 08:41:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41: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')]} 08:41:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41: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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41: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 08:41: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 08:41:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:05 INFO - 'HOME': '/Users/cltbld', 08:41:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:05 INFO - 'LOGNAME': 'cltbld', 08:41:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:05 INFO - 'MOZ_NO_REMOTE': '1', 08:41:05 INFO - 'NO_EM_RESTART': '1', 08:41:05 INFO - 'PAGER': '/bin/cat', 08:41:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:05 INFO - 'PWD': '/builds/slave/test', 08:41:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:05 INFO - 'SHELL': '/bin/bash', 08:41:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:05 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:05 INFO - 'USER': 'cltbld', 08:41:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:05 INFO - 'XPC_FLAGS': '0x0', 08:41:05 INFO - 'XPC_SERVICE_NAME': '0', 08:41:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41: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 08:41:05 INFO - Cleaning up... 08:41:05 INFO - Return code: 0 08:41:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41: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')]} 08:41:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41: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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41: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 08:41: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 08:41:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:05 INFO - 'HOME': '/Users/cltbld', 08:41:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:05 INFO - 'LOGNAME': 'cltbld', 08:41:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:05 INFO - 'MOZ_NO_REMOTE': '1', 08:41:05 INFO - 'NO_EM_RESTART': '1', 08:41:05 INFO - 'PAGER': '/bin/cat', 08:41:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:05 INFO - 'PWD': '/builds/slave/test', 08:41:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:05 INFO - 'SHELL': '/bin/bash', 08:41:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:05 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:05 INFO - 'USER': 'cltbld', 08:41:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:05 INFO - 'XPC_FLAGS': '0x0', 08:41:05 INFO - 'XPC_SERVICE_NAME': '0', 08:41:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:06 INFO - Downloading/unpacking psutil>=3.1.1 08:41: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 08:41: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 08:41: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 08:41: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 08:41:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:41:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:08 INFO - Installing collected packages: psutil 08:41:08 INFO - Running setup.py install for psutil 08:41:08 INFO - building 'psutil._psutil_osx' extension 08:41: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 08:41: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 08:41:09 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 08:41: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 08:41:09 INFO - building 'psutil._psutil_posix' extension 08:41: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 08:41:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:41:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:41:09 INFO - ^ 08:41:09 INFO - 1 warning generated. 08:41: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 08:41:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:41:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:41:09 INFO - Successfully installed psutil 08:41:09 INFO - Cleaning up... 08:41:09 INFO - Return code: 0 08:41:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41: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')]} 08:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:09 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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41:09 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 08:41:09 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 08:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:09 INFO - 'HOME': '/Users/cltbld', 08:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:09 INFO - 'LOGNAME': 'cltbld', 08:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:09 INFO - 'MOZ_NO_REMOTE': '1', 08:41:09 INFO - 'NO_EM_RESTART': '1', 08:41:09 INFO - 'PAGER': '/bin/cat', 08:41:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:09 INFO - 'PWD': '/builds/slave/test', 08:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:09 INFO - 'SHELL': '/bin/bash', 08:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:09 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:09 INFO - 'USER': 'cltbld', 08:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:09 INFO - 'XPC_FLAGS': '0x0', 08:41:09 INFO - 'XPC_SERVICE_NAME': '0', 08:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:41:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:41:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:41:11 INFO - Installing collected packages: mozsystemmonitor 08:41:11 INFO - Running setup.py install for mozsystemmonitor 08:41:11 INFO - Successfully installed mozsystemmonitor 08:41:11 INFO - Cleaning up... 08:41:11 INFO - Return code: 0 08:41:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:11 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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41:11 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 08:41:11 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 08:41:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:11 INFO - 'HOME': '/Users/cltbld', 08:41:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:11 INFO - 'LOGNAME': 'cltbld', 08:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:11 INFO - 'MOZ_NO_REMOTE': '1', 08:41:11 INFO - 'NO_EM_RESTART': '1', 08:41:11 INFO - 'PAGER': '/bin/cat', 08:41:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:11 INFO - 'PWD': '/builds/slave/test', 08:41:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:11 INFO - 'SHELL': '/bin/bash', 08:41:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:11 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:11 INFO - 'USER': 'cltbld', 08:41:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:11 INFO - 'XPC_FLAGS': '0x0', 08:41:11 INFO - 'XPC_SERVICE_NAME': '0', 08:41:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:11 INFO - Downloading/unpacking jsonschema==2.5.1 08:41:11 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 08:41:11 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 08:41:11 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 08:41:11 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 08:41:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:41:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:41:13 INFO - Downloading functools32-3.2.3-2.tar.gz 08:41:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:41:13 INFO - warning: no files found matching '*.txt' 08:41:13 INFO - no previously-included directories found matching 'build' 08:41:13 INFO - no previously-included directories found matching 'dist' 08:41:13 INFO - no previously-included directories found matching '.git*' 08:41:13 INFO - Installing collected packages: jsonschema, functools32 08:41:13 INFO - Running setup.py install for functools32 08:41:13 INFO - warning: no files found matching '*.txt' 08:41:13 INFO - no previously-included directories found matching 'build' 08:41:13 INFO - no previously-included directories found matching 'dist' 08:41:13 INFO - no previously-included directories found matching '.git*' 08:41:13 INFO - Successfully installed jsonschema functools32 08:41:13 INFO - Cleaning up... 08:41:13 INFO - Return code: 0 08:41:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:41:13 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')]} 08:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:13 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')]} 08:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:13 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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41:13 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 08:41:13 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 08:41:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:13 INFO - 'HOME': '/Users/cltbld', 08:41:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:13 INFO - 'LOGNAME': 'cltbld', 08:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:13 INFO - 'MOZ_NO_REMOTE': '1', 08:41:13 INFO - 'NO_EM_RESTART': '1', 08:41:13 INFO - 'PAGER': '/bin/cat', 08:41:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:13 INFO - 'PWD': '/builds/slave/test', 08:41:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:13 INFO - 'SHELL': '/bin/bash', 08:41:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:13 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:13 INFO - 'USER': 'cltbld', 08:41:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:13 INFO - 'XPC_FLAGS': '0x0', 08:41:13 INFO - 'XPC_SERVICE_NAME': '0', 08:41:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:41:13 INFO - Cleaning up... 08:41:13 INFO - Return code: 0 08:41:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:41:13 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')]} 08:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:13 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')]} 08:41:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:13 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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41:13 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 08:41:13 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 08:41:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:13 INFO - 'HOME': '/Users/cltbld', 08:41:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:13 INFO - 'LOGNAME': 'cltbld', 08:41:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:13 INFO - 'MOZ_NO_REMOTE': '1', 08:41:13 INFO - 'NO_EM_RESTART': '1', 08:41:13 INFO - 'PAGER': '/bin/cat', 08:41:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:13 INFO - 'PWD': '/builds/slave/test', 08:41:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:13 INFO - 'SHELL': '/bin/bash', 08:41:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:13 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:13 INFO - 'USER': 'cltbld', 08:41:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:13 INFO - 'XPC_FLAGS': '0x0', 08:41:13 INFO - 'XPC_SERVICE_NAME': '0', 08:41:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:14 INFO - Downloading/unpacking blobuploader==1.2.4 08:41: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 08:41: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 08:41: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 08:41: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 08:41:15 INFO - Downloading blobuploader-1.2.4.tar.gz 08:41:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:41:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:41: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 08:41: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 08:41: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 08:41: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 08:41:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:41:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:41: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 08:41: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 08:41: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 08:41: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 08:41:16 INFO - Downloading docopt-0.6.1.tar.gz 08:41:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:41:16 INFO - Installing collected packages: blobuploader, requests, docopt 08:41:16 INFO - Running setup.py install for blobuploader 08:41:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:41:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:41:16 INFO - Running setup.py install for requests 08:41:17 INFO - Running setup.py install for docopt 08:41:17 INFO - Successfully installed blobuploader requests docopt 08:41:17 INFO - Cleaning up... 08:41:17 INFO - Return code: 0 08:41:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41: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')]} 08:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41: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')]} 08:41:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:17 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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41:17 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 08:41:17 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 08:41:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:17 INFO - 'HOME': '/Users/cltbld', 08:41:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:17 INFO - 'LOGNAME': 'cltbld', 08:41:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:17 INFO - 'MOZ_NO_REMOTE': '1', 08:41:17 INFO - 'NO_EM_RESTART': '1', 08:41:17 INFO - 'PAGER': '/bin/cat', 08:41:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:17 INFO - 'PWD': '/builds/slave/test', 08:41:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:17 INFO - 'SHELL': '/bin/bash', 08:41:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:17 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:17 INFO - 'USER': 'cltbld', 08:41:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:17 INFO - 'XPC_FLAGS': '0x0', 08:41:17 INFO - 'XPC_SERVICE_NAME': '0', 08:41:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:17 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-lRP_nY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:17 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-v0hngi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:17 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-3s0_7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:17 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-tNBk3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-6xK6Qa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-AASZUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-7ri36I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-HA5tjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-SbGJDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Qrnig1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-oENu8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-2JCjyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:18 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-1Ba1aN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Xro9CW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-PX8XPF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-x8aEGN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:19 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-sU9VK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:41:19 INFO - Running setup.py install for manifestparser 08:41:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:41:19 INFO - Running setup.py install for mozcrash 08:41:19 INFO - Running setup.py install for mozdebug 08:41:19 INFO - Running setup.py install for mozdevice 08:41:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:41:20 INFO - Running setup.py install for mozfile 08:41:20 INFO - Running setup.py install for mozhttpd 08:41:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:41:20 INFO - Running setup.py install for mozinfo 08:41:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:41:20 INFO - Running setup.py install for mozInstall 08:41:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:41:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:41:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:41:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:41:20 INFO - Running setup.py install for mozleak 08:41:20 INFO - Running setup.py install for mozlog 08:41:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:41:20 INFO - Running setup.py install for moznetwork 08:41:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:41:21 INFO - Running setup.py install for mozprocess 08:41:21 INFO - Running setup.py install for mozprofile 08:41:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:41:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:41:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:41:21 INFO - Running setup.py install for mozrunner 08:41:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:41:21 INFO - Running setup.py install for mozscreenshot 08:41:21 INFO - Running setup.py install for moztest 08:41:21 INFO - Running setup.py install for mozversion 08:41:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:41:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:41:22 INFO - Cleaning up... 08:41:22 INFO - Return code: 0 08:41:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:22 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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41:22 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 08:41:22 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 08:41:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:22 INFO - 'HOME': '/Users/cltbld', 08:41:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:22 INFO - 'LOGNAME': 'cltbld', 08:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:22 INFO - 'MOZ_NO_REMOTE': '1', 08:41:22 INFO - 'NO_EM_RESTART': '1', 08:41:22 INFO - 'PAGER': '/bin/cat', 08:41:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:22 INFO - 'PWD': '/builds/slave/test', 08:41:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:22 INFO - 'SHELL': '/bin/bash', 08:41:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:22 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:22 INFO - 'USER': 'cltbld', 08:41:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:22 INFO - 'XPC_FLAGS': '0x0', 08:41:22 INFO - 'XPC_SERVICE_NAME': '0', 08:41:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:41:22 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-qN3Y3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:41:22 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)) 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:41:22 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-zZMEEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:41:22 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)) 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:41:22 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-rRYfgH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:41:22 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)) 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:41:22 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-wwRudw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:41:22 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-OX4qEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:41:22 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)) 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:41:22 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-StmrMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:41:22 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)) 08:41:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:41:22 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ndXvP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-w6hKep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-HTbLll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-7QPDGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-nzibru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-jRmMuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-RQ7m9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-9JxhDy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-PRX01E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:41:23 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)) 08:41:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:41:23 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-xnE83W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:41:24 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)) 08:41:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:41:24 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Exf5U0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:41:24 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)) 08:41:24 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)) 08:41:24 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)) 08:41:24 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)) 08:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:41:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:41:24 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 08:41:24 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 08:41:24 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 08:41:24 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 08:41:25 INFO - Downloading blessings-1.6.tar.gz 08:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:41:25 INFO - Installing collected packages: blessings 08:41:25 INFO - Running setup.py install for blessings 08:41:25 INFO - Successfully installed blessings 08:41:25 INFO - Cleaning up... 08:41:25 INFO - Return code: 0 08:41:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:41:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:41:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:41:26 INFO - Reading from file tmpfile_stdout 08:41:26 INFO - Current package versions: 08:41:26 INFO - blessings == 1.6 08:41:26 INFO - blobuploader == 1.2.4 08:41:26 INFO - docopt == 0.6.1 08:41:26 INFO - functools32 == 3.2.3-2 08:41:26 INFO - jsonschema == 2.5.1 08:41:26 INFO - manifestparser == 1.1 08:41:26 INFO - mozInstall == 1.12 08:41:26 INFO - mozcrash == 1.0 08:41:26 INFO - mozdebug == 0.1 08:41:26 INFO - mozdevice == 0.50 08:41:26 INFO - mozfile == 1.2 08:41:26 INFO - mozhttpd == 0.7 08:41:26 INFO - mozinfo == 0.9 08:41:26 INFO - mozleak == 0.1 08:41:26 INFO - mozlog == 3.4 08:41:26 INFO - moznetwork == 0.27 08:41:26 INFO - mozprocess == 0.25 08:41:26 INFO - mozprofile == 0.28 08:41:26 INFO - mozrunner == 6.13 08:41:26 INFO - mozscreenshot == 0.1 08:41:26 INFO - mozsystemmonitor == 0.3 08:41:26 INFO - moztest == 0.8 08:41:26 INFO - mozversion == 1.4 08:41:26 INFO - psutil == 3.1.1 08:41:26 INFO - requests == 1.2.3 08:41:26 INFO - wsgiref == 0.1.2 08:41:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:41:26 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')]} 08:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:26 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')]} 08:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:26 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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41:26 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 08:41:26 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 08:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:26 INFO - 'HOME': '/Users/cltbld', 08:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:26 INFO - 'LOGNAME': 'cltbld', 08:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:26 INFO - 'MOZ_NO_REMOTE': '1', 08:41:26 INFO - 'NO_EM_RESTART': '1', 08:41:26 INFO - 'PAGER': '/bin/cat', 08:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:26 INFO - 'PWD': '/builds/slave/test', 08:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:26 INFO - 'SHELL': '/bin/bash', 08:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:26 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:26 INFO - 'USER': 'cltbld', 08:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:26 INFO - 'XPC_FLAGS': '0x0', 08:41:26 INFO - 'XPC_SERVICE_NAME': '0', 08:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 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)) 08:41:26 INFO - Cleaning up... 08:41:26 INFO - Return code: 0 08:41:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:41:26 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')]} 08:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:41:26 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')]} 08:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:41:26 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 0x10c1beb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c1c2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe612cbed00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, '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.OHhBaloqlk/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.anw9uJQ3zm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 08:41:26 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 08:41:26 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 08:41:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:26 INFO - 'HOME': '/Users/cltbld', 08:41:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:26 INFO - 'LOGNAME': 'cltbld', 08:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:26 INFO - 'MOZ_NO_REMOTE': '1', 08:41:26 INFO - 'NO_EM_RESTART': '1', 08:41:26 INFO - 'PAGER': '/bin/cat', 08:41:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:26 INFO - 'PWD': '/builds/slave/test', 08:41:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:26 INFO - 'SHELL': '/bin/bash', 08:41:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:26 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:26 INFO - 'USER': 'cltbld', 08:41:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:26 INFO - 'XPC_FLAGS': '0x0', 08:41:26 INFO - 'XPC_SERVICE_NAME': '0', 08:41:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:41:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:41:26 INFO - Cleaning up... 08:41:26 INFO - Return code: 0 08:41:26 INFO - Running post-action listener: _resource_record_post_action 08:41:26 INFO - Running post-action listener: _start_resource_monitoring 08:41:26 INFO - Starting resource monitoring. 08:41:26 INFO - [mozharness: 2017-05-23 15:41:26.742931Z] Finished create-virtualenv step (success) 08:41:26 INFO - [mozharness: 2017-05-23 15:41:26.744009Z] Running install step. 08:41:26 INFO - Running pre-action listener: _resource_record_pre_action 08:41:26 INFO - Running main action method: install 08:41:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:41:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:41:27 INFO - Reading from file tmpfile_stdout 08:41:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:41:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:41:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:41:27 INFO - Reading from file tmpfile_stdout 08:41:27 INFO - Output received: 08:41:27 INFO - Usage: mozinstall [options] installer 08:41:27 INFO - Options: 08:41:27 INFO - -h, --help show this help message and exit 08:41:27 INFO - -d DEST, --destination=DEST 08:41:27 INFO - Directory to install application into. [default: 08:41:27 INFO - "/builds/slave/test"] 08:41:27 INFO - --app=APP Application being installed. [default: firefox] 08:41:27 INFO - mkdir: /builds/slave/test/build/application 08:41:27 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'] 08:41:27 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 08:41:48 INFO - Reading from file tmpfile_stdout 08:41:48 INFO - Output received: 08:41:48 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:41:48 INFO - Running post-action listener: _resource_record_post_action 08:41:48 INFO - [mozharness: 2017-05-23 15:41:48.464719Z] Finished install step (success) 08:41:48 INFO - [mozharness: 2017-05-23 15:41:48.464847Z] Running run-tests step. 08:41:48 INFO - Running pre-action listener: _resource_record_pre_action 08:41:48 INFO - Running main action method: run_tests 08:41:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:41:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:41:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:41:48 INFO - 2017-05-23 08:41:48.479 screenresolution[88147:819225] starting screenresolution argv=screenresolution get 08:41:48 INFO - 2017-05-23 08:41:48.482 screenresolution[88147:819225] Display 0: 1600x1200x32@60 08:41:48 INFO - 2017-05-23 08:41:48.488 screenresolution[88148:819228] starting screenresolution argv=screenresolution list 08:41:48 INFO - Available Modes on Display 0 08:41:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:41:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:41:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:41:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:41:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:41:48 INFO - Intel Iris: 08:41:48 INFO - Chipset Model: Intel Iris 08:41:48 INFO - Type: GPU 08:41:48 INFO - Bus: Built-In 08:41:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:41:48 INFO - Vendor: Intel (0x8086) 08:41:48 INFO - Device ID: 0x0a2e 08:41:48 INFO - Revision ID: 0x0009 08:41:48 INFO - Displays: 08:41:48 INFO - Display: 08:41:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:41:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:41:48 INFO - Display Serial Number: 1600x1200 60 08:41:48 INFO - Main Display: Yes 08:41:48 INFO - Mirror: Off 08:41:48 INFO - Online: Yes 08:41:48 INFO - Rotation: Supported 08:41:48 INFO - Return code: 0 08:41:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:41:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:41:48 INFO - Python 2.7.3 08:41:48 INFO - Return code: 0 08:41:48 INFO - grabbing minidump binary from tooltool 08:41:48 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')]} 08:41:48 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 0x10beced30>, '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 0x10be82db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bec3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:41:48 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 08:41:48 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 08:41:48 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 08:41:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:41:48 INFO - Return code: 0 08:41:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:41:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:41:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:41:48 INFO - ENV: RUST_BACKTRACE is now 1 08:41:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:41:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:41:48 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/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 08:41:48 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/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0195 --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 08:41:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OHhBaloqlk/Render', 08:41:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:48 INFO - 'HOME': '/Users/cltbld', 08:41:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:48 INFO - 'LOGNAME': 'cltbld', 08:41:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:41:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:41:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:48 INFO - 'MOZ_NO_REMOTE': '1', 08:41:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:41:48 INFO - 'NO_EM_RESTART': '1', 08:41:48 INFO - 'PAGER': '/bin/cat', 08:41:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:48 INFO - 'PWD': '/builds/slave/test', 08:41:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:41:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:48 INFO - 'RUST_BACKTRACE': '1', 08:41:48 INFO - 'SHELL': '/bin/bash', 08:41:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.anw9uJQ3zm/Listeners', 08:41:48 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 08:41:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:48 INFO - 'USER': 'cltbld', 08:41:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:48 INFO - 'XPC_FLAGS': '0x0', 08:41:48 INFO - 'XPC_SERVICE_NAME': '0', 08:41:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:48 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/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 08:41:49 INFO - mozversion application_buildid: 20170523071136 08:41:49 INFO - mozversion application_changeset: 4f874bc1e42463e30bc5595e84e5aaa450d81167 08:41:49 INFO - mozversion application_display_name: Nightly 08:41:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:41:49 INFO - mozversion application_name: Firefox 08:41:49 INFO - mozversion application_remotingname: firefox 08:41:49 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:41:49 INFO - mozversion application_vendor: Mozilla 08:41:49 INFO - mozversion application_version: 55.0a1 08:41:49 INFO - mozversion platform_buildid: 20170523071136 08:41:49 INFO - mozversion platform_changeset: 4f874bc1e42463e30bc5595e84e5aaa450d81167 08:41:49 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:41:49 INFO - mozversion platform_version: 55.0a1 08:41:49 INFO - using testdate: 1495554109 08:41:49 INFO - actual date: 1495554109 08:41:49 INFO - starting webserver on 'localhost:54620' 08:41:49 INFO - SUITE-START | Running 2 tests 08:41:49 INFO - TEST-START | dromaeo_css 08:41:49 INFO - operating with platform_type : mac_ 08:41:49 INFO - Initialising browser for dromaeo_css test... 08:41:49 INFO - TEST-INFO | started process 88158 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpb6yRYs/profile http://localhost:54620/getInfo.html) 08:41:49 INFO - PID 88158 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 08:41:51 INFO - PID 88158 | 2017-05-23 08:41:51.726 plugin-container[88160:819393] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e3f, name = 'com.apple.tsm.portname' 08:41:51 INFO - PID 88158 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:51 INFO - PID 88158 | 2017-05-23 08:41:51.729 plugin-container[88160:819393] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa30f, name = 'com.apple.CFPasteboardClient' 08:41:51 INFO - PID 88158 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:51 INFO - PID 88158 | 2017-05-23 08:41:51.729 plugin-container[88160:819393] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:51 INFO - PID 88158 | __metrics Screen width/height:1600/1200 08:41:51 INFO - PID 88158 | colorDepth:24 08:41:51 INFO - PID 88158 | Browser inner width/height: 1024/691 08:41:51 INFO - PID 88158 | __metrics 08:41:52 INFO - PID 88158 | 08:41:52 INFO - PID 88158 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 08:41:52 INFO - PID 88158 | 08:41:53 INFO - TEST-INFO | 88158: exit 0 08:41:53 INFO - Browser initialized. 08:41:53 INFO - Running cycle 1/1 for dromaeo_css test... 08:41:53 INFO - TEST-INFO | started process 88162 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpb6yRYs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:54 INFO - PID 88162 | 2017-05-23 08:41:54.828 plugin-container[88163:819535] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a47, name = 'com.apple.tsm.portname' 08:41:54 INFO - PID 88162 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:54 INFO - PID 88162 | 2017-05-23 08:41:54.828 plugin-container[88163:819535] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0f, name = 'com.apple.CFPasteboardClient' 08:41:54 INFO - PID 88162 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:54 INFO - PID 88162 | 2017-05-23 08:41:54.828 plugin-container[88163:819535] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:55 INFO - PID 88162 | 2017-05-23 08:41:55.625 plugin-container[88164:819582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 08:41:55 INFO - PID 88162 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:55 INFO - PID 88162 | 2017-05-23 08:41:55.625 plugin-container[88164:819582] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 08:41:55 INFO - PID 88162 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:55 INFO - PID 88162 | 2017-05-23 08:41:55.626 plugin-container[88164:819582] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:42:04 INFO - PID 88162 | RSS: Main: 242286592 08:42:04 INFO - PID 88162 | 08:45:07 INFO - PID 88162 | Cycle 1(1): loaded http://localhost:54620/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54620/tests/dromaeo/cssquery-ext.html) 08:45:08 INFO - PID 88162 | RSS: Main: 263647232 08:45:08 INFO - PID 88162 | 08:48:10 INFO - PID 88162 | Cycle 1(1): loaded http://localhost:54620/tests/dromaeo/cssquery-ext.html (next: http://localhost:54620/tests/dromaeo/cssquery-jquery.html) 08:48:11 INFO - PID 88162 | RSS: Main: 272269312 08:48:11 INFO - PID 88162 | 08:51:12 INFO - PID 88162 | Cycle 1(1): loaded http://localhost:54620/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54620/tests/dromaeo/cssquery-mootools.html) 08:51:13 INFO - PID 88162 | RSS: Main: 277229568 08:51:13 INFO - PID 88162 | 08:54:15 INFO - PID 88162 | Cycle 1(1): loaded http://localhost:54620/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54620/tests/dromaeo/cssquery-prototype.html) 08:54:15 INFO - PID 88162 | RSS: Main: 274735104 08:54:15 INFO - PID 88162 | 08:57:18 INFO - PID 88162 | Cycle 1(1): loaded http://localhost:54620/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54620/tests/dromaeo/cssquery-yui.html) 08:57:19 INFO - PID 88162 | RSS: Main: 276500480 08:57:19 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | Cycle 1(1): loaded http://localhost:54620/tests/dromaeo/cssquery-yui.html (next: http://localhost:54620/tests/dromaeo/cssquery-dojo.html) 09:00:21 INFO - PID 88162 | RSS: Main: 277639168 09:00:21 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | __start_tp_report 09:00:21 INFO - PID 88162 | _x_x_mozilla_page_load 09:00:21 INFO - PID 88162 | _x_x_mozilla_page_load_details 09:00:21 INFO - PID 88162 | |i|pagename|runs| 09:00:21 INFO - PID 88162 | |0;dojo.html;3519.5248641433413;3533.240353324035;3580.373434648936;3594.4967704521355;3601.531800865887;6095.5733098682995;6172.7839525616555;6197.380261973806;6208.627482351062;6233.812985610433;6112.746658179501;6155.076738489229;6162.7534898604;6164.969175154118;6230.84422889428;5406.08096623573;5457.372402173742;5462.945370546284;5481.205225242347;5560.110382338827;5334.493223143795;5524.226608274845;5551.750171242284;5565.38780734121;5576.86057848555;5535.723213839292;5599.552035837143;5610.775568977236;5628;5671.886562268752;5409.459054094598;5435.103207970719;5456.7544460499375;5482.17767334899;5527;5438.347398312228;5439.129739241742;5442.9727851360885;5453.563714902838;5483.985462689415;4691.6481263905;5614.9893019256515;5742.425757424266;6122.326544080148;6236.563440559163;6513.120728701612;6704.430123439513;6705.832354191136;6706.161729783777;6719.462443004548;5640.520555752766;6707.993800929851;6826.897596536056;6827.2831352708245;6831.2144103428245;6344.809655710336;6348.682565871734;6352.142460767784;6365.936340636535;6376.426121649074;6868.175818901764;6957.65211739411;6963.8607227855155;6963.895541566881;6982.825429364205;6455.193100862392;6493.740250389931;6591.406773390322;6638.4689224861895;6690.966545167243;6815.591064536144;6862.4853136015;6863.588184708834;6864.4165245954655;6879.89680154798;6520.347965203442;6567.540272180901;6733.192016957898;6742.089817874524;6765.323467653196;6676.86646267072;6709.362610552087;6751.649670066006;6756.628385438749;6767.796966091025;5343.625946183807;5360.142377219704;5360.705161216092;5386.676799392049;5388.515033646912;4789.8323558675975;4796.184648609745;4799.544043315879;4817.108834865561;4835.903281934412;4946.629002824802;5986.191864098291;6248.343923888017;6264.718087685974;6266.717997690115;6880.380765731108;6891.655417229119;6897.206821215625;6918.100646915969;6918.93081069193;6277.7175027123885;6306.400891915261;6370.426661600478;6374.617522948638;6379.521535884827;5315;5617.01702202113;5952.821415357546;6019.849503762353;6045.093236014633;6984.092068031124;6987.580745155307;6987.825304367431;7019.894701579378;7022.683979220845;6777.390034896883;6879.58722476653;6890.793276201522;6916.135483064617;6921.1002569665625;6803.489738269551;6987;7005;7034.824129396806;7038.612876291649;5346.25152478661;5677.5457963362005;6671.499637527108;6676.232233293234;6711.798646040626;5710.572356910772;6739.6967136478;6814.182298124257;6859.5541289816;6872;6483.124778154889;6669.166354205724;6671.232808227116;6677.76627818024;6683.866322673616;6422.3577642235405;6472.288048314776;6546.312637173219;6592.208934927839;6617.239017513048;4465.553444655508;5700.743466543932;6466.612003279818;6477.77327793525;6543.149390579194;6398.840028999312;6441.066045423481;6480.254770701241;6483.805485835433;6643.7674681385915;6407.391297826699;6423.4861211103935;6435.967820160869;6443.742250309935;6449.064885591469 09:00:21 INFO - PID 88162 | |1;ext.html;794.8554082121744;806.1615919443777;834.4659417972497;1227.3924407418328;1296.2999980010795;38360.80819595901;38702;38728;38846.41730374043;38876;39230.80384598073;39362.40956385649;39654.801725991405;39694.80152599233;39724.20551588966;7852.646630901609;7988.121306656263;8100.432969692124;8378.455400398985;8393.202645748644;8281.54451505167;8399.706010289641;8403.159684031578;8423.410361274713;8471.36464765142;8347.207015333533;8402.075771665111;8423.326133909302;8427.536485493296;8441.113683063282;8362.080171181165;8402.369822263354;8404.24361807434;8405.621747021398;8416.537090460022;6987.441004719661;8202.425830191889;8248.051474080497;8249.381296402793;8250.587470626504;35868;35956.74151404688;36090.91727248187;36224.63775362239;36264.912052638465;3531.3820081485637;3549.2901419715904;3557.004038869121;3570.83931223093;3578.2306804037257;30411.391772164654;30563.541546876815;30693.232669183224;30715.539266911015;30747.538786918216;3046.9542956855666;3056.7401770849283;3077.7383922366625;3079.291762894547;3089.6137982752157;241526;243013;271872.6406367975;285930;288251;47193.76403118031;47426.5257347429;47702;47776.522234777905;47794;49278.014439711005;49344.01311973741;49494.75252623714;49528.50471495311;49562.75218623884;14241.64395890111;14272.286385680882;14342.354594043292;14368.425262989364;14386.640333991734;11729.35488548121;11757.294562326288;11774.470148843322;11775;11780.823287650692;13140.34298285082;13211.754001819714;13329.533466328632;13407.195568265935;13430.254066971427;6023.969880150571;6029.638221706711;6066.969665151734;6094.634321940698;6097.60365576245;913.2784302777759;976.9267304952157;1006.6879267426974;1027.6762819711766;1065.2756125834353;23308.883455582614;23603.173888913807;23995.64006539903;24063.398415039763;24077.879610602184;1092.3664274720643;1100.471773548701;1134.4101067444883;1138.0895283773114;1146.4325159046325;11616.477258523386;12251.142420030512;12255.188285981154;12275.2021118627;12278.938605307096;311944;312721.72782722;315266;315407;316363;52923.73538132285;53032.469675303524;53042.7347863266;53183.20225196625;53264.46735532673;1479.7706355514913;1521.8554237347435;1524.390243902448;1528.2740698168282;1531.4792970390122;21692.457688557282;22856.428589285402;23606;23667.171648992204;23766.405839854142;34899.47650785291;36688.81655591705;36727.81636091803;36732.632673672924;36870;196840;197625;197767;197826;198440.00779996006;32253.51619725659;32792.50811237786;33628.15929601682;33630;33660;43107.78446107749;43398.78300608477;44761;44830.55169448394;45141.32288015616;24399.51200975935;28843.711562884942;30007;30954.226144346572;31257;30000.399991999602;30654.773809047387;30678.846605766827;31050.068497945096;31089.844550777103;36081;36221.81889090538;36862.81568592247;36913.815430922674;37077.6292237085;24827.62758558587;30455.62949667226;31325.373492530478;31526.684733152375;31582.36835263328;21597.892010539847;22632.868356581777;23132.884335578216;24345.14791982272;24772.25683229506 09:00:21 INFO - PID 88162 | |2;jquery.html;426.12006207182145;431.60508135056426;436.1734513097679;449.97405914514917;450.28854410032153;19222.135003924803;20953.16187352506;21640.02619882105;21817.345479635635;22032;21663.350099497;22430.775692243074;23077.769222307772;23116.422089447773;23148.537029259405;6066.241719785027;6246.0006398976175;6260.060990851363;6270.968645156768;6272.87454250917;6597.505187110986;6979.616121113337;7024.964875175617;7045.048918396003;7059.152901651809;6690.130283063219;6902.102726645529;6954.791356259295;7013.649317534128;7015.579065256101;5320.866978325535;6440.903386449207;6834.111565496453;6951.513394062417;7007.964960175192;6792.626405547693;6797.320267973212;6810.080639113706;6813.250542440328;6891.827704307382;28440.146795596163;28761.712382876114;29552.556711649344;29595.408091838257;29701.405971880442;2247.235939780466;2291.8968646410954;2296.1619009061737;2296.391456264091;2301.367124040902;17810.287588496445;18706.90646546772;18814.905925470423;19049.7142542862;19089.427317180507;2256.413332533553;2317.0384290519414;2324.1865347128537;2362.842207318419;2374.2877136858874;127778;153178;153212;156307;156938.21530892386;20312.390628281177;22663.773362266165;22743.545129097325;23914.760852391602;24042.918068686602;40380.394294085614;40503.98740031523;40531.59468405337;40588.57939972146;40704.98237544026;6559.44244739193;8730.563471826383;8925.509096999598;8997.055309192572;9038;7172.354488096096;8501.702440414556;8575.399722019523;8585.914140858637;8740.825183496421;6403.583767055126;7021.824454388579;7125.394341480928;7566.621668916532;7938.704795284316;2235.2511908510833;2237.7314722233436;2249.3682391601865;2257.097161135559;2425.4178997040585;880.6257340630335;922.520289449496;939.3096074385321;966.6423423333412;984.4683870709737;20692.689609655867;24636.384090397525;26017.869910650326;26586.202413927615;26744.866275668886;994.945278009702;1000.9299349045497;1011.7672935224939;1018.872640919885;1024.3700124423426;10255.846162307571;10595.364278143335;10866.67399978002;10950.616728414467;11082.113430925507;567522;575074;576940;588580;589653;130384;131080;133594;136120;138128;1194.7252132009687;1220.5117952818944;1243.6704273367745;1245.8131280308025;1248.875112488762;19984.400467985983;20999.37001889946;21533.46166345791;22015.559688806454;22335.888320558293;46100.30849537192;49514.50485495243;55498.44501555094;55941;56071;72007;73932.63033684797;74851.50296994139;75091.62454187694;76805.61597191979;6569.835754106186;8718.346124040596;8736.08271131522;8818;9084.818303633758;12290.139690221598;12898.67753306175;14056.789148162581;14060.437582496586;14433.278336083364;9452.905470945203;11851.348175850066;11971.700707482383;12049.939750301193;12076.584683063247;9768.511574421109;10548.208884333859;11180.832287515856;11546.960773530423;11613.245139065933;11738.237014594024;12109.939450302692;12210.816837747618;12222.816657750318;12224.938875305566;8761.29909607217;8834.955825220834;8853.468791872509;9175.577785443267;9178.862317065115;11311.208215424842;11340.376279304717;11536.673282572612;11775.411229438665;11826.04870196408 09:00:21 INFO - PID 88162 | |3;mootools.html;1270.1680399338434;1397.5108711950818;1401.0822910993297;1410.5909286306971;1414.9504767333146;11229.989300962912;11527.423628818557;11638.941805290982;12045.217060891047;12069;11712.882871171307;11831.645050648494;11894.762104757921;11894.82157767634;11961.641150765468;2506.9874650626766;2544.2367289813074;2550.375158086262;2551.795856331489;2553.10641275553;2491.5888878335054;2556.194798638432;2559.667243258383;2561.7950563954837;2567.536504192611;2476.0343466048257;2495.126705653016;2526.886290116949;2526.9115580954576;2528.898844046236;2487.8880450379775;2515.937101572466;2550.2604244768995;2551.885165167572;2552.706438759548;2114.3445531885163;2385.8329916905823;2499.200255918107;2504.511620234055;2510.14655017295;11595.072394208442;11617;11661.12541559378;11680.824787628193;11683.240589361749;6461.870762584769;6705.564138330993;6719.328067193291;6726.764563240312;6736.730530778763;3875.709321800848;4258.552851950562;4287.678424118172;4327.0480494291205;4342.436722779797;1182.1783860217151;1191.9702007449796;1222.1261797814516;1241.8633950265464;1245.9377031148406;12754.660760620187;13604.727905441836;14676.853231467656;15936.282867270884;16198.433054843024;5273.525382715574;5337.066013447631;5485.424030476823;5523.972380138073;5533.584981126432;11674.00770934463;11736.765264694659;11761.764764704658;11766.235194712317;11974.161808673483;6457.838554036092;6482.546221764525;6485.967570162119;6497.870042599122;6502.317256688074;5068.771905264272;5566.55467562594;5925;6019.337872833985;6054.788082417094;3625.746197766131;3954.9604503955165;4139.441175441277;4272.9359059614135;4338.305871060616;1068.3109394440542;1268.6130730127215;1271.9364031798373;1284.3064745037761;1289.5615490733007;634.6667999300404;790.9169537198511;808.4219782855287;814.2752949874616;840.1220724343076;5457.399686034533;5996.430339117776;6302.464290535356;6827.249002609709;6862.622555759481;930.251147825993;933.1881263300971;938.2634631813993;948.7144918635288;969.8448248280241;4222.78886055696;4294.312909934458;4343.587359200871;4407.162639098561;4452.443444569429;14601.977861549589;14983.475578354923;15055.623609409853;15086;15213.31540080699;14381.92809035948;14547.709045819025;15029.023113497806;15067.246637668073;15101.773473397909;8434.873476897852;8461.153884611613;8618.956905215433;8620.396572240008;8633.611487483078;5428.348598168087;5949.91075133882;6588.472922166311;6616.636085015371;6756.527043106936;11068.833967490655;12909.35453227316;13147.14543554666;13243.139195952232;13341.66645833862;13448;13636.454541818217;13693.520726774914;13785.793213102012;13791.10357826738;9054.275657947217;9104.499252540909;9182.311326650395;9259.814803703754;9304.95347523258;10400.47997600102;10489.423081730578;10504.054635082879;10509.264351495322;10514.57941682324;8638.91361086381;9107.316951228551;9168;9192.31057670664;9276.211522020703;8831.69089081879;8868.822623547621;8974.14745599167;8976.326775491994;9218;8029.47808392476;8180.7954801130445;8254.215849494289;8283.544405057779;8311.542865142475;5973.402659734165;5991.101334799815;6069.453749162597;6140.907886381798;6172.567920245539;5693.4021927696995;6017.15759793638;6149.938500614937;6195.411435913581;6327.525435592441 09:00:21 INFO - PID 88162 | |4;prototype.html;311.8331692544489;384.8268279274327;400.25552472401336;403.6185804414829;411.7817556694953;4817.421909370872;5219.765110570029;6204.41058099481;6327.5887067340645;6372.426481616653;5809.883802323951;5986.2517185351835;6026.450030227184;6129.601575897575;6163.137160797481;4475.1944649963;4525.592696657301;4690.132325519773;4724.834630787923;4753.33453316536;3852.9036774080732;3853.65317121459;4003.779792111433;4153.8338466461455;4728.361671174399;3624.746267761258;4425.645948324126;4660.557247061523;4687.460941991681;4706.81133273028;4676.462206846222;4696.95303046972;4702.224133018048;4705.811767529295;4721;3446.965530344715;4078;4169.291220492493;4180.435641188431;4197.349410841324;4950.455449900508;5952.898713737973;5995.370486098984;6042.9395706042815;6056.939430605682;5640.912862240997;5650.0959846424375;5658.519025882805;5660.29246344207;5661.207430959669;6446.967765161145;6561;6592.351912021994;6638.137042184485;6674.498237896483;4779.737114458703;4797.976010119962;4805.711657300573;4817.807287708488;4825.445073816521;5859.802574034737;6341.936580634181;6926.619035953021;6979.546329488618;7137.607431591312;3646.799426031541;3899.4150877368056;4267.935980960288;4333.198358303661;4433.645308375322;3834.9616503835164;4487.63127246193;4499.955000450019;4526.642007397768;4527.868032991752;5102.923456148161;5291.703532634452;5381.27352807366;5385.461453854583;5401.405845357008;6169.753209871645;6683.331666833278;6700.932990670129;6702.932970670328;6708.92023472725;1336.4386957477882;1486;1521.4218596933094;1545.9768103478457;1546.6829299993474;831.6132998155765;966.3477152921749;991.2169386184977;999.7900440907346;1008.5814387029301;6238.344973777688;6293.181886354744;6296.5592408531875;6299.842503937438;6318.241810982619;5906.763729450774;6546.083548303146;6560.311167327457;6881.552699074544;6908.965455172692;5731.971340143273;6138.1713468681155;6213.2233470816145;6232.844178895473;6251.9687401563615;3014.156036309837;3061.479548476764;3618.891433256954;3631.891043268706;3634.491171236029;6710.463162946952;7070.505064645426;7136.500444968802;7141.678624461808;7161.498695091398;6489.935100649028;6769.018492318586;7173.744594695907;7193.3166349196745;7218.783436496913;6573.934260657428;6841.828954276083;7029.859402811915;7108.644567771694;7111.751088711871;5645.633033852787;6693.397594216543;6860.622665753432;6917.584944903322;6937;6243.250809902841;6307.400796924285;6864.313568643295;6868.862622747417;6876.931230687629;5170.157721774371;6678.732850686112;6835.4189893859575;6974.37230649244;7111.288871112847;4340.631046360968;4402.757848318373;4409.3606427068535;4423.513413524445;4435.558443505809;5791.623544469596;6994;6997.265287144776;7021.192562855337;7046.647667616701;5799.82600521985;6638.468922486093;6696.229933557704;6761.797146085625;6791.490638202254;6712.765053223114;6721.93278067213;6729.158855143107;6736.7642132525125;6786;6007.669578173242;6394.520410969289;6442.806715798346;6535.44448721864;6619.801405957829;6359.618422894641;6627.6354800484505;6756.966215168893;6758.966205168943;6789.685592399116;5921.585489015728;6381.819363417782;6582.683463307453;6588.8023359299295;6673.899891501728 09:00:21 INFO - PID 88162 | |5;yui.html;808.7747063199254;835.2566216067697;843.0010437631407;843.7173546861801;864.9827003459931;14332.355044023017;14873.033252838557;16202.513924582267;16280.104594247327;16512.422065227704;15652.295646695897;17135;17361.565960850952;17445.040522771244;17539.38612148575;995.6117114325419;1018.0481250031207;1019.0115587879782;1020.9846852297221;1022.7852151048289;1015.8120747661669;1016;1019.3476175247847;1020.0921180149672;1021.0657248617543;1064.0317311246768;1067.941263230522;1072.5870539842178;1076.0261962923566;1076.993011534218;1066.6000249906285;1069.096613361708;1071.3090056913286;1072.0833687197412;1073.2433634287809;675.9320273967169;706.703199628682;781.0393216343872;785.1206648553657;791.1218547412368;16273.349066037345;16547.83452165475;16642.50072497827;16875.915620421943;16886.240119194666;15971.520854374387;16711.99728016323;16829.91585042079;16866;16916.66166676672;14072.85927140736;14519.709605807824;14601;14639.63400914975;14736.48422305214;3566.1619519412916;3604.5854726706502;3663.303972245265;3666.816659167058;3669.101070237809;129637.70362296444;130655;132688;133838;134003.6599634001;10845.566177352817;11397.886021139682;13024.283664398548;13431.597052088453;13475.056746027683;21137.577248454945;24822.890054345582;25561.488770224492;25758.742412576008;25846.87076564643;7626.161122276545;7907.48601340911;7926.4847784894955;7934.523928564305;7970.960145199237;3962.9603703963166;4019.537753158424;4053.1893621275863;4054.270231358325;4070.6946978976102;4019.9799001005395;4132.648724858361;4134.111166099303;4139.337705967031;4144.5026596807975;867.6572753762229;886.2732559301439;894.7763059235191;896.7847716547982;897.9236764874928;628.4909223528956;646.1922596754057;648.1800522339245;653.9346065393422;684.9383555480031;11129.72175695598;11762.88237117635;12097.697557561132;12102.135349693908;12209.145359824726;608.3217212807776;635.1679300116862;649.6297110646981;661.1042038038485;677.9525433219726;4274.893127671771;5341.370463546451;5355.625106242525;5598.496135347838;5645.125005624135;109036;111168.88831111585;112090.43954780337;113374;114940;96547.51726241324;96786.54820177703;97430;97663.09347626015;98856.50571747095;74548.25451745413;86632;87718;88456;88578.55710721404;12103.757924841279;12240.265584064984;12546.686332841751;12564.748705026032;12802.935985320013;38411;38426.23147537089;39606.00984975399;39900.60099398969;40005.19976601118;51067.74466127794;51088.46734597968;51107.488925111764;51372.48627513677;51375.71560711012;14665.92667036658;15884;16165;17160.39938602143;17331.913340433217;17809.643807124045;17974.730379044588;18601.627967440847;18763.061846907327;18925.621487570446;16022.679546408774;16055.839441605902;17128.40050598223;17333.133343333036;17350.21924013397;16870.409535666193;17031.14844257807;17170.313187472362;17371.91314043472;17673.20470578801;15761.290741916411;16038.358465661242;16667.833321667113;16904.57738556546;16978.830211697725;9155.77110572241;11570.537178512765;11748.118891083373;11748.647540573797;11967.940160299142;10974.561017559208;13186.274754888573;13192.67018324511;13285.269310187647;13763.587092387244 09:00:21 INFO - PID 88162 | __end_tp_report 09:00:21 INFO - PID 88162 | __start_cc_report 09:00:21 INFO - PID 88162 | _x_x_mozilla_cycle_collect,353 09:00:21 INFO - PID 88162 | __end_cc_report 09:00:21 INFO - PID 88162 | __startTimestamp1495555221490__endTimestamp 09:00:21 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | ------- Summary: start ------- 09:00:21 INFO - PID 88162 | Number of tests: 6 09:00:21 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | [#0] dojo.html Cycles:165 Average:6160.90 Median:6407.39 stddev:770.59 (12.0%) stddev-sans-first:744.57 09:00:21 INFO - PID 88162 | Values: 3519.5 3533.2 3580.4 3594.5 3601.5 6095.6 6172.8 6197.4 6208.6 6233.8 6112.7 6155.1 6162.8 6165.0 6230.8 5406.1 5457.4 5462.9 5481.2 5560.1 5334.5 5524.2 5551.8 5565.4 5576.9 5535.7 5599.6 5610.8 5628.0 5671.9 5409.5 5435.1 5456.8 5482.2 5527.0 5438.3 5439.1 5443.0 5453.6 5484.0 4691.6 5615.0 5742.4 6122.3 6236.6 6513.1 6704.4 6705.8 6706.2 6719.5 5640.5 6708.0 6826.9 6827.3 6831.2 6344.8 6348.7 6352.1 6365.9 6376.4 6868.2 6957.7 6963.9 6963.9 6982.8 6455.2 6493.7 6591.4 6638.5 6691.0 6815.6 6862.5 6863.6 6864.4 6879.9 6520.3 6567.5 6733.2 6742.1 6765.3 6676.9 6709.4 6751.6 6756.6 6767.8 5343.6 5360.1 5360.7 5386.7 5388.5 4789.8 4796.2 4799.5 4817.1 4835.9 4946.6 5986.2 6248.3 6264.7 6266.7 6880.4 6891.7 6897.2 6918.1 6918.9 6277.7 6306.4 6370.4 6374.6 6379.5 5315.0 5617.0 5952.8 6019.8 6045.1 6984.1 6987.6 6987.8 7019.9 7022.7 6777.4 6879.6 6890.8 6916.1 6921.1 6803.5 6987.0 7005.0 7034.8 7038.6 5346.3 5677.5 6671.5 6676.2 6711.8 5710.6 6739.7 6814.2 6859.6 6872.0 6483.1 6669.2 6671.2 6677.8 6683.9 6422.4 6472.3 6546.3 6592.2 6617.2 4465.6 5700.7 6466.6 6477.8 6543.1 6398.8 6441.1 6480.3 6483.8 6643.8 6407.4 6423.5 6436.0 6443.7 6449.1 09:00:21 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | [#1] ext.html Cycles:180 Average:41494.39 Median:32523.01 stddev:69136.12 (212.6%) stddev-sans-first:69262.53 09:00:21 INFO - PID 88162 | Values: 794.9 806.2 834.5 1227.4 1296.3 38360.8 38702.0 38728.0 38846.4 38876.0 39230.8 39362.4 39654.8 39694.8 39724.2 7852.6 7988.1 8100.4 8378.5 8393.2 8281.5 8399.7 8403.2 8423.4 8471.4 8347.2 8402.1 8423.3 8427.5 8441.1 8362.1 8402.4 8404.2 8405.6 8416.5 6987.4 8202.4 8248.1 8249.4 8250.6 35868.0 35956.7 36090.9 36224.6 36264.9 3531.4 3549.3 3557.0 3570.8 3578.2 30411.4 30563.5 30693.2 30715.5 30747.5 3047.0 3056.7 3077.7 3079.3 3089.6 241526.0 243013.0 271872.6 285930.0 288251.0 47193.8 47426.5 47702.0 47776.5 47794.0 49278.0 49344.0 49494.8 49528.5 49562.8 14241.6 14272.3 14342.4 14368.4 14386.6 11729.4 11757.3 11774.5 11775.0 11780.8 13140.3 13211.8 13329.5 13407.2 13430.3 6024.0 6029.6 6067.0 6094.6 6097.6 913.3 976.9 1006.7 1027.7 1065.3 23308.9 23603.2 23995.6 24063.4 24077.9 1092.4 1100.5 1134.4 1138.1 1146.4 11616.5 12251.1 12255.2 12275.2 12278.9 311944.0 312721.7 315266.0 315407.0 316363.0 52923.7 53032.5 53042.7 53183.2 53264.5 1479.8 1521.9 1524.4 1528.3 1531.5 21692.5 22856.4 23606.0 23667.2 23766.4 34899.5 36688.8 36727.8 36732.6 36870.0 196840.0 197625.0 197767.0 197826.0 198440.0 32253.5 32792.5 33628.2 33630.0 33660.0 43107.8 43398.8 44761.0 44830.6 45141.3 24399.5 28843.7 30007.0 30954.2 31257.0 30000.4 30654.8 30678.8 31050.1 31089.8 36081.0 36221.8 36862.8 36913.8 37077.6 24827.6 30455.6 31325.4 31526.7 31582.4 21597.9 22632.9 23132.9 24345.1 24772.3 09:00:21 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | [#2] jquery.html Cycles:180 Average:37416.36 Median:26665.53 stddev:97614.82 (366.1%) stddev-sans-first:97849.14 09:00:21 INFO - PID 88162 | Values: 426.1 431.6 436.2 450.0 450.3 19222.1 20953.2 21640.0 21817.3 22032.0 21663.4 22430.8 23077.8 23116.4 23148.5 6066.2 6246.0 6260.1 6271.0 6272.9 6597.5 6979.6 7025.0 7045.0 7059.2 6690.1 6902.1 6954.8 7013.6 7015.6 5320.9 6440.9 6834.1 6951.5 7008.0 6792.6 6797.3 6810.1 6813.3 6891.8 28440.1 28761.7 29552.6 29595.4 29701.4 2247.2 2291.9 2296.2 2296.4 2301.4 17810.3 18706.9 18814.9 19049.7 19089.4 2256.4 2317.0 2324.2 2362.8 2374.3 127778.0 153178.0 153212.0 156307.0 156938.2 20312.4 22663.8 22743.5 23914.8 24042.9 40380.4 40504.0 40531.6 40588.6 40705.0 6559.4 8730.6 8925.5 8997.1 9038.0 7172.4 8501.7 8575.4 8585.9 8740.8 6403.6 7021.8 7125.4 7566.6 7938.7 2235.3 2237.7 2249.4 2257.1 2425.4 880.6 922.5 939.3 966.6 984.5 20692.7 24636.4 26017.9 26586.2 26744.9 994.9 1000.9 1011.8 1018.9 1024.4 10255.8 10595.4 10866.7 10950.6 11082.1 567522.0 575074.0 576940.0 588580.0 589653.0 130384.0 131080.0 133594.0 136120.0 138128.0 1194.7 1220.5 1243.7 1245.8 1248.9 19984.4 20999.4 21533.5 22015.6 22335.9 46100.3 49514.5 55498.4 55941.0 56071.0 72007.0 73932.6 74851.5 75091.6 76805.6 6569.8 8718.3 8736.1 8818.0 9084.8 12290.1 12898.7 14056.8 14060.4 14433.3 9452.9 11851.3 11971.7 12049.9 12076.6 9768.5 10548.2 11180.8 11547.0 11613.2 11738.2 12109.9 12210.8 12222.8 12224.9 8761.3 8835.0 8853.5 9175.6 9178.9 11311.2 11340.4 11536.7 11775.4 11826.0 09:00:21 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | [#3] mootools.html Cycles:180 Average:7001.83 Median:3750.73 stddev:4386.78 (117.0%) stddev-sans-first:4377.94 09:00:21 INFO - PID 88162 | Values: 1270.2 1397.5 1401.1 1410.6 1415.0 11230.0 11527.4 11638.9 12045.2 12069.0 11712.9 11831.6 11894.8 11894.8 11961.6 2507.0 2544.2 2550.4 2551.8 2553.1 2491.6 2556.2 2559.7 2561.8 2567.5 2476.0 2495.1 2526.9 2526.9 2528.9 2487.9 2515.9 2550.3 2551.9 2552.7 2114.3 2385.8 2499.2 2504.5 2510.1 11595.1 11617.0 11661.1 11680.8 11683.2 6461.9 6705.6 6719.3 6726.8 6736.7 3875.7 4258.6 4287.7 4327.0 4342.4 1182.2 1192.0 1222.1 1241.9 1245.9 12754.7 13604.7 14676.9 15936.3 16198.4 5273.5 5337.1 5485.4 5524.0 5533.6 11674.0 11736.8 11761.8 11766.2 11974.2 6457.8 6482.5 6486.0 6497.9 6502.3 5068.8 5566.6 5925.0 6019.3 6054.8 3625.7 3955.0 4139.4 4272.9 4338.3 1068.3 1268.6 1271.9 1284.3 1289.6 634.7 790.9 808.4 814.3 840.1 5457.4 5996.4 6302.5 6827.2 6862.6 930.3 933.2 938.3 948.7 969.8 4222.8 4294.3 4343.6 4407.2 4452.4 14602.0 14983.5 15055.6 15086.0 15213.3 14381.9 14547.7 15029.0 15067.2 15101.8 8434.9 8461.2 8619.0 8620.4 8633.6 5428.3 5949.9 6588.5 6616.6 6756.5 11068.8 12909.4 13147.1 13243.1 13341.7 13448.0 13636.5 13693.5 13785.8 13791.1 9054.3 9104.5 9182.3 9259.8 9305.0 10400.5 10489.4 10504.1 10509.3 10514.6 8638.9 9107.3 9168.0 9192.3 9276.2 8831.7 8868.8 8974.1 8976.3 9218.0 8029.5 8180.8 8254.2 8283.5 8311.5 5973.4 5991.1 6069.5 6140.9 6172.6 5693.4 6017.2 6149.9 6195.4 6327.5 09:00:21 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | [#4] prototype.html Cycles:180 Average:5373.67 Median:6133.89 stddev:1714.97 (28.0%) stddev-sans-first:1677.17 09:00:21 INFO - PID 88162 | Values: 311.8 384.8 400.3 403.6 411.8 4817.4 5219.8 6204.4 6327.6 6372.4 5809.9 5986.3 6026.5 6129.6 6163.1 4475.2 4525.6 4690.1 4724.8 4753.3 3852.9 3853.7 4003.8 4153.8 4728.4 3624.7 4425.6 4660.6 4687.5 4706.8 4676.5 4697.0 4702.2 4705.8 4721.0 3447.0 4078.0 4169.3 4180.4 4197.3 4950.5 5952.9 5995.4 6042.9 6056.9 5640.9 5650.1 5658.5 5660.3 5661.2 6447.0 6561.0 6592.4 6638.1 6674.5 4779.7 4798.0 4805.7 4817.8 4825.4 5859.8 6341.9 6926.6 6979.5 7137.6 3646.8 3899.4 4267.9 4333.2 4433.6 3835.0 4487.6 4500.0 4526.6 4527.9 5102.9 5291.7 5381.3 5385.5 5401.4 6169.8 6683.3 6700.9 6702.9 6708.9 1336.4 1486.0 1521.4 1546.0 1546.7 831.6 966.3 991.2 999.8 1008.6 6238.3 6293.2 6296.6 6299.8 6318.2 5906.8 6546.1 6560.3 6881.6 6909.0 5732.0 6138.2 6213.2 6232.8 6252.0 3014.2 3061.5 3618.9 3631.9 3634.5 6710.5 7070.5 7136.5 7141.7 7161.5 6489.9 6769.0 7173.7 7193.3 7218.8 6573.9 6841.8 7029.9 7108.6 7111.8 5645.6 6693.4 6860.6 6917.6 6937.0 6243.3 6307.4 6864.3 6868.9 6876.9 5170.2 6678.7 6835.4 6974.4 7111.3 4340.6 4402.8 4409.4 4423.5 4435.6 5791.6 6994.0 6997.3 7021.2 7046.6 5799.8 6638.5 6696.2 6761.8 6791.5 6712.8 6721.9 6729.2 6736.8 6786.0 6007.7 6394.5 6442.8 6535.4 6619.8 6359.6 6627.6 6757.0 6759.0 6789.7 5921.6 6381.8 6582.7 6588.8 6673.9 09:00:21 INFO - PID 88162 | 09:00:21 INFO - PID 88162 | [#5] yui.html Cycles:180 Average:22259.80 Median:17332.52 stddev:32326.24 (186.5%) stddev-sans-first:32376.80 09:00:21 INFO - PID 88162 | Values: 808.8 835.3 843.0 843.7 865.0 14332.4 14873.0 16202.5 16280.1 16512.4 15652.3 17135.0 17361.6 17445.0 17539.4 995.6 1018.0 1019.0 1021.0 1022.8 1015.8 1016.0 1019.3 1020.1 1021.1 1064.0 1067.9 1072.6 1076.0 1077.0 1066.6 1069.1 1071.3 1072.1 1073.2 675.9 706.7 781.0 785.1 791.1 16273.3 16547.8 16642.5 16875.9 16886.2 15971.5 16712.0 16829.9 16866.0 16916.7 14072.9 14519.7 14601.0 14639.6 14736.5 3566.2 3604.6 3663.3 3666.8 3669.1 129637.7 130655.0 132688.0 133838.0 134003.7 10845.6 11397.9 13024.3 13431.6 13475.1 21137.6 24822.9 25561.5 25758.7 25846.9 7626.2 7907.5 7926.5 7934.5 7971.0 3963.0 4019.5 4053.2 4054.3 4070.7 4020.0 4132.6 4134.1 4139.3 4144.5 867.7 886.3 894.8 896.8 897.9 628.5 646.2 648.2 653.9 684.9 11129.7 11762.9 12097.7 12102.1 12209.1 608.3 635.2 649.6 661.1 678.0 4274.9 5341.4 5355.6 5598.5 5645.1 109036.0 111168.9 112090.4 113374.0 114940.0 96547.5 96786.5 97430.0 97663.1 98856.5 74548.3 86632.0 87718.0 88456.0 88578.6 12103.8 12240.3 12546.7 12564.7 12802.9 38411.0 38426.2 39606.0 39900.6 40005.2 51067.7 51088.5 51107.5 51372.5 51375.7 14665.9 15884.0 16165.0 17160.4 17331.9 17809.6 17974.7 18601.6 18763.1 18925.6 16022.7 16055.8 17128.4 17333.1 17350.2 16870.4 17031.1 17170.3 17371.9 17673.2 15761.3 16038.4 16667.8 16904.6 16978.8 9155.8 11570.5 11748.1 11748.6 11967.9 10974.6 13186.3 13192.7 13285.3 13763.6 09:00:21 INFO - PID 88162 | -------- Summary: end -------- 09:00:21 INFO - PID 88162 | 09:00:22 INFO - TEST-INFO | 88162: exit 0 09:00:22 INFO - TEST-OK | dromaeo_css | took 1113096ms 09:00:22 INFO - TEST-START | kraken 09:00:22 INFO - operating with platform_type : mac_ 09:00:22 INFO - Initialising browser for kraken test... 09:00:22 INFO - TEST-INFO | started process 88168 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmptNRLMv/profile http://localhost:54620/getInfo.html) 09:00:22 INFO - PID 88168 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 09:00:24 INFO - PID 88168 | 2017-05-23 09:00:24.860 plugin-container[88169:821619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963b, name = 'com.apple.tsm.portname' 09:00:24 INFO - PID 88168 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:24 INFO - PID 88168 | 2017-05-23 09:00:24.864 plugin-container[88169:821619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 09:00:24 INFO - PID 88168 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:24 INFO - PID 88168 | 2017-05-23 09:00:24.865 plugin-container[88169:821619] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:24 INFO - PID 88168 | __metrics Screen width/height:1600/1200 09:00:24 INFO - PID 88168 | colorDepth:24 09:00:24 INFO - PID 88168 | Browser inner width/height: 1024/691 09:00:24 INFO - PID 88168 | __metrics 09:00:26 INFO - TEST-INFO | 88168: exit 0 09:00:26 INFO - Browser initialized. 09:00:26 INFO - Running cycle 1/1 for kraken test... 09:00:26 INFO - TEST-INFO | started process 88171 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmptNRLMv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:00:28 INFO - PID 88171 | 2017-05-23 09:00:28.146 plugin-container[88172:821770] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9943, name = 'com.apple.tsm.portname' 09:00:28 INFO - PID 88171 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:28 INFO - PID 88171 | 2017-05-23 09:00:28.147 plugin-container[88172:821770] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 09:00:28 INFO - PID 88171 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:28 INFO - PID 88171 | 2017-05-23 09:00:28.147 plugin-container[88172:821770] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:28 INFO - PID 88171 | 2017-05-23 09:00:28.962 plugin-container[88173:821814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 09:00:28 INFO - PID 88171 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:28 INFO - PID 88171 | 2017-05-23 09:00:28.962 plugin-container[88173:821814] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0f, name = 'com.apple.CFPasteboardClient' 09:00:28 INFO - PID 88171 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:28 INFO - PID 88171 | 2017-05-23 09:00:28.962 plugin-container[88173:821814] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:38 INFO - PID 88171 | RSS: Main: 242319360 09:00:38 INFO - PID 88171 | 09:00:40 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54620/tests/kraken/driver.html?testName=audio-beat-detection) 09:00:41 INFO - PID 88171 | RSS: Main: 254398464 09:00:41 INFO - PID 88171 | 09:00:44 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54620/tests/kraken/driver.html?testName=audio-dft) 09:00:45 INFO - PID 88171 | RSS: Main: 263499776 09:00:45 INFO - PID 88171 | 09:00:48 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54620/tests/kraken/driver.html?testName=audio-fft) 09:00:49 INFO - PID 88171 | RSS: Main: 265277440 09:00:49 INFO - PID 88171 | 09:00:51 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54620/tests/kraken/driver.html?testName=audio-oscillator) 09:00:51 INFO - PID 88171 | RSS: Main: 266461184 09:00:51 INFO - PID 88171 | 09:00:53 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54620/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:00:54 INFO - PID 88171 | RSS: Main: 266891264 09:00:54 INFO - PID 88171 | 09:00:57 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54620/tests/kraken/driver.html?testName=imaging-darkroom) 09:00:57 INFO - PID 88171 | RSS: Main: 276471808 09:00:57 INFO - PID 88171 | 09:01:00 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54620/tests/kraken/driver.html?testName=imaging-desaturate) 09:01:01 INFO - PID 88171 | RSS: Main: 277671936 09:01:01 INFO - PID 88171 | 09:01:04 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54620/tests/kraken/driver.html?testName=json-parse-financial) 09:01:04 INFO - PID 88171 | RSS: Main: 276856832 09:01:04 INFO - PID 88171 | 09:01:06 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54620/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:01:06 INFO - PID 88171 | RSS: Main: 276258816 09:01:06 INFO - PID 88171 | 09:01:08 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54620/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:01:09 INFO - PID 88171 | RSS: Main: 276131840 09:01:09 INFO - PID 88171 | 09:01:11 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54620/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:01:11 INFO - PID 88171 | RSS: Main: 276078592 09:01:11 INFO - PID 88171 | 09:01:14 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54620/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:01:15 INFO - PID 88171 | RSS: Main: 275767296 09:01:15 INFO - PID 88171 | 09:01:18 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54620/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:01:19 INFO - PID 88171 | RSS: Main: 276049920 09:01:19 INFO - PID 88171 | 09:01:20 INFO - PID 88171 | Cycle 1(1): loaded http://localhost:54620/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54620/tests/kraken/driver.html?testName=ai-astar) 09:01:21 INFO - PID 88171 | RSS: Main: 277929984 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | __start_tp_report 09:01:21 INFO - PID 88171 | _x_x_mozilla_page_load 09:01:21 INFO - PID 88171 | _x_x_mozilla_page_load_details 09:01:21 INFO - PID 88171 | |i|pagename|runs| 09:01:21 INFO - PID 88171 | |0;ai-astar;112;119;116;115;114;115;114;114;115;115 09:01:21 INFO - PID 88171 | |1;audio-beat-detection;135;110;111;158;144;128;111;108;153;103 09:01:21 INFO - PID 88171 | |2;audio-dft;202;211;282;252;207;256;181;271;278;201 09:01:21 INFO - PID 88171 | |3;audio-fft;83;56;61;84;57;58;60;57;74;78 09:01:21 INFO - PID 88171 | |4;audio-oscillator;88;70;84;74;69;68;88;72;71;90 09:01:21 INFO - PID 88171 | |5;imaging-gaussian-blur;89;89;90;89;89;88;89;89;88;89 09:01:21 INFO - PID 88171 | |6;imaging-darkroom;97;96;97;94;97;96;97;96;96;95 09:01:21 INFO - PID 88171 | |7;imaging-desaturate;92;93;91;92;92;92;90;93;92;94 09:01:21 INFO - PID 88171 | |8;json-parse-financial;51;50;50;51;51;52;52;53;51;50 09:01:21 INFO - PID 88171 | |9;json-stringify-tinderbox;56;66;65;58;65;61;59;59;60;58 09:01:21 INFO - PID 88171 | |10;stanford-crypto-aes;85;81;85;83;81;81;81;86;82;84 09:01:21 INFO - PID 88171 | |11;stanford-crypto-ccm;138;136;138;143;151;146;140;145;189;128 09:01:21 INFO - PID 88171 | |12;stanford-crypto-pbkdf2;189;188;181;184;186;189;221;184;183;186 09:01:21 INFO - PID 88171 | |13;stanford-crypto-sha256-iterative;67;69;66;67;68;65;68;67;67;69 09:01:21 INFO - PID 88171 | __end_tp_report 09:01:21 INFO - PID 88171 | __start_cc_report 09:01:21 INFO - PID 88171 | _x_x_mozilla_cycle_collect,924 09:01:21 INFO - PID 88171 | __end_cc_report 09:01:21 INFO - PID 88171 | __startTimestamp1495555281141__endTimestamp 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | ------- Summary: start ------- 09:01:21 INFO - PID 88171 | Number of tests: 14 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#0] ai-astar Cycles:10 Average:114.90 Median:115.00 stddev:1.79 (1.6%) stddev-sans-first:1.56 09:01:21 INFO - PID 88171 | Values: 112.0 119.0 116.0 115.0 114.0 115.0 114.0 114.0 115.0 115.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:131.50 stddev:20.33 (15.5%) stddev-sans-first:21.31 09:01:21 INFO - PID 88171 | Values: 135.0 110.0 111.0 158.0 144.0 128.0 111.0 108.0 153.0 103.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#2] audio-dft Cycles:10 Average:234.10 Median:254.00 stddev:37.41 (14.7%) stddev-sans-first:37.84 09:01:21 INFO - PID 88171 | Values: 202.0 211.0 282.0 252.0 207.0 256.0 181.0 271.0 278.0 201.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#3] audio-fft Cycles:10 Average:66.80 Median:67.50 stddev:11.55 (17.1%) stddev-sans-first:10.67 09:01:21 INFO - PID 88171 | Values: 83.0 56.0 61.0 84.0 57.0 58.0 60.0 57.0 74.0 78.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.00 stddev:8.96 (11.3%) stddev-sans-first:8.64 09:01:21 INFO - PID 88171 | Values: 88.0 70.0 84.0 74.0 69.0 68.0 88.0 72.0 71.0 90.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 09:01:21 INFO - PID 88171 | Values: 89.0 89.0 90.0 89.0 89.0 88.0 89.0 89.0 88.0 89.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:96.50 stddev:0.99 (1.0%) stddev-sans-first:1.00 09:01:21 INFO - PID 88171 | Values: 97.0 96.0 97.0 94.0 97.0 96.0 97.0 96.0 96.0 95.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:92.00 stddev:1.10 (1.2%) stddev-sans-first:1.17 09:01:21 INFO - PID 88171 | Values: 92.0 93.0 91.0 92.0 92.0 92.0 90.0 93.0 92.0 94.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.00 stddev:0.99 (1.9%) stddev-sans-first:1.05 09:01:21 INFO - PID 88171 | Values: 51.0 50.0 50.0 51.0 51.0 52.0 52.0 53.0 51.0 50.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:60.50 stddev:3.47 (5.7%) stddev-sans-first:3.23 09:01:21 INFO - PID 88171 | Values: 56.0 66.0 65.0 58.0 65.0 61.0 59.0 59.0 60.0 58.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:83.50 stddev:1.97 (2.4%) stddev-sans-first:1.94 09:01:21 INFO - PID 88171 | Values: 85.0 81.0 85.0 83.0 81.0 81.0 81.0 86.0 82.0 84.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:144.00 stddev:16.56 (11.5%) stddev-sans-first:17.35 09:01:21 INFO - PID 88171 | Values: 138.0 136.0 138.0 143.0 151.0 146.0 140.0 145.0 189.0 128.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.10 Median:187.00 stddev:11.51 (6.2%) stddev-sans-first:12.21 09:01:21 INFO - PID 88171 | Values: 189.0 188.0 181.0 184.0 186.0 189.0 221.0 184.0 183.0 186.0 09:01:21 INFO - PID 88171 | 09:01:21 INFO - PID 88171 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.25 (1.9%) stddev-sans-first:1.32 09:01:21 INFO - PID 88171 | Values: 67.0 69.0 66.0 67.0 68.0 65.0 68.0 67.0 67.0 69.0 09:01:21 INFO - PID 88171 | -------- Summary: end -------- 09:01:21 INFO - PID 88171 | 09:01:21 INFO - TEST-INFO | 88171: exit 0 09:01:22 INFO - TEST-OK | kraken | took 59829ms 09:01:22 INFO - SUITE-END | took 1172s 09:01:22 INFO - Completed test suite (00:19:33) 09:01:22 INFO - javascript benchmark 09:01:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7940.703592955388, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3519.5248641433413, 3533.240353324035, 3580.373434648936, 3594.4967704521355, 3601.531800865887, 6095.5733098682995, 6172.7839525616555, 6197.380261973806, 6208.627482351062, 6233.812985610433, 6112.746658179501, 6155.076738489229, 6162.7534898604, 6164.969175154118, 6230.84422889428, 5406.08096623573, 5457.372402173742, 5462.945370546284, 5481.205225242347, 5560.110382338827, 5334.493223143795, 5524.226608274845, 5551.750171242284, 5565.38780734121, 5576.86057848555, 5535.723213839292, 5599.552035837143, 5610.775568977236, 5628.0, 5671.886562268752, 5409.459054094598, 5435.103207970719, 5456.7544460499375, 5482.17767334899, 5527.0, 5438.347398312228, 5439.129739241742, 5442.9727851360885, 5453.563714902838, 5483.985462689415, 4691.6481263905, 5614.9893019256515, 5742.425757424266, 6122.326544080148, 6236.563440559163, 6513.120728701612, 6704.430123439513, 6705.832354191136, 6706.161729783777, 6719.462443004548, 5640.520555752766, 6707.993800929851, 6826.897596536056, 6827.2831352708245, 6831.2144103428245, 6344.809655710336, 6348.682565871734, 6352.142460767784, 6365.936340636535, 6376.426121649074, 6868.175818901764, 6957.65211739411, 6963.8607227855155, 6963.895541566881, 6982.825429364205, 6455.193100862392, 6493.740250389931, 6591.406773390322, 6638.4689224861895, 6690.966545167243, 6815.591064536144, 6862.4853136015, 6863.588184708834, 6864.4165245954655, 6879.89680154798, 6520.347965203442, 6567.540272180901, 6733.192016957898, 6742.089817874524, 6765.323467653196, 6676.86646267072, 6709.362610552087, 6751.649670066006, 6756.628385438749, 6767.796966091025, 5343.625946183807, 5360.142377219704, 5360.705161216092, 5386.676799392049, 5388.515033646912, 4789.8323558675975, 4796.184648609745, 4799.544043315879, 4817.108834865561, 4835.903281934412, 4946.629002824802, 5986.191864098291, 6248.343923888017, 6264.718087685974, 6266.717997690115, 6880.380765731108, 6891.655417229119, 6897.206821215625, 6918.100646915969, 6918.93081069193, 6277.7175027123885, 6306.400891915261, 6370.426661600478, 6374.617522948638, 6379.521535884827, 5315.0, 5617.01702202113, 5952.821415357546, 6019.849503762353, 6045.093236014633, 6984.092068031124, 6987.580745155307, 6987.825304367431, 7019.894701579378, 7022.683979220845, 6777.390034896883, 6879.58722476653, 6890.793276201522, 6916.135483064617, 6921.1002569665625, 6803.489738269551, 6987.0, 7005.0, 7034.824129396806, 7038.612876291649, 5346.25152478661, 5677.5457963362005, 6671.499637527108, 6676.232233293234, 6711.798646040626, 5710.572356910772, 6739.6967136478, 6814.182298124257, 6859.5541289816, 6872.0, 6483.124778154889, 6669.166354205724, 6671.232808227116, 6677.76627818024, 6683.866322673616, 6422.3577642235405, 6472.288048314776, 6546.312637173219, 6592.208934927839, 6617.239017513048, 4465.553444655508, 5700.743466543932, 6466.612003279818, 6477.77327793525, 6543.149390579194, 6398.840028999312, 6441.066045423481, 6480.254770701241, 6483.805485835433, 6643.7674681385915, 6407.391297826699, 6423.4861211103935, 6435.967820160869, 6443.742250309935, 6449.064885591469], "value": 6110.976509545266, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.8554082121744, 806.1615919443777, 834.4659417972497, 1227.3924407418328, 1296.2999980010795, 38360.80819595901, 38702.0, 38728.0, 38846.41730374043, 38876.0, 39230.80384598073, 39362.40956385649, 39654.801725991405, 39694.80152599233, 39724.20551588966, 7852.646630901609, 7988.121306656263, 8100.432969692124, 8378.455400398985, 8393.202645748644, 8281.54451505167, 8399.706010289641, 8403.159684031578, 8423.410361274713, 8471.36464765142, 8347.207015333533, 8402.075771665111, 8423.326133909302, 8427.536485493296, 8441.113683063282, 8362.080171181165, 8402.369822263354, 8404.24361807434, 8405.621747021398, 8416.537090460022, 6987.441004719661, 8202.425830191889, 8248.051474080497, 8249.381296402793, 8250.587470626504, 35868.0, 35956.74151404688, 36090.91727248187, 36224.63775362239, 36264.912052638465, 3531.3820081485637, 3549.2901419715904, 3557.004038869121, 3570.83931223093, 3578.2306804037257, 30411.391772164654, 30563.541546876815, 30693.232669183224, 30715.539266911015, 30747.538786918216, 3046.9542956855666, 3056.7401770849283, 3077.7383922366625, 3079.291762894547, 3089.6137982752157, 241526.0, 243013.0, 271872.6406367975, 285930.0, 288251.0, 47193.76403118031, 47426.5257347429, 47702.0, 47776.522234777905, 47794.0, 49278.014439711005, 49344.01311973741, 49494.75252623714, 49528.50471495311, 49562.75218623884, 14241.64395890111, 14272.286385680882, 14342.354594043292, 14368.425262989364, 14386.640333991734, 11729.35488548121, 11757.294562326288, 11774.470148843322, 11775.0, 11780.823287650692, 13140.34298285082, 13211.754001819714, 13329.533466328632, 13407.195568265935, 13430.254066971427, 6023.969880150571, 6029.638221706711, 6066.969665151734, 6094.634321940698, 6097.60365576245, 913.2784302777759, 976.9267304952157, 1006.6879267426974, 1027.6762819711766, 1065.2756125834353, 23308.883455582614, 23603.173888913807, 23995.64006539903, 24063.398415039763, 24077.879610602184, 1092.3664274720643, 1100.471773548701, 1134.4101067444883, 1138.0895283773114, 1146.4325159046325, 11616.477258523386, 12251.142420030512, 12255.188285981154, 12275.2021118627, 12278.938605307096, 311944.0, 312721.72782722, 315266.0, 315407.0, 316363.0, 52923.73538132285, 53032.469675303524, 53042.7347863266, 53183.20225196625, 53264.46735532673, 1479.7706355514913, 1521.8554237347435, 1524.390243902448, 1528.2740698168282, 1531.4792970390122, 21692.457688557282, 22856.428589285402, 23606.0, 23667.171648992204, 23766.405839854142, 34899.47650785291, 36688.81655591705, 36727.81636091803, 36732.632673672924, 36870.0, 196840.0, 197625.0, 197767.0, 197826.0, 198440.00779996006, 32253.51619725659, 32792.50811237786, 33628.15929601682, 33630.0, 33660.0, 43107.78446107749, 43398.78300608477, 44761.0, 44830.55169448394, 45141.32288015616, 24399.51200975935, 28843.711562884942, 30007.0, 30954.226144346572, 31257.0, 30000.399991999602, 30654.773809047387, 30678.846605766827, 31050.068497945096, 31089.844550777103, 36081.0, 36221.81889090538, 36862.81568592247, 36913.815430922674, 37077.6292237085, 24827.62758558587, 30455.62949667226, 31325.373492530478, 31526.684733152375, 31582.36835263328, 21597.892010539847, 22632.868356581777, 23132.884335578216, 24345.14791982272, 24772.25683229506], "value": 17156.95168147174, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.12006207182145, 431.60508135056426, 436.1734513097679, 449.97405914514917, 450.28854410032153, 19222.135003924803, 20953.16187352506, 21640.02619882105, 21817.345479635635, 22032.0, 21663.350099497, 22430.775692243074, 23077.769222307772, 23116.422089447773, 23148.537029259405, 6066.241719785027, 6246.0006398976175, 6260.060990851363, 6270.968645156768, 6272.87454250917, 6597.505187110986, 6979.616121113337, 7024.964875175617, 7045.048918396003, 7059.152901651809, 6690.130283063219, 6902.102726645529, 6954.791356259295, 7013.649317534128, 7015.579065256101, 5320.866978325535, 6440.903386449207, 6834.111565496453, 6951.513394062417, 7007.964960175192, 6792.626405547693, 6797.320267973212, 6810.080639113706, 6813.250542440328, 6891.827704307382, 28440.146795596163, 28761.712382876114, 29552.556711649344, 29595.408091838257, 29701.405971880442, 2247.235939780466, 2291.8968646410954, 2296.1619009061737, 2296.391456264091, 2301.367124040902, 17810.287588496445, 18706.90646546772, 18814.905925470423, 19049.7142542862, 19089.427317180507, 2256.413332533553, 2317.0384290519414, 2324.1865347128537, 2362.842207318419, 2374.2877136858874, 127778.0, 153178.0, 153212.0, 156307.0, 156938.21530892386, 20312.390628281177, 22663.773362266165, 22743.545129097325, 23914.760852391602, 24042.918068686602, 40380.394294085614, 40503.98740031523, 40531.59468405337, 40588.57939972146, 40704.98237544026, 6559.44244739193, 8730.563471826383, 8925.509096999598, 8997.055309192572, 9038.0, 7172.354488096096, 8501.702440414556, 8575.399722019523, 8585.914140858637, 8740.825183496421, 6403.583767055126, 7021.824454388579, 7125.394341480928, 7566.621668916532, 7938.704795284316, 2235.2511908510833, 2237.7314722233436, 2249.3682391601865, 2257.097161135559, 2425.4178997040585, 880.6257340630335, 922.520289449496, 939.3096074385321, 966.6423423333412, 984.4683870709737, 20692.689609655867, 24636.384090397525, 26017.869910650326, 26586.202413927615, 26744.866275668886, 994.945278009702, 1000.9299349045497, 1011.7672935224939, 1018.872640919885, 1024.3700124423426, 10255.846162307571, 10595.364278143335, 10866.67399978002, 10950.616728414467, 11082.113430925507, 567522.0, 575074.0, 576940.0, 588580.0, 589653.0, 130384.0, 131080.0, 133594.0, 136120.0, 138128.0, 1194.7252132009687, 1220.5117952818944, 1243.6704273367745, 1245.8131280308025, 1248.875112488762, 19984.400467985983, 20999.37001889946, 21533.46166345791, 22015.559688806454, 22335.888320558293, 46100.30849537192, 49514.50485495243, 55498.44501555094, 55941.0, 56071.0, 72007.0, 73932.63033684797, 74851.50296994139, 75091.62454187694, 76805.61597191979, 6569.835754106186, 8718.346124040596, 8736.08271131522, 8818.0, 9084.818303633758, 12290.139690221598, 12898.67753306175, 14056.789148162581, 14060.437582496586, 14433.278336083364, 9452.905470945203, 11851.348175850066, 11971.700707482383, 12049.939750301193, 12076.584683063247, 9768.511574421109, 10548.208884333859, 11180.832287515856, 11546.960773530423, 11613.245139065933, 11738.237014594024, 12109.939450302692, 12210.816837747618, 12222.816657750318, 12224.938875305566, 8761.29909607217, 8834.955825220834, 8853.468791872509, 9175.577785443267, 9178.862317065115, 11311.208215424842, 11340.376279304717, 11536.673282572612, 11775.411229438665, 11826.04870196408], "value": 11130.188501499602, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1270.1680399338434, 1397.5108711950818, 1401.0822910993297, 1410.5909286306971, 1414.9504767333146, 11229.989300962912, 11527.423628818557, 11638.941805290982, 12045.217060891047, 12069.0, 11712.882871171307, 11831.645050648494, 11894.762104757921, 11894.82157767634, 11961.641150765468, 2506.9874650626766, 2544.2367289813074, 2550.375158086262, 2551.795856331489, 2553.10641275553, 2491.5888878335054, 2556.194798638432, 2559.667243258383, 2561.7950563954837, 2567.536504192611, 2476.0343466048257, 2495.126705653016, 2526.886290116949, 2526.9115580954576, 2528.898844046236, 2487.8880450379775, 2515.937101572466, 2550.2604244768995, 2551.885165167572, 2552.706438759548, 2114.3445531885163, 2385.8329916905823, 2499.200255918107, 2504.511620234055, 2510.14655017295, 11595.072394208442, 11617.0, 11661.12541559378, 11680.824787628193, 11683.240589361749, 6461.870762584769, 6705.564138330993, 6719.328067193291, 6726.764563240312, 6736.730530778763, 3875.709321800848, 4258.552851950562, 4287.678424118172, 4327.0480494291205, 4342.436722779797, 1182.1783860217151, 1191.9702007449796, 1222.1261797814516, 1241.8633950265464, 1245.9377031148406, 12754.660760620187, 13604.727905441836, 14676.853231467656, 15936.282867270884, 16198.433054843024, 5273.525382715574, 5337.066013447631, 5485.424030476823, 5523.972380138073, 5533.584981126432, 11674.00770934463, 11736.765264694659, 11761.764764704658, 11766.235194712317, 11974.161808673483, 6457.838554036092, 6482.546221764525, 6485.967570162119, 6497.870042599122, 6502.317256688074, 5068.771905264272, 5566.55467562594, 5925.0, 6019.337872833985, 6054.788082417094, 3625.746197766131, 3954.9604503955165, 4139.441175441277, 4272.9359059614135, 4338.305871060616, 1068.3109394440542, 1268.6130730127215, 1271.9364031798373, 1284.3064745037761, 1289.5615490733007, 634.6667999300404, 790.9169537198511, 808.4219782855287, 814.2752949874616, 840.1220724343076, 5457.399686034533, 5996.430339117776, 6302.464290535356, 6827.249002609709, 6862.622555759481, 930.251147825993, 933.1881263300971, 938.2634631813993, 948.7144918635288, 969.8448248280241, 4222.78886055696, 4294.312909934458, 4343.587359200871, 4407.162639098561, 4452.443444569429, 14601.977861549589, 14983.475578354923, 15055.623609409853, 15086.0, 15213.31540080699, 14381.92809035948, 14547.709045819025, 15029.023113497806, 15067.246637668073, 15101.773473397909, 8434.873476897852, 8461.153884611613, 8618.956905215433, 8620.396572240008, 8633.611487483078, 5428.348598168087, 5949.91075133882, 6588.472922166311, 6616.636085015371, 6756.527043106936, 11068.833967490655, 12909.35453227316, 13147.14543554666, 13243.139195952232, 13341.66645833862, 13448.0, 13636.454541818217, 13693.520726774914, 13785.793213102012, 13791.10357826738, 9054.275657947217, 9104.499252540909, 9182.311326650395, 9259.814803703754, 9304.95347523258, 10400.47997600102, 10489.423081730578, 10504.054635082879, 10509.264351495322, 10514.57941682324, 8638.91361086381, 9107.316951228551, 9168.0, 9192.31057670664, 9276.211522020703, 8831.69089081879, 8868.822623547621, 8974.14745599167, 8976.326775491994, 9218.0, 8029.47808392476, 8180.7954801130445, 8254.215849494289, 8283.544405057779, 8311.542865142475, 5973.402659734165, 5991.101334799815, 6069.453749162597, 6140.907886381798, 6172.567920245539, 5693.4021927696995, 6017.15759793638, 6149.938500614937, 6195.411435913581, 6327.525435592441], "value": 5314.533653505609, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.8331692544489, 384.8268279274327, 400.25552472401336, 403.6185804414829, 411.7817556694953, 4817.421909370872, 5219.765110570029, 6204.41058099481, 6327.5887067340645, 6372.426481616653, 5809.883802323951, 5986.2517185351835, 6026.450030227184, 6129.601575897575, 6163.137160797481, 4475.1944649963, 4525.592696657301, 4690.132325519773, 4724.834630787923, 4753.33453316536, 3852.9036774080732, 3853.65317121459, 4003.779792111433, 4153.8338466461455, 4728.361671174399, 3624.746267761258, 4425.645948324126, 4660.557247061523, 4687.460941991681, 4706.81133273028, 4676.462206846222, 4696.95303046972, 4702.224133018048, 4705.811767529295, 4721.0, 3446.965530344715, 4078.0, 4169.291220492493, 4180.435641188431, 4197.349410841324, 4950.455449900508, 5952.898713737973, 5995.370486098984, 6042.9395706042815, 6056.939430605682, 5640.912862240997, 5650.0959846424375, 5658.519025882805, 5660.29246344207, 5661.207430959669, 6446.967765161145, 6561.0, 6592.351912021994, 6638.137042184485, 6674.498237896483, 4779.737114458703, 4797.976010119962, 4805.711657300573, 4817.807287708488, 4825.445073816521, 5859.802574034737, 6341.936580634181, 6926.619035953021, 6979.546329488618, 7137.607431591312, 3646.799426031541, 3899.4150877368056, 4267.935980960288, 4333.198358303661, 4433.645308375322, 3834.9616503835164, 4487.63127246193, 4499.955000450019, 4526.642007397768, 4527.868032991752, 5102.923456148161, 5291.703532634452, 5381.27352807366, 5385.461453854583, 5401.405845357008, 6169.753209871645, 6683.331666833278, 6700.932990670129, 6702.932970670328, 6708.92023472725, 1336.4386957477882, 1486.0, 1521.4218596933094, 1545.9768103478457, 1546.6829299993474, 831.6132998155765, 966.3477152921749, 991.2169386184977, 999.7900440907346, 1008.5814387029301, 6238.344973777688, 6293.181886354744, 6296.5592408531875, 6299.842503937438, 6318.241810982619, 5906.763729450774, 6546.083548303146, 6560.311167327457, 6881.552699074544, 6908.965455172692, 5731.971340143273, 6138.1713468681155, 6213.2233470816145, 6232.844178895473, 6251.9687401563615, 3014.156036309837, 3061.479548476764, 3618.891433256954, 3631.891043268706, 3634.491171236029, 6710.463162946952, 7070.505064645426, 7136.500444968802, 7141.678624461808, 7161.498695091398, 6489.935100649028, 6769.018492318586, 7173.744594695907, 7193.3166349196745, 7218.783436496913, 6573.934260657428, 6841.828954276083, 7029.859402811915, 7108.644567771694, 7111.751088711871, 5645.633033852787, 6693.397594216543, 6860.622665753432, 6917.584944903322, 6937.0, 6243.250809902841, 6307.400796924285, 6864.313568643295, 6868.862622747417, 6876.931230687629, 5170.157721774371, 6678.732850686112, 6835.4189893859575, 6974.37230649244, 7111.288871112847, 4340.631046360968, 4402.757848318373, 4409.3606427068535, 4423.513413524445, 4435.558443505809, 5791.623544469596, 6994.0, 6997.265287144776, 7021.192562855337, 7046.647667616701, 5799.82600521985, 6638.468922486093, 6696.229933557704, 6761.797146085625, 6791.490638202254, 6712.765053223114, 6721.93278067213, 6729.158855143107, 6736.7642132525125, 6786.0, 6007.669578173242, 6394.520410969289, 6442.806715798346, 6535.44448721864, 6619.801405957829, 6359.618422894641, 6627.6354800484505, 6756.966215168893, 6758.966205168943, 6789.685592399116, 5921.585489015728, 6381.819363417782, 6582.683463307453, 6588.8023359299295, 6673.899891501728], "value": 4823.11537896964, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.7747063199254, 835.2566216067697, 843.0010437631407, 843.7173546861801, 864.9827003459931, 14332.355044023017, 14873.033252838557, 16202.513924582267, 16280.104594247327, 16512.422065227704, 15652.295646695897, 17135.0, 17361.565960850952, 17445.040522771244, 17539.38612148575, 995.6117114325419, 1018.0481250031207, 1019.0115587879782, 1020.9846852297221, 1022.7852151048289, 1015.8120747661669, 1016.0, 1019.3476175247847, 1020.0921180149672, 1021.0657248617543, 1064.0317311246768, 1067.941263230522, 1072.5870539842178, 1076.0261962923566, 1076.993011534218, 1066.6000249906285, 1069.096613361708, 1071.3090056913286, 1072.0833687197412, 1073.2433634287809, 675.9320273967169, 706.703199628682, 781.0393216343872, 785.1206648553657, 791.1218547412368, 16273.349066037345, 16547.83452165475, 16642.50072497827, 16875.915620421943, 16886.240119194666, 15971.520854374387, 16711.99728016323, 16829.91585042079, 16866.0, 16916.66166676672, 14072.85927140736, 14519.709605807824, 14601.0, 14639.63400914975, 14736.48422305214, 3566.1619519412916, 3604.5854726706502, 3663.303972245265, 3666.816659167058, 3669.101070237809, 129637.70362296444, 130655.0, 132688.0, 133838.0, 134003.6599634001, 10845.566177352817, 11397.886021139682, 13024.283664398548, 13431.597052088453, 13475.056746027683, 21137.577248454945, 24822.890054345582, 25561.488770224492, 25758.742412576008, 25846.87076564643, 7626.161122276545, 7907.48601340911, 7926.4847784894955, 7934.523928564305, 7970.960145199237, 3962.9603703963166, 4019.537753158424, 4053.1893621275863, 4054.270231358325, 4070.6946978976102, 4019.9799001005395, 4132.648724858361, 4134.111166099303, 4139.337705967031, 4144.5026596807975, 867.6572753762229, 886.2732559301439, 894.7763059235191, 896.7847716547982, 897.9236764874928, 628.4909223528956, 646.1922596754057, 648.1800522339245, 653.9346065393422, 684.9383555480031, 11129.72175695598, 11762.88237117635, 12097.697557561132, 12102.135349693908, 12209.145359824726, 608.3217212807776, 635.1679300116862, 649.6297110646981, 661.1042038038485, 677.9525433219726, 4274.893127671771, 5341.370463546451, 5355.625106242525, 5598.496135347838, 5645.125005624135, 109036.0, 111168.88831111585, 112090.43954780337, 113374.0, 114940.0, 96547.51726241324, 96786.54820177703, 97430.0, 97663.09347626015, 98856.50571747095, 74548.25451745413, 86632.0, 87718.0, 88456.0, 88578.55710721404, 12103.757924841279, 12240.265584064984, 12546.686332841751, 12564.748705026032, 12802.935985320013, 38411.0, 38426.23147537089, 39606.00984975399, 39900.60099398969, 40005.19976601118, 51067.74466127794, 51088.46734597968, 51107.488925111764, 51372.48627513677, 51375.71560711012, 14665.92667036658, 15884.0, 16165.0, 17160.39938602143, 17331.913340433217, 17809.643807124045, 17974.730379044588, 18601.627967440847, 18763.061846907327, 18925.621487570446, 16022.679546408774, 16055.839441605902, 17128.40050598223, 17333.133343333036, 17350.21924013397, 16870.409535666193, 17031.14844257807, 17170.313187472362, 17371.91314043472, 17673.20470578801, 15761.290741916411, 16038.358465661242, 16667.833321667113, 16904.57738556546, 16978.830211697725, 9155.77110572241, 11570.537178512765, 11748.118891083373, 11748.647540573797, 11967.940160299142, 10974.561017559208, 13186.274754888573, 13192.67018324511, 13285.269310187647, 13763.587092387244], "value": 8380.636667244578, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1492.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 119.0, 116.0, 115.0, 114.0, 115.0, 114.0, 114.0, 115.0, 115.0], "value": 114.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 110.0, 111.0, 158.0, 144.0, 128.0, 111.0, 108.0, 153.0, 103.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 211.0, 282.0, 252.0, 207.0, 256.0, 181.0, 271.0, 278.0, 201.0], "value": 234.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 56.0, 61.0, 84.0, 57.0, 58.0, 60.0, 57.0, 74.0, 78.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 84.0, 74.0, 69.0, 68.0, 88.0, 72.0, 71.0, 90.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 89.0, 89.0, 88.0, 89.0, 89.0, 88.0, 89.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 94.0, 97.0, 96.0, 97.0, 96.0, 96.0, 95.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 91.0, 92.0, 92.0, 92.0, 90.0, 93.0, 92.0, 94.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 50.0, 51.0, 51.0, 52.0, 52.0, 53.0, 51.0, 50.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 66.0, 65.0, 58.0, 65.0, 61.0, 59.0, 59.0, 60.0, 58.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 85.0, 83.0, 81.0, 81.0, 81.0, 86.0, 82.0, 84.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 136.0, 138.0, 143.0, 151.0, 146.0, 140.0, 145.0, 189.0, 128.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 188.0, 181.0, 184.0, 186.0, 189.0, 221.0, 184.0, 183.0, 186.0], "value": 189.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 66.0, 67.0, 68.0, 65.0, 68.0, 67.0, 67.0, 69.0], "value": 67.3, "unit": "score"}]}]} 09:01:22 INFO - Return code: 0 09:01:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:01:22 INFO - # TBPL SUCCESS # 09:01:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:01:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:01:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:01:22 INFO - 2017-05-23 09:01:22.484 screenresolution[88178:822191] starting screenresolution argv=screenresolution get 09:01:22 INFO - 2017-05-23 09:01:22.486 screenresolution[88178:822191] Display 0: 1600x1200x32@60 09:01:22 INFO - 2017-05-23 09:01:22.492 screenresolution[88179:822194] starting screenresolution argv=screenresolution list 09:01:22 INFO - Available Modes on Display 0 09:01:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:01:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:01:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:01:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:01:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:01:22 INFO - Intel Iris: 09:01:22 INFO - Chipset Model: Intel Iris 09:01:22 INFO - Type: GPU 09:01:22 INFO - Bus: Built-In 09:01:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:01:22 INFO - Vendor: Intel (0x8086) 09:01:22 INFO - Device ID: 0x0a2e 09:01:22 INFO - Revision ID: 0x0009 09:01:22 INFO - Displays: 09:01:22 INFO - Display: 09:01:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:01:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:01:22 INFO - Display Serial Number: 1600x1200 60 09:01:22 INFO - Main Display: Yes 09:01:22 INFO - Mirror: Off 09:01:22 INFO - Online: Yes 09:01:22 INFO - Rotation: Supported 09:01:22 INFO - Return code: 0 09:01:22 INFO - Running post-action listener: _resource_record_post_action 09:01:22 INFO - [mozharness: 2017-05-23 16:01:22.727907Z] Finished run-tests step (success) 09:01:22 INFO - Running post-run listener: _resource_record_post_run 09:01:22 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 0; Write bytes: 563133440; Read time: 0; Write time: 1611 09:01:22 INFO - TinderboxPrint: CPU usage
54.1% 09:01:22 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 09:01:22 INFO - TinderboxPrint: I/O write bytes / time
563,133,440 / 1,611 09:01:22 INFO - TinderboxPrint: CPU idle
2,196.6 (45.9%) 09:01:22 INFO - TinderboxPrint: CPU system
963.0 (20.1%) 09:01:22 INFO - TinderboxPrint: CPU user
1,622.2 (33.9%) 09:01:22 INFO - TinderboxPrint: Swap in / out
244,637,696 / 0 09:01:22 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229990912; Write bytes: 227856384; Read time: 16793; Write time: 403 09:01:22 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 0; Write bytes: 316840960; Read time: 0; Write time: 1168 09:01:23 INFO - Running post-run listener: _upload_blobber_files 09:01:23 INFO - Blob upload gear active. 09:01:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:01:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:01:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:01:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:01:23 INFO - (blobuploader) - INFO - Open directory for files ... 09:01:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:01:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:01:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:24 INFO - (blobuploader) - INFO - Done attempting. 09:01:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:01:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:01:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:25 INFO - (blobuploader) - INFO - Done attempting. 09:01:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:01:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:01:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:26 INFO - (blobuploader) - INFO - Done attempting. 09:01:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:01:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:01:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:28 INFO - (blobuploader) - INFO - Done attempting. 09:01:28 INFO - (blobuploader) - INFO - Iteration through files over. 09:01:28 INFO - Return code: 0 09:01:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:01:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:01:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a7ce643aa7617052926e19620ef0ca9a00387a9304c70f9dbf33c2c6627852decd3a7c99e804cad129bea8135ef0e40ae8d817a72606572fe50a8ac1c91f11c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c5bd6970349597a8d98ab09d6fe51fefb6c0d25c35872aed68262f509ac00affd34243020c4418dac5af3911c1e84fd18323eee77eb40aae35ec1206fbce828", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ba9b2b942adfe98aa7441502dfe6ff7c62de4e8a827e2ff2a5230b59b44d613b70b363daafec7737be4d501415554d5ce865c985a2c0296ed58c535e6bb1e73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf60462611ef965bfec79f88ae17b42f979c1a61da7960b152abc2fc043994f9d96a158a194dfc4436f9e618d9cf7ec72e37f663ebf324d824600fc281e8d1b8"} 09:01:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:01:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:01:28 INFO - Contents: 09:01:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a7ce643aa7617052926e19620ef0ca9a00387a9304c70f9dbf33c2c6627852decd3a7c99e804cad129bea8135ef0e40ae8d817a72606572fe50a8ac1c91f11c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c5bd6970349597a8d98ab09d6fe51fefb6c0d25c35872aed68262f509ac00affd34243020c4418dac5af3911c1e84fd18323eee77eb40aae35ec1206fbce828", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ba9b2b942adfe98aa7441502dfe6ff7c62de4e8a827e2ff2a5230b59b44d613b70b363daafec7737be4d501415554d5ce865c985a2c0296ed58c535e6bb1e73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf60462611ef965bfec79f88ae17b42f979c1a61da7960b152abc2fc043994f9d96a158a194dfc4436f9e618d9cf7ec72e37f663ebf324d824600fc281e8d1b8"} 09:01:28 INFO - Running post-run listener: copy_logs_to_upload_dir 09:01:28 INFO - Copying logs to upload dir... 09:01:28 INFO - mkdir: /builds/slave/test/build/upload/logs 09:01:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1352.531111 ========= master_lag: 1.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 33 secs) (at 2017-05-23 09:01:29.429720) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 09:01:29.437231) ========= 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.OHhBaloqlk/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.anw9uJQ3zm/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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/9a7ce643aa7617052926e19620ef0ca9a00387a9304c70f9dbf33c2c6627852decd3a7c99e804cad129bea8135ef0e40ae8d817a72606572fe50a8ac1c91f11c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c5bd6970349597a8d98ab09d6fe51fefb6c0d25c35872aed68262f509ac00affd34243020c4418dac5af3911c1e84fd18323eee77eb40aae35ec1206fbce828", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ba9b2b942adfe98aa7441502dfe6ff7c62de4e8a827e2ff2a5230b59b44d613b70b363daafec7737be4d501415554d5ce865c985a2c0296ed58c535e6bb1e73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf60462611ef965bfec79f88ae17b42f979c1a61da7960b152abc2fc043994f9d96a158a194dfc4436f9e618d9cf7ec72e37f663ebf324d824600fc281e8d1b8"} build_url:https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011648 build_url: 'https://queue.taskcluster.net/v1/task/G2NU0gjNTa-LUo2rvpPguA/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/9a7ce643aa7617052926e19620ef0ca9a00387a9304c70f9dbf33c2c6627852decd3a7c99e804cad129bea8135ef0e40ae8d817a72606572fe50a8ac1c91f11c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c5bd6970349597a8d98ab09d6fe51fefb6c0d25c35872aed68262f509ac00affd34243020c4418dac5af3911c1e84fd18323eee77eb40aae35ec1206fbce828", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4ba9b2b942adfe98aa7441502dfe6ff7c62de4e8a827e2ff2a5230b59b44d613b70b363daafec7737be4d501415554d5ce865c985a2c0296ed58c535e6bb1e73", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bf60462611ef965bfec79f88ae17b42f979c1a61da7960b152abc2fc043994f9d96a158a194dfc4436f9e618d9cf7ec72e37f663ebf324d824600fc281e8d1b8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 09:01:29.472389) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 09:01:29.472702) ========= 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.OHhBaloqlk/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.anw9uJQ3zm/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005503 ========= master_lag: 0.82 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 09:01:30.298529) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 09:01:30.298903) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 09:01:30.299518) ========= ========= Total master_lag: 1.99 =========