builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0323 starttime: 1495216450.6 results: success (0) revision: 1ea1b40c2679da341d50d27226c61033bac06711 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:10.595959) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:10.596430) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:10.596704) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:10.622338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:10.622853) ========= 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.6QmAqkB1AM/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.yNQeh3JErO/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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-19 10:54:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.0M=0.001s 2017-05-19 10:54:10 (17.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079522 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:10.725822) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:10.726137) ========= 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.6QmAqkB1AM/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.yNQeh3JErO/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.025321 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:10.773974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 10:54:10.774306) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1ea1b40c2679da341d50d27226c61033bac06711 --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 1ea1b40c2679da341d50d27226c61033bac06711 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.6QmAqkB1AM/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.yNQeh3JErO/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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-19 10:54:10,874 truncating revision to first 12 chars 2017-05-19 10:54:10,874 Setting DEBUG logging. 2017-05-19 10:54:10,874 attempt 1/10 2017-05-19 10:54:10,874 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1ea1b40c2679?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 10:54:11,577 unpacking tar archive at: mozilla-central-1ea1b40c2679/testing/mozharness/ program finished with exit code 0 elapsedTime=0.983678 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 10:54:11.779922) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:11.780289) ========= script_repo_revision: 1ea1b40c2679da341d50d27226c61033bac06711 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:11.780760) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:11.781024) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 10:54:11.799608) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-19 10:54:11.799960) ========= /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.6QmAqkB1AM/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.yNQeh3JErO/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:54:11 INFO - MultiFileLogger online at 20170519 10:54:11 in /builds/slave/test 10:54:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:54:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:54:12 INFO - {'append_to_log': False, 10:54:12 INFO - 'base_work_dir': '/builds/slave/test', 10:54:12 INFO - 'blob_upload_branch': 'Firefox', 10:54:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:54:12 INFO - 'branch': 'Firefox', 10:54:12 INFO - 'buildbot_json_path': 'buildprops.json', 10:54:12 INFO - 'config_files': ('talos/mac_config.py',), 10:54:12 INFO - 'default_actions': ('clobber', 10:54:12 INFO - 'read-buildbot-config', 10:54:12 INFO - 'download-and-extract', 10:54:12 INFO - 'populate-webroot', 10:54:12 INFO - 'create-virtualenv', 10:54:12 INFO - 'install', 10:54:12 INFO - 'run-tests'), 10:54:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:54:12 INFO - 'download_minidump_stackwalk': True, 10:54:12 INFO - 'download_symbols': 'ondemand', 10:54:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:54:12 INFO - 'tooltool.py': '/tools/tooltool.py', 10:54:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:54:12 INFO - '/tools/misc-python/virtualenv.py')}, 10:54:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:54:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:54:12 INFO - 'gecko_profile': False, 10:54:12 INFO - 'gecko_profile_interval': 0, 10:54:12 INFO - 'installer_path': 'installer.exe', 10:54:12 INFO - 'log_level': 'info', 10:54:12 INFO - 'log_name': 'talos', 10:54:12 INFO - 'log_to_console': True, 10:54:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:54:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:54:12 INFO - 'opt_config_files': (), 10:54:12 INFO - 'pip_index': False, 10:54:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:54:12 INFO - 'cmd': ('bash', 10:54:12 INFO - '-c', 10:54:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:54:12 INFO - 'enabled': True, 10:54:12 INFO - 'halt_on_failure': False, 10:54:12 INFO - 'name': 'check_screen_resolution'},), 10:54:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:54:12 INFO - 'cmd': ('bash', 10:54:12 INFO - '-c', 10:54:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:54:12 INFO - 'enabled': True, 10:54:12 INFO - 'halt_on_failure': False, 10:54:12 INFO - 'name': 'check_screen_resolution'},), 10:54:12 INFO - 'run_cmd_checks_enabled': True, 10:54:12 INFO - 'suite': 'dromaeojs-e10s', 10:54:12 INFO - 'system_bits': '32', 10:54:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:54:12 INFO - 'title': 't-yosemite-r7-0323', 10:54:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:54:12 INFO - 'use_talos_json': True, 10:54:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:54:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:54:12 INFO - 'work_dir': 'build'} 10:54:12 INFO - [mozharness: 2017-05-19 17:54:12.009253Z] Running clobber step. 10:54:12 INFO - Running pre-action listener: _resource_record_pre_action 10:54:12 INFO - Running main action method: clobber 10:54:12 INFO - rmtree: /builds/slave/test/build 10:54:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:54:14 INFO - Running post-action listener: _resource_record_post_action 10:54:14 INFO - [mozharness: 2017-05-19 17:54:14.442524Z] Finished clobber step (success) 10:54:14 INFO - [mozharness: 2017-05-19 17:54:14.442676Z] Running read-buildbot-config step. 10:54:14 INFO - Running pre-action listener: _resource_record_pre_action 10:54:14 INFO - Running main action method: read_buildbot_config 10:54:14 INFO - Using buildbot properties: 10:54:14 INFO - { 10:54:14 INFO - "project": "", 10:54:14 INFO - "product": "firefox", 10:54:14 INFO - "who": "ffxbld@noreply.mozilla.org", 10:54:14 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 10:54:14 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:54:14 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 10:54:14 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:54:14 INFO - "stage_platform": "macosx64", 10:54:14 INFO - "basedir": "/builds/slave/test", 10:54:14 INFO - "buildnumber": 75, 10:54:14 INFO - "platform": "yosemite_r7", 10:54:14 INFO - "parent_task_id": "NEOpwZLqSnezgn5YpBQidw", 10:54:14 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:54:14 INFO - "slavebuilddir": "test", 10:54:14 INFO - "taskId": "MUP_Pd-HTkSg33MxRkjunw", 10:54:14 INFO - "branch": "mozilla-central", 10:54:14 INFO - "script_repo_revision": "production", 10:54:14 INFO - "revision": "1ea1b40c2679da341d50d27226c61033bac06711", 10:54:14 INFO - "slavename": "t-yosemite-r7-0323", 10:54:14 INFO - "repo_path": "mozilla-central" 10:54:14 INFO - } 10:54:14 INFO - Finding installer, test and symbols from parent task. 10:54:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MUP_Pd-HTkSg33MxRkjunw'}, attempt #1 10:54:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MUP_Pd-HTkSg33MxRkjunw'}, attempt #1 10:54:15 INFO - Task dependencies: N7jfSuAATHSWObKQEawrBw NEOpwZLqSnezgn5YpBQidw 10:54:15 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw'}, attempt #1 10:54:16 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:54:16 INFO - Set test_url: https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/test_packages.json 10:54:16 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/target.crashreporter-symbols.zip 10:54:16 INFO - Running post-action listener: _resource_record_post_action 10:54:16 INFO - [mozharness: 2017-05-19 17:54:16.337652Z] Finished read-buildbot-config step (success) 10:54:16 INFO - [mozharness: 2017-05-19 17:54:16.337801Z] Running download-and-extract step. 10:54:16 INFO - Running pre-action listener: _resource_record_pre_action 10:54:16 INFO - Running main action method: download_and_extract 10:54:16 INFO - mkdir: /builds/slave/test/build/tests 10:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:16 INFO - trying https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 10:54:16 INFO - Downloading https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:54:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 10:54:17 INFO - Downloaded 1325 bytes. 10:54:17 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 10:54:17 INFO - Using the following test package requirements: 10:54:17 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 10:54:17 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:54:17 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 10:54:17 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 10:54:17 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'jsshell-mac.zip'], 10:54:17 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 10:54:17 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 10:54:17 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 10:54:17 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 10:54:17 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 10:54:17 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 10:54:17 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 10:54:17 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:54:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:54:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:54:17 INFO - Fetch https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:54:19 INFO - Content-Length response header: 56574765 10:54:19 INFO - Bytes received: 56574765 10:54:36 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:54:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 10:54:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:54:36 INFO - Fetch https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 10:54:38 INFO - Content-Length response header: 56574765 10:54:38 INFO - Bytes received: 56574765 10:54:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 10:54:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:54:55 INFO - Fetch https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 10:54:56 INFO - Content-Length response header: 14410001 10:54:56 INFO - Bytes received: 14410001 10:54:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:54:56 INFO - trying https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:54:56 INFO - Downloading https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 10:54:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 10:54:58 INFO - Downloaded 71630864 bytes. 10:54:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:54:58 INFO - mkdir: /builds/slave/test/properties 10:54:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:54:58 INFO - Writing to file /builds/slave/test/properties/build_url 10:54:58 INFO - Contents: 10:54:58 INFO - build_url:https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 10:54:58 INFO - Running post-action listener: _resource_record_post_action 10:54:58 INFO - Running post-action listener: set_extra_try_arguments 10:54:58 INFO - [mozharness: 2017-05-19 17:54:58.400453Z] Finished download-and-extract step (success) 10:54:58 INFO - [mozharness: 2017-05-19 17:54:58.400598Z] Running populate-webroot step. 10:54:58 INFO - Running pre-action listener: _resource_record_pre_action 10:54:58 INFO - Running main action method: populate_webroot 10:54:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:54:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:54:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:54:58 INFO - '--tppagecycles', 10:54:58 INFO - '7'], 10:54:58 INFO - 'tests': ['tsvgm']}}, 10:54:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:54:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:54:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:54:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:54:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:54:58 INFO - 'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:54:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:54:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:54:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:54:58 INFO - 'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['damp', 'tps']}, 10:54:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:54:58 INFO - 'tests': ['damp', 'tps']}, 10:54:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['dromaeo_dom']}, 10:54:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:54:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 10:54:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 10:54:58 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 10:54:58 INFO - 'tests': ['ts_paint', 'tp5o']}, 10:54:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['a11yr', 10:54:58 INFO - 'ts_paint', 10:54:58 INFO - 'tpaint', 10:54:58 INFO - 'sessionrestore', 10:54:58 INFO - 'sessionrestore_no_auto_restore', 10:54:58 INFO - 'tabpaint']}, 10:54:58 INFO - 'other-e10s': {'tests': ['a11yr', 10:54:58 INFO - 'ts_paint', 10:54:58 INFO - 'tpaint', 10:54:58 INFO - 'sessionrestore', 10:54:58 INFO - 'sessionrestore_no_auto_restore', 10:54:58 INFO - 'tabpaint']}, 10:54:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:54:58 INFO - 'ts_paint', 10:54:58 INFO - 'tpaint', 10:54:58 INFO - 'sessionrestore', 10:54:58 INFO - 'sessionrestore_no_auto_restore', 10:54:58 INFO - 'tabpaint']}, 10:54:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:54:58 INFO - 'ts_paint', 10:54:58 INFO - 'tpaint', 10:54:58 INFO - 'sessionrestore', 10:54:58 INFO - 'sessionrestore_no_auto_restore', 10:54:58 INFO - 'tabpaint']}, 10:54:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['a11yr', 10:54:58 INFO - 'ts_paint', 10:54:58 INFO - 'tpaint', 10:54:58 INFO - 'sessionrestore', 10:54:58 INFO - 'sessionrestore_no_auto_restore', 10:54:58 INFO - 'tabpaint']}, 10:54:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['a11yr', 10:54:58 INFO - 'ts_paint', 10:54:58 INFO - 'tpaint', 10:54:58 INFO - 'sessionrestore', 10:54:58 INFO - 'sessionrestore_no_auto_restore', 10:54:58 INFO - 'tabpaint']}, 10:54:58 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 10:54:58 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 10:54:58 INFO - 'bloom_basic_ref']}, 10:54:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['tsvgx', 10:54:58 INFO - 'tsvgr_opacity', 10:54:58 INFO - 'tart', 10:54:58 INFO - 'tscrollx', 10:54:58 INFO - 'cart', 10:54:58 INFO - 'tsvg_static']}, 10:54:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:54:58 INFO - 'tsvgr_opacity', 10:54:58 INFO - 'tart', 10:54:58 INFO - 'tscrollx', 10:54:58 INFO - 'cart', 10:54:58 INFO - 'tsvg_static']}, 10:54:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:54:58 INFO - 'talos_options': ['--disable-e10s'], 10:54:58 INFO - 'tests': ['tp5o']}, 10:54:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:54:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:54:58 INFO - 'talos_options': ['--disable-e10s', 10:54:58 INFO - '--xperf_path', 10:54:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:54:58 INFO - 'tests': ['tp5n']}, 10:54:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:54:58 INFO - 'talos_options': ['--xperf_path', 10:54:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:54:58 INFO - 'tests': ['tp5n']}}, 10:54:58 INFO - 'talos.zip': {'path': '', 10:54:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:54:58 INFO - Running post-action listener: _resource_record_post_action 10:54:58 INFO - [mozharness: 2017-05-19 17:54:58.421804Z] Finished populate-webroot step (success) 10:54:58 INFO - [mozharness: 2017-05-19 17:54:58.421936Z] Running create-virtualenv step. 10:54:58 INFO - Running pre-action listener: _resource_record_pre_action 10:54:58 INFO - Running main action method: create_virtualenv 10:54:58 INFO - Creating virtualenv /builds/slave/test/build/venv 10:54:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:54:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:54:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:54:58 INFO - Using real prefix '/tools/python27' 10:54:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:54:59 INFO - Installing distribute.............................................................................................................................................................................................done. 10:55:02 INFO - Installing pip.................done. 10:55:02 INFO - Return code: 0 10:55:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:55:02 INFO - Reading from file tmpfile_stdout 10:55:02 INFO - Output received: 10:55:02 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:55:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:02 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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:02 INFO - 'HOME': '/Users/cltbld', 10:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:02 INFO - 'LOGNAME': 'cltbld', 10:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:02 INFO - 'MOZ_NO_REMOTE': '1', 10:55:02 INFO - 'NO_EM_RESTART': '1', 10:55:02 INFO - 'PAGER': '/bin/cat', 10:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:02 INFO - 'PWD': '/builds/slave/test', 10:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:02 INFO - 'SHELL': '/bin/bash', 10:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:02 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:02 INFO - 'USER': 'cltbld', 10:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:02 INFO - 'XPC_FLAGS': '0x0', 10:55:02 INFO - 'XPC_SERVICE_NAME': '0', 10:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:55:02 INFO - Cleaning up... 10:55:02 INFO - Return code: 0 10:55:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:02 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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:55:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:55:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:02 INFO - 'HOME': '/Users/cltbld', 10:55:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:02 INFO - 'LOGNAME': 'cltbld', 10:55:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:02 INFO - 'MOZ_NO_REMOTE': '1', 10:55:02 INFO - 'NO_EM_RESTART': '1', 10:55:02 INFO - 'PAGER': '/bin/cat', 10:55:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:02 INFO - 'PWD': '/builds/slave/test', 10:55:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:02 INFO - 'SHELL': '/bin/bash', 10:55:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:02 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:02 INFO - 'USER': 'cltbld', 10:55:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:02 INFO - 'XPC_FLAGS': '0x0', 10:55:02 INFO - 'XPC_SERVICE_NAME': '0', 10:55:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:02 INFO - Downloading/unpacking psutil>=3.1.1 10:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:55:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:55:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:55:04 INFO - Installing collected packages: psutil 10:55:04 INFO - Running setup.py install for psutil 10:55:05 INFO - building 'psutil._psutil_osx' extension 10:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:55:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:55:05 INFO - building 'psutil._psutil_posix' extension 10:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:55:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:55:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:55:05 INFO - ^ 10:55:05 INFO - 1 warning generated. 10:55:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:55:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:55:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:55:06 INFO - Successfully installed psutil 10:55:06 INFO - Cleaning up... 10:55:06 INFO - Return code: 0 10:55:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:06 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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:55:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:55:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:06 INFO - 'HOME': '/Users/cltbld', 10:55:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:06 INFO - 'LOGNAME': 'cltbld', 10:55:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:06 INFO - 'MOZ_NO_REMOTE': '1', 10:55:06 INFO - 'NO_EM_RESTART': '1', 10:55:06 INFO - 'PAGER': '/bin/cat', 10:55:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:06 INFO - 'PWD': '/builds/slave/test', 10:55:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:06 INFO - 'SHELL': '/bin/bash', 10:55:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:06 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:06 INFO - 'USER': 'cltbld', 10:55:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:06 INFO - 'XPC_FLAGS': '0x0', 10:55:06 INFO - 'XPC_SERVICE_NAME': '0', 10:55:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:55:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:55:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:55:07 INFO - Installing collected packages: mozsystemmonitor 10:55:07 INFO - Running setup.py install for mozsystemmonitor 10:55:07 INFO - Successfully installed mozsystemmonitor 10:55:07 INFO - Cleaning up... 10:55:07 INFO - Return code: 0 10:55:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:55:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:55:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:55:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:08 INFO - 'HOME': '/Users/cltbld', 10:55:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:08 INFO - 'LOGNAME': 'cltbld', 10:55:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:08 INFO - 'MOZ_NO_REMOTE': '1', 10:55:08 INFO - 'NO_EM_RESTART': '1', 10:55:08 INFO - 'PAGER': '/bin/cat', 10:55:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:08 INFO - 'PWD': '/builds/slave/test', 10:55:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:08 INFO - 'SHELL': '/bin/bash', 10:55:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:08 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:08 INFO - 'USER': 'cltbld', 10:55:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:08 INFO - 'XPC_FLAGS': '0x0', 10:55:08 INFO - 'XPC_SERVICE_NAME': '0', 10:55:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:09 INFO - Downloading/unpacking jsonschema==2.5.1 10:55: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 10:55: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 10:55: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 10:55: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 10:55:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:55:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:11 INFO - Downloading functools32-3.2.3-2.tar.gz 10:55:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:55:11 INFO - warning: no files found matching '*.txt' 10:55:11 INFO - no previously-included directories found matching 'build' 10:55:11 INFO - no previously-included directories found matching 'dist' 10:55:11 INFO - no previously-included directories found matching '.git*' 10:55:11 INFO - Installing collected packages: jsonschema, functools32 10:55:11 INFO - Running setup.py install for functools32 10:55:11 INFO - warning: no files found matching '*.txt' 10:55:11 INFO - no previously-included directories found matching 'build' 10:55:11 INFO - no previously-included directories found matching 'dist' 10:55:11 INFO - no previously-included directories found matching '.git*' 10:55:11 INFO - Successfully installed jsonschema functools32 10:55:11 INFO - Cleaning up... 10:55:11 INFO - Return code: 0 10:55:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:55: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')]} 10:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55: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')]} 10:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55: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', '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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:55: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 functools32==3.2.3-2 10:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:11 INFO - 'HOME': '/Users/cltbld', 10:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:11 INFO - 'LOGNAME': 'cltbld', 10:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:11 INFO - 'MOZ_NO_REMOTE': '1', 10:55:11 INFO - 'NO_EM_RESTART': '1', 10:55:11 INFO - 'PAGER': '/bin/cat', 10:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:11 INFO - 'PWD': '/builds/slave/test', 10:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:11 INFO - 'SHELL': '/bin/bash', 10:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:11 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:11 INFO - 'USER': 'cltbld', 10:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:11 INFO - 'XPC_FLAGS': '0x0', 10:55:11 INFO - 'XPC_SERVICE_NAME': '0', 10:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:55:11 INFO - Cleaning up... 10:55:11 INFO - Return code: 0 10:55:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:55: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')]} 10:55:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55: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')]} 10:55:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55: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', '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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:55: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 blobuploader==1.2.4 10:55:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:11 INFO - 'HOME': '/Users/cltbld', 10:55:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:11 INFO - 'LOGNAME': 'cltbld', 10:55:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:11 INFO - 'MOZ_NO_REMOTE': '1', 10:55:11 INFO - 'NO_EM_RESTART': '1', 10:55:11 INFO - 'PAGER': '/bin/cat', 10:55:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:11 INFO - 'PWD': '/builds/slave/test', 10:55:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:11 INFO - 'SHELL': '/bin/bash', 10:55:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:11 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:11 INFO - 'USER': 'cltbld', 10:55:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:11 INFO - 'XPC_FLAGS': '0x0', 10:55:11 INFO - 'XPC_SERVICE_NAME': '0', 10:55:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:11 INFO - Downloading/unpacking blobuploader==1.2.4 10:55: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 10:55: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 10:55: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 10:55: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 10:55:13 INFO - Downloading blobuploader-1.2.4.tar.gz 10:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:55:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:55: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 10:55: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 10:55: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 10:55: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 10:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:55:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:55: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 10:55: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 10:55: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 10:55: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 10:55:14 INFO - Downloading docopt-0.6.1.tar.gz 10:55:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:55:14 INFO - Installing collected packages: blobuploader, requests, docopt 10:55:14 INFO - Running setup.py install for blobuploader 10:55:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:55:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:55:14 INFO - Running setup.py install for requests 10:55:14 INFO - Running setup.py install for docopt 10:55:15 INFO - Successfully installed blobuploader requests docopt 10:55:15 INFO - Cleaning up... 10:55:15 INFO - Return code: 0 10:55:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:15 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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:55:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:55:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:15 INFO - 'HOME': '/Users/cltbld', 10:55:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:15 INFO - 'LOGNAME': 'cltbld', 10:55:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:15 INFO - 'MOZ_NO_REMOTE': '1', 10:55:15 INFO - 'NO_EM_RESTART': '1', 10:55:15 INFO - 'PAGER': '/bin/cat', 10:55:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:15 INFO - 'PWD': '/builds/slave/test', 10:55:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:15 INFO - 'SHELL': '/bin/bash', 10:55:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:15 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:15 INFO - 'USER': 'cltbld', 10:55:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:15 INFO - 'XPC_FLAGS': '0x0', 10:55:15 INFO - 'XPC_SERVICE_NAME': '0', 10:55:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:55:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-msKkUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:55:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-b6QaQX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:55:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-u6CiBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:55:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-3O7EW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:55:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-L1bMdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:55:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:55:15 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-FEGyru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-Ikmow2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-zVceUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-jMs6m_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-AUwZaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-I_zqDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-SHmXPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-YeWrBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-7abgDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-oNGFAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:55:16 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-yKK1L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:55:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:55:17 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-MrkwGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:55:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:55:17 INFO - Running setup.py install for manifestparser 10:55:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:55:17 INFO - Running setup.py install for mozcrash 10:55:17 INFO - Running setup.py install for mozdebug 10:55:17 INFO - Running setup.py install for mozdevice 10:55:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:55:17 INFO - Running setup.py install for mozfile 10:55:17 INFO - Running setup.py install for mozhttpd 10:55:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:55:18 INFO - Running setup.py install for mozinfo 10:55:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:55:18 INFO - Running setup.py install for mozInstall 10:55:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:55:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:55:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:55:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:55:18 INFO - Running setup.py install for mozleak 10:55:18 INFO - Running setup.py install for mozlog 10:55:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:55:18 INFO - Running setup.py install for moznetwork 10:55:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:55:18 INFO - Running setup.py install for mozprocess 10:55:18 INFO - Running setup.py install for mozprofile 10:55:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:55:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:55:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:55:19 INFO - Running setup.py install for mozrunner 10:55:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:55:19 INFO - Running setup.py install for mozscreenshot 10:55:19 INFO - Running setup.py install for moztest 10:55:19 INFO - Running setup.py install for mozversion 10:55:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:55:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:55:19 INFO - Cleaning up... 10:55:19 INFO - Return code: 0 10:55:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:19 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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:55:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:55:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:19 INFO - 'HOME': '/Users/cltbld', 10:55:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:19 INFO - 'LOGNAME': 'cltbld', 10:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:19 INFO - 'MOZ_NO_REMOTE': '1', 10:55:19 INFO - 'NO_EM_RESTART': '1', 10:55:19 INFO - 'PAGER': '/bin/cat', 10:55:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:19 INFO - 'PWD': '/builds/slave/test', 10:55:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:19 INFO - 'SHELL': '/bin/bash', 10:55:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:19 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:19 INFO - 'USER': 'cltbld', 10:55:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:19 INFO - 'XPC_FLAGS': '0x0', 10:55:19 INFO - 'XPC_SERVICE_NAME': '0', 10:55:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-6PPtwb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-WkCtsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-pixttm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-xYcHWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:55:20 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)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-8SlJOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-4Jdr0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-1zVD84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-CneQw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-ywpRjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:55:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:55:20 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-BlL4NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:55:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-RgYQkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:55:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-sGDJqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:55:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-nTL2Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:55:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-CsGBzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-oZpZRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:55:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-2lqYDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:55:21 INFO - Running setup.py (path:/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/pip-ixhnaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:55:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:55:21 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)) 10:55:21 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)) 10:55:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:23 INFO - Downloading blessings-1.6.tar.gz 10:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:55:23 INFO - Installing collected packages: blessings 10:55:23 INFO - Running setup.py install for blessings 10:55:23 INFO - Successfully installed blessings 10:55:23 INFO - Cleaning up... 10:55:23 INFO - Return code: 0 10:55:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:55:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:55:23 INFO - Reading from file tmpfile_stdout 10:55:23 INFO - Current package versions: 10:55:23 INFO - blessings == 1.6 10:55:23 INFO - blobuploader == 1.2.4 10:55:23 INFO - docopt == 0.6.1 10:55:23 INFO - functools32 == 3.2.3-2 10:55:23 INFO - jsonschema == 2.5.1 10:55:23 INFO - manifestparser == 1.1 10:55:23 INFO - mozInstall == 1.12 10:55:23 INFO - mozcrash == 1.0 10:55:23 INFO - mozdebug == 0.1 10:55:23 INFO - mozdevice == 0.50 10:55:23 INFO - mozfile == 1.2 10:55:23 INFO - mozhttpd == 0.7 10:55:23 INFO - mozinfo == 0.9 10:55:23 INFO - mozleak == 0.1 10:55:23 INFO - mozlog == 3.4 10:55:23 INFO - moznetwork == 0.27 10:55:23 INFO - mozprocess == 0.25 10:55:23 INFO - mozprofile == 0.28 10:55:23 INFO - mozrunner == 6.13 10:55:23 INFO - mozscreenshot == 0.1 10:55:23 INFO - mozsystemmonitor == 0.3 10:55:23 INFO - moztest == 0.8 10:55:23 INFO - mozversion == 1.4 10:55:23 INFO - psutil == 3.1.1 10:55:23 INFO - requests == 1.2.3 10:55:23 INFO - wsgiref == 0.1.2 10:55:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:23 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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:55:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:23 INFO - 'HOME': '/Users/cltbld', 10:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:23 INFO - 'LOGNAME': 'cltbld', 10:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:23 INFO - 'MOZ_NO_REMOTE': '1', 10:55:23 INFO - 'NO_EM_RESTART': '1', 10:55:23 INFO - 'PAGER': '/bin/cat', 10:55:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:23 INFO - 'PWD': '/builds/slave/test', 10:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:23 INFO - 'SHELL': '/bin/bash', 10:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:23 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:23 INFO - 'USER': 'cltbld', 10:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:23 INFO - 'XPC_FLAGS': '0x0', 10:55:23 INFO - 'XPC_SERVICE_NAME': '0', 10:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:55:24 INFO - Cleaning up... 10:55:24 INFO - Return code: 0 10:55:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:24 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 0x10fcffb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fd03768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa925a6160>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, '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.6QmAqkB1AM/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.yNQeh3JErO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:55:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:55:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:24 INFO - 'HOME': '/Users/cltbld', 10:55:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:24 INFO - 'LOGNAME': 'cltbld', 10:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:24 INFO - 'MOZ_NO_REMOTE': '1', 10:55:24 INFO - 'NO_EM_RESTART': '1', 10:55:24 INFO - 'PAGER': '/bin/cat', 10:55:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:24 INFO - 'PWD': '/builds/slave/test', 10:55:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:24 INFO - 'SHELL': '/bin/bash', 10:55:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:24 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:24 INFO - 'USER': 'cltbld', 10:55:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:24 INFO - 'XPC_FLAGS': '0x0', 10:55:24 INFO - 'XPC_SERVICE_NAME': '0', 10:55:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:55:24 INFO - Cleaning up... 10:55:24 INFO - Return code: 0 10:55:24 INFO - Running post-action listener: _resource_record_post_action 10:55:24 INFO - Running post-action listener: _start_resource_monitoring 10:55:24 INFO - Starting resource monitoring. 10:55:24 INFO - [mozharness: 2017-05-19 17:55:24.437967Z] Finished create-virtualenv step (success) 10:55:24 INFO - [mozharness: 2017-05-19 17:55:24.438972Z] Running install step. 10:55:24 INFO - Running pre-action listener: _resource_record_pre_action 10:55:24 INFO - Running main action method: install 10:55:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:55:24 INFO - Reading from file tmpfile_stdout 10:55:24 INFO - Detecting whether we're running mozinstall >=1.0... 10:55:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:55:24 INFO - Reading from file tmpfile_stdout 10:55:24 INFO - Output received: 10:55:24 INFO - Usage: mozinstall [options] installer 10:55:24 INFO - Options: 10:55:24 INFO - -h, --help show this help message and exit 10:55:24 INFO - -d DEST, --destination=DEST 10:55:24 INFO - Directory to install application into. [default: 10:55:24 INFO - "/builds/slave/test"] 10:55:24 INFO - --app=APP Application being installed. [default: firefox] 10:55:24 INFO - mkdir: /builds/slave/test/build/application 10:55:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:55:47 INFO - Reading from file tmpfile_stdout 10:55:47 INFO - Output received: 10:55:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:55:47 INFO - Running post-action listener: _resource_record_post_action 10:55:47 INFO - [mozharness: 2017-05-19 17:55:47.432796Z] Finished install step (success) 10:55:47 INFO - [mozharness: 2017-05-19 17:55:47.432956Z] Running run-tests step. 10:55:47 INFO - Running pre-action listener: _resource_record_pre_action 10:55:47 INFO - Running main action method: run_tests 10:55:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:55:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:55:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:55:47 INFO - 2017-05-19 10:55:47.447 screenresolution[1627:7421] starting screenresolution argv=screenresolution get 10:55:47 INFO - 2017-05-19 10:55:47.450 screenresolution[1627:7421] Display 0: 1600x1200x32@60 10:55:47 INFO - 2017-05-19 10:55:47.456 screenresolution[1628:7423] starting screenresolution argv=screenresolution list 10:55:47 INFO - Available Modes on Display 0 10:55:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:55:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:55:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:55:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:55:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:55:47 INFO - Intel Iris: 10:55:47 INFO - Chipset Model: Intel Iris 10:55:47 INFO - Type: GPU 10:55:47 INFO - Bus: Built-In 10:55:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:55:47 INFO - Vendor: Intel (0x8086) 10:55:47 INFO - Device ID: 0x0a2e 10:55:47 INFO - Revision ID: 0x0009 10:55:47 INFO - Displays: 10:55:47 INFO - Display: 10:55:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:55:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:55:47 INFO - Display Serial Number: 1600x1200 60 10:55:47 INFO - Main Display: Yes 10:55:47 INFO - Mirror: Off 10:55:47 INFO - Online: Yes 10:55:47 INFO - Rotation: Supported 10:55:47 INFO - Return code: 0 10:55:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:55:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:55:47 INFO - Python 2.7.3 10:55:47 INFO - Return code: 0 10:55:47 INFO - grabbing minidump binary from tooltool 10:55:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:47 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 0x10fa0fd30>, '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 0x10f9c3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fa04be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:55:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:55:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:55:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:55:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:55:47 INFO - Return code: 0 10:55:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:55:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:55:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:55:47 INFO - ENV: RUST_BACKTRACE is now 1 10:55:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:55:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:55:47 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/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:55:47 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/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0323 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:55:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.6QmAqkB1AM/Render', 10:55:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:47 INFO - 'HOME': '/Users/cltbld', 10:55:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:47 INFO - 'LOGNAME': 'cltbld', 10:55:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:55:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:55:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:47 INFO - 'MOZ_NO_REMOTE': '1', 10:55:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:55:47 INFO - 'NO_EM_RESTART': '1', 10:55:47 INFO - 'PAGER': '/bin/cat', 10:55:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:47 INFO - 'PWD': '/builds/slave/test', 10:55:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:55:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:47 INFO - 'RUST_BACKTRACE': '1', 10:55:47 INFO - 'SHELL': '/bin/bash', 10:55:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yNQeh3JErO/Listeners', 10:55:47 INFO - 'TMPDIR': '/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/', 10:55:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:47 INFO - 'USER': 'cltbld', 10:55:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:47 INFO - 'XPC_FLAGS': '0x0', 10:55:47 INFO - 'XPC_SERVICE_NAME': '0', 10:55:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:47 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/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0323', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:55:48 INFO - mozversion application_buildid: 20170519083940 10:55:48 INFO - mozversion application_changeset: 1ea1b40c2679da341d50d27226c61033bac06711 10:55:48 INFO - mozversion application_display_name: Nightly 10:55:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:55:48 INFO - mozversion application_name: Firefox 10:55:48 INFO - mozversion application_remotingname: firefox 10:55:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:55:48 INFO - mozversion application_vendor: Mozilla 10:55:48 INFO - mozversion application_version: 55.0a1 10:55:48 INFO - mozversion platform_buildid: 20170519083940 10:55:48 INFO - mozversion platform_changeset: 1ea1b40c2679da341d50d27226c61033bac06711 10:55:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:55:48 INFO - mozversion platform_version: 55.0a1 10:55:48 INFO - using testdate: 1495216548 10:55:48 INFO - actual date: 1495216548 10:55:48 INFO - starting webserver on 'localhost:49228' 10:55:48 INFO - SUITE-START | Running 2 tests 10:55:48 INFO - TEST-START | dromaeo_css 10:55:48 INFO - operating with platform_type : mac_ 10:55:48 INFO - Initialising browser for dromaeo_css test... 10:55:48 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpUezp82/profile http://localhost:49228/getInfo.html) 10:55:48 INFO - PID 1638 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2941: String.localeCompare is deprecated; use String.prototype.localeCompare instead 10:55:50 INFO - PID 1638 | 2017-05-19 10:55:50.420 plugin-container[1640:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c3b, name = 'com.apple.tsm.portname' 10:55:50 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:50 INFO - PID 1638 | 2017-05-19 10:55:50.423 plugin-container[1640:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 10:55:50 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:50 INFO - PID 1638 | 2017-05-19 10:55:50.423 plugin-container[1640:7593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:55:50 INFO - PID 1638 | __metrics Screen width/height:1600/1200 10:55:50 INFO - PID 1638 | colorDepth:24 10:55:50 INFO - PID 1638 | Browser inner width/height: 1024/691 10:55:50 INFO - PID 1638 | __metrics 10:55:51 INFO - TEST-INFO | 1638: exit 0 10:55:51 INFO - Browser initialized. 10:55:51 INFO - Running cycle 1/1 for dromaeo_css test... 10:55:51 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpUezp82/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:55:53 INFO - PID 1642 | 2017-05-19 10:55:53.248 plugin-container[1643:7742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 10:55:53 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:53 INFO - PID 1642 | 2017-05-19 10:55:53.248 plugin-container[1643:7742] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 10:55:53 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:53 INFO - PID 1642 | 2017-05-19 10:55:53.248 plugin-container[1643:7742] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:55:53 INFO - PID 1642 | 2017-05-19 10:55:53.963 plugin-container[1644:7794] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 10:55:53 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:53 INFO - PID 1642 | 2017-05-19 10:55:53.964 plugin-container[1644:7794] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 10:55:53 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:53 INFO - PID 1642 | 2017-05-19 10:55:53.964 plugin-container[1644:7794] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:56:03 INFO - PID 1642 | RSS: Main: 237629440 10:56:03 INFO - PID 1642 | 10:59:07 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 10:59:08 INFO - PID 1642 | RSS: Main: 259735552 10:59:08 INFO - PID 1642 | 11:02:10 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 11:02:10 INFO - PID 1642 | RSS: Main: 268304384 11:02:10 INFO - PID 1642 | 11:05:12 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 11:05:13 INFO - PID 1642 | RSS: Main: 272764928 11:05:13 INFO - PID 1642 | 11:08:14 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 11:08:15 INFO - PID 1642 | RSS: Main: 270462976 11:08:15 INFO - PID 1642 | 11:11:18 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 11:11:18 INFO - PID 1642 | RSS: Main: 272003072 11:11:18 INFO - PID 1642 | 11:14:20 INFO - PID 1642 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 11:14:21 INFO - PID 1642 | RSS: Main: 273485824 11:14:21 INFO - PID 1642 | 11:14:21 INFO - PID 1642 | __start_tp_report 11:14:21 INFO - PID 1642 | _x_x_mozilla_page_load 11:14:21 INFO - PID 1642 | _x_x_mozilla_page_load_details 11:14:21 INFO - PID 1642 | |i|pagename|runs| 11:14:21 INFO - PID 1642 | |0;dojo.html;3462.792232466052;3585.748997570168;3660.524131862861;3665.2669466106768;3668.413053911375;5253.60597955153;5610.326760788701;5881.647101173932;6176.135341052256;6305.653189074599;5118.974405127979;6224.253089629239;6224.626522408658;6243.875122497548;6341.7146228419715;4996.400431948171;5484.725763711818;5491.670499770006;5505.476979686923;5511.311086114246;4591.931121033187;4986.4265609455015;5584.4136365681625;5600.327960644729;5637.859053523674;5411.621186516945;5434.076207044812;5545.334559852803;5589.832305030855;5632.408597097307;5529.087700529408;5572.331320241557;5573.247611572427;5604.019296623115;5615.9157612635845;5536.778528858841;5540.141278101901;5540.168974653794;5557.472040156219;5559.305086864142;6114.694265286718;6319.115323854664;6319.304876463631;6339.080833279195;6346.587471814318;6533.93466065338;6588.538802283842;6699.162604674416;6776.42400395967;6778.694958726869;6698.56459330142;6702.597844129319;6702.994550817368;6710.3289671033;6730.9326906731285;6122.602030867979;6288.496920246369;6358.1734374531015;6388.105665206875;6390.0734393512685;6776.864462710718;6831.487638427137;6842.55523390978;6877.965610171917;6911.894096944465;6667.6666166691475;6678.065070890079;6693.866122677519;6706.96646516774;6740.561863478858;6587.176602924635;6786.4910131739325;6872.69072891721;6887.345702158287;6903.654817259217;6579.144711187515;6594.86810263792;6667.433268172163;6781.287964763727;6783.796486105425;6775.457963362919;6781.220159681601;6788.355106264996;6789.93210067893;6809.216940051859;5280.392754833167;5349.411564727877;5372.301600791872;5422.810201642924;5456.7544460499375;4442.756028312077;4445.933311000337;4785.832495862698;4840.878978025577;4916.336294600254;6364.522660800459;6364.840878978062;6368.235811702625;6371.681415929185;6373.107764912916;6825.249222585511;6876.106106206161;6895.931040689629;6907.274736152732;6937.445004399636;5721.485066343966;5975.19334889793;6310.842228944313;6347.682615869188;6366.936330636634;5005.89988200234;5987.101934709828;6053.7578496860515;6084.148219249309;6106.847328816726;6952.061471701357;7026.086608740831;7036.50744447894;7041.225465198721;7069.222385537587;6607.108040414482;6718.630475323904;6886.655667216719;6896.344847239705;6909;6955.513114082168;7016.158061032709;7022.859542809218;7055.506114571931;7057.717691292496;6737.494687898526;6790.185177778698;6795.864082718417;6803.149606299213;6809.863802724017;6444.163413427151;6651.567648102858;6868.965655171693;6930.445564354739;6939.791806245821;6431.099646049463;6556.0493728410065;6619.205695316593;6649.135612370361;6671.966640166768;6686.732530698718;6691.1636045494315;6691.197056353269;6694.263631000684;6698.899516507354;5578.693171875586;6262.624242545462;6345.270293916259;6528.575642583217;6530.216374035146;6669.666516674244;6700.162479690039;6701.932980670131;6702.229243637044;6740;5288.603354748332;6306.905396418965;6360.173177486897;6461.0954466373805;6521.152250207443 11:14:21 INFO - PID 1642 | |1;ext.html;794.3406972213064;814.4502460838933;840.7771940435786;1226.748516554106;1289.0718682548572;26474.867625661893;37330.06674833127;37478.62521374785;37651.81174094129;37683.62316376835;37890.4316435253;38261.852144435645;38280.617193828126;38333.23333533328;38335.61664383356;7897.368210543157;8060.355171586259;8271.17288271174;8396.916030839675;8434.957825210866;8390.748277551684;8441.198086181803;8466.74599762005;8486.66053357865;8497.320214382866;8408.873866892001;8416.410851240416;8471.983361996568;8491.830163396728;8515.95742021289;8346.8330633387;8399.454035487704;8443.915560844374;8451.408401411903;8462.19609137128;7821.374290056839;7918;8243.711470098518;8254.05078415978;8304.584770761498;36680.082997925;36834.447483287775;36900.07749806249;37012.62987370119;37219.69731059427;3671;3702.500162478085;3716.591174970751;3718.3492888744363;3719.9070023249365;29986.85006574975;30161.849190754125;30181.396372072435;30274.394512109855;30428;3029.6364436267568;3054.1906394805396;3074;3075.9846200769075;3086.922826929345;239693;305152;305597;306543;309495;47468;47571.810704731965;47649.52350476451;47760.52239477561;47866.760666196446;48868.77828054327;48986.75506622444;54374;54655.453445465835;55241.723791380784;12389.690257743629;13984.230867302193;14287.357068931922;14395.064320819114;14444.422223111169;9385.436873787457;10969.945150274198;11475.598354057567;11537.192396532162;11579.305241685526;13817.654558636115;13832.792508112387;13872.583822485341;13880.375383107785;14146;5141.3830340358645;6241.438270555672;6242.500599951993;6252.593581417284;6285;849.0109022988149;1028.861103750999;1059.3432072115338;1064.2869277584036;1086.7011571817814;21593.67609485859;23517.17689880846;23679.644805327935;23959.281221563382;24686.01255949778;935.7520257131865;1002.588938535197;1139.168407062832;1169.789437901186;1173.8356630071796;12275.877241227652;12613.87386126127;12637.241765493916;12687.619371418872;12690;263822;284073;284805.5759721188;299646;300888;53909.730451347496;53968.190477142874;54361;54471.008189099164;54634.45365546373;1547.048565132458;1551.6276093737422;1571.198688668787;1586.7302558565073;1596.481143628302;24315.63526547068;24466;24488.265352039467;24507;24924.875375623007;38212.47150113964;50295;51109.977800444525;51179.232311514614;51340.486595133574;205822;252468.4753152445;253569;254244;254552.45447545286;35793.28413431769;35960;35981.82009090042;35989;36191.638083618825;43742.78128609336;44024.27684401008;44058.55941440545;45173.322400163364;45500.31749523826;29872.402551949275;30626.081217563507;31166;31351.84324078365;31683.683163168072;28033.159005229874;28327.57508637331;30716.385672286877;31317.686823131477;31505.52741708922;37783.2443351137;37933.81033094817;37986.62013379941;38007.2398552033;38223.617763822;21790.673139902596;27809;29412.55881161827;29845.70154298516;30536.389272213986;24368.147114850894;24674.383140421633;24695.62956555689;24748.30297067136;24979.87590558393 11:14:21 INFO - PID 1642 | |2;jquery.html;396.3519645379804;409.5945014435708;416.190509459102;424.64329962831215;439.9406080179176;20614.48463788406;21243.468913277175;21504.784952150494;22072.668909966364;22267.997940092693;23004.194853180143;23090.30729078126;23213;23644.290671279847;23710.288691339287;6011.218541589587;6130.264368275791;6146.416090471398;6199.070139479091;6204.224471941007;5955.583109182359;6156.138140660311;6160.845978850542;6221.253449586056;6233;5949.464548190662;6116.0825876118715;6174.629522228681;6187.752489900398;6203.627782333052;6051.394860513958;6120.081987701836;6155.353687862755;6168.660723660197;6180.443760061571;4919.94221242431;4933.580645645125;5279;5371.543418809406;5476.890462190774;26350.209493715218;27463.86268068647;27792.02727904534;27931.301717457023;28053.438931221262;2163.8485306028583;2222.0889435331596;2233.7319521657505;2235.407616981501;2241.1595651630637;14816.925915370353;17595;17735.556611084827;17790.46628601144;17955.551111222194;2072.585482903425;2095.109578429177;2131.1475409836034;2134.797194266542;2137.9893100534396;146858;148401;149331.50668493286;152481.9999999989;154047;12363.381830908598;12826.294553799631;12893.161944473766;12970.416331265116;12981.221126732426;15483.380664773507;16228.350865965229;16451.83548164527;16550.83449165517;16552.84130110905;8142.47073940192;8679.609417576225;8956.8656470153;9054.049324820931;9151.039140890243;8446.155384461505;8450.070492245848;8484.915150848536;8610.741677749678;8623.396362254707;7886;8012.479188852706;8025.036995560454;8068.757937261891;8072.515649060958;2201.878896660699;2209.3482422685347;2214.933551993443;2220.8445408821226;2393.1419814817536;939.6758118449125;957.5260246178186;990.5047476261869;1018.5365658625308;1023.8617786598862;20261.088251028737;20631.58736825285;20896.686549701768;20979.47551311199;20982.475438113863;1011.145581983223;1021;1031.83490641497;1045.2265323660436;1049.994750026245;10645.40318952148;10707.839382409271;10778.568857245622;10783.46082695862;10837;480471;482237.5888120607;488619;496127.63553276187;496465;97650;118196.40901795436;121467.17799233018;122000;124827;1172.9120315976165;1214.2714371377103;1229.360732419137;1232.4515590562294;1235.042841797596;19119.75721578093;21286.78713212848;21923;22336;22423;55858.720706396205;57045.42954570401;57142.42857571371;57816.421835781104;59020.14697795365;71432.64283678548;71562;72171.63914180396;72286;72768;9069.591868366071;9087;9214.769630759285;9217.400868943498;9273.907260927304;13871;14429.134251944917;14512.927435362755;14881.032732872329;14896.553103406915;11801.881981180079;11861.644150675493;11995;12064.879351206375;12237.938810305892;11199.824018477942;11254.493547790205;11555.4800033997;11649.941750291195;11911.940440297743;9350.906490935276;12216.938915305367;12536.059795515555;12627.558035468715;12635.05237107239;8809.779755506164;8839.425437346552;8949.284057275532;9217.86173207389;9261.953690231505;11880.524779008743;11938.403079845799;12099.516019359127;12110.939445302716;12200.328981905725 11:14:21 INFO - PID 1642 | |3;mootools.html;1274.7514234724233;1396.371632765256;1419.2548911821293;1456.6649670575766;1496.7605183170695;10020.498975051254;11380.772384552303;11523.13576481763;11875.99054080403;11915.582954596592;11984.460699268531;12138.089643276768;12208.389580520961;12209.633710988684;12210.450529726162;2161.416417567256;2538.8984440622353;2598.9610155847677;2599.090318388568;2604.9088281910135;2429.866357350345;2500.949981000388;2559.5904655255163;2611.308003379106;2617.4634199988955;2225.688403623494;2592.7277635848245;2594.0661361909697;2616.3459135216194;2622.8557429341377;2564.230730780758;2570.935726606831;2571.2414837622946;2571.897124115033;2573.9742602574015;1928.9614207715767;2537.479816637585;2564.6153077038407;2569.306287302418;2664.547027005414;11629.534818607246;11870.940645296805;11890.989265912407;11894.762104757943;11910.928016478472;6577.079208910708;6807.4894382921475;6812.897806532906;6831.316868313179;6842.589444633338;3977.7016723745546;4290.377895205209;4320.17916595849;4396.20868243716;4398.692091553593;1120.4621781544904;1140.201858698914;1143.5654451308533;1241.4351470081092;1289.071868254855;16587.502374928772;16640.251188696537;16712.49862504127;16737.163141842982;16758.83241167585;5386.245925570423;5609.915851262234;5630.239917611152;5669.6314739541795;5723.828285151452;11551.88448115508;11708.414579271002;11724.648260552198;11725.882741172649;11742.941285293518;6143.109249158847;6491.123698300763;6514.739410423625;6544.836379090466;6560.114384558024;5805.332386775491;6194.938050619436;6209.9068513972325;6220.098085777537;6226.906596401058;3844.9231015380096;4338.7200775771225;4365.563443655609;4397.362382454527;4475.5748203920575;1267.8034904589804;1295.7861952777687;1312.3831799054428;1313.481174935895;1313.678148853537;799.8800179972934;800.3597122302135;825.7481468152271;831.3141658132065;857.8670306102564;5507.697076660822;6517.478601711851;6659.067730517731;6750.257471678112;6879.6560171991205;817.0930267403178;818.9073294687506;942.5617088054088;991.4447909170743;1099.8460215569826;3386.745994050456;3403.9489407658907;4316.827326906951;4324;4519.412476378116;14418.279086045655;14725;15012.47456339023;15076.773848392282;15205.771913421308;14695.191764452844;14857.108573485626;14934.25328733559;14954.775678364833;14976.925115374572;7448.664810083559;7986.281234688906;8110.594470276581;8137;8171.957010747313;6369.20384951881;6777.695003724943;7031.296870312928;7176.677049532888;7294.416446684147;12046.578369757017;12305.507779688713;13461.730765384833;13587.52443664467;13803.10279831848;11347;13596.728065438438;13946.721065578835;14134.78797818054;14249.216293103564;9461.195798357217;9463.952680236554;9464.242860571276;9597.328187026842;9628.133467987915;9731.12419882214;10594.576216951236;10707.571697132027;10848.132149428184;10866.728331791452;7424.777256682308;8312.750617481484;9123.673463266825;9235.399699019543;9292.58183381763;8997.190252877272;9047;9066.456012639263;9095.181433671001;9099.1810737034;8136.471129376571;8365;8372.832543348977;8385.83228335442;8411.495310281403;5220.9477905220465;5903.881922361443;6118.785842495491;6134.294556125924;6310.400511951358;5412.512873841216;6129.325774164749;6129.90312847394;6247.12540244357;6317.5893566918 11:14:21 INFO - PID 1642 | |4;prototype.html;338.8034939734954;398.31688653958463;398.6013986013986;406.99186016279657;408.4547129582008;5157.819476318335;5824.592613321207;6282.623042617446;6315.305316415191;6422.486201103912;4788.592969597588;6325.051242313655;6325.209348831396;6360.236771587404;6360.459360954324;4622.6533010024295;4627.791749371269;4634.844797169316;4651.209294419957;4654.441467023945;4010.7994600270017;4202.390653355262;4621.58405743483;4651.395318608576;4660.4174478190225;3785.848566057354;4586.8394606188795;4589;4611.769411529427;4612.308153776927;4623.398958135421;4636.516314779272;4641.767911604406;4644.373009643706;4653.86038418848;3236.951445728316;3437.731097475654;3890.7860067696265;3900.2394533066063;3951.624595663436;6142.710030893518;6236.6258024518675;6276.278228003746;6293.937060629427;6295.779647712354;5087.872803179913;5459.071957767189;5518.475744804237;5522.447755224485;5527.640703354269;5965;6703.061571380011;6849.349311815369;6922.757703480404;6946.895796563055;4360;5008.474110218447;5020;5023.598112151019;5040.193569028975;7145.53231751787;7172.820679483002;7219;7249.673764680602;7257.983882256488;4340.2187606230555;5012.24816277554;5125.384953805567;5126.743947732829;5148.716820574904;4502.909941801146;4656.301554766745;5063.974680126649;5065.493450654979;5221.321228240305;4767.879548306145;5168.457311982263;5275.391005743209;5590.720463976785;5688.132070192309;6775.966120169466;6826.180858297036;6840.965795170992;6844.315568443216;6911.136107986502;1720.6386658801791;1772.53027947594;1783;1787.1511032259832;1821.298799962024;1243.9502419903104;1248.2448118888124;1256.616731896762;1263.0968857267098;1269.1686945051006;5344.198370244494;6350.793349263625;6396.0405939109505;6422.261439934374;6424.38968298011;5974.790882319098;6217.253929528486;6965.233824279325;7082.39799617028;7082.5396349237135;5686.061799803069;6152.78465253714;6365.7268546290925;6473.122794389575;6515.73937042513;3335.232896433834;3710.18375957289;3721.813909304524;3730.235301763132;3746.4754934309217;6351;6950.165980082322;7024.754133605299;7228.1326240850385;7308.6345682715655;7005.369516743517;7319.634018299063;7333.4866559340335;7353.669084891193;7358.8528229435115;6089.117078023751;6509.127069393703;7161.928380716126;7195.6761945713615;7312.670929808065;5952.422608008795;6516.902246466401;7122.394596459359;7126.714931402686;7231.2768723127265;6742.73029078851;6950.54821436605;7020.7893763187185;7026.086608740831;7071.010058591904;6700.296468870698;6751.771915268048;7285.344319011315;7332.156801967629;7350.228226036189;3667.266546690726;4127.628513433805;4510.1656193604285;4522.389477420637;4540.137373898949;7176.102987126609;7189.424846012202;7191.7842464726145;7201.783946481614;7305.2329505403295;5593.9440605595055;5784.165758778253;5878.767287552955;6833.3166683331265;6937.202221744564;5937.168796368425;6161.72272247741;6409.974404095322;6416.620426608115;6913.41235994926;5814.476697097281;6156.384361563987;6510.674466276572;6774.746671865721;6775.86448271022;6682.298358672464;6890.552114112566;6893.482988775962;6893.965530172317;6899.275576064441;6578.901316480353;6613.702383392662;6676.9332306676315;6724.831879203059;6751.45988320943 11:14:21 INFO - PID 1642 | |5;yui.html;701.9929800701992;816.534575292083;845.3068483843251;852.869947319801;859.3468963587671;14558.563243102697;15206.543803685907;16307.59231019225;16737.665246695058;16783.74824377634;16885.24016419261;17592.472225833244;17612.647747045085;17623.471295861113;18341.816581834177;974.3569244298765;977.7018009507098;991.7124034030159;994.1847684898386;997.2919227348555;1005.3214080495673;1009.1573536097368;1012.3217444312291;1012.7063151686002;1016.8474728790667;1052.1582733812918;1058.544825724938;1062.8618279623677;1065.169168048923;1066.2323127348297;1053.1311667873997;1059.3008614314554;1059.6397224943555;1060.8249638809586;1061.3631820907433;663.6262935723055;727.4035291061332;770.838123993962;774.7327172125664;779.3570304498811;14778.187199704013;16859.24133413999;17069.48791536256;17074.914625426918;17089.658206835917;16320.591985200346;16371.508854734377;16808.15959202035;16911.66176676472;16992.830071699373;11994.820077698841;14051.50819721315;14342.856571434257;14344.928275358661;14380;3465;3563.127033876691;3572.4462708280257;3597.0467826026124;3794.3739283018267;104430.47784761028;113921;114311.42844285726;116635.41682291534;117152.41423792827;13115.934420327838;13355.131916425402;13444.865551344557;13453.798193027113;13526.32368381577;21040.684389734168;25258;25499.617505737428;25822.48355032889;25861;6735.191776986793;7676.424268179909;7912.248336408032;7939.682412703427;7949.205079492121;3978.900527486836;4053.9391909121387;4060.6954478414236;4064.918701625951;4078.286299897506;4175.749455032707;4231.259529582311;4323.459567554056;4342.457192850894;4383.62739167168;848.7411339541376;890.5191196753681;897.8249241397865;898.2185498616245;899.0290486274938;639.5139693832721;646.8867948109062;647.9643619600873;657.1489920552896;680.7243066557953;10651.615290012249;11431.14266430021;11896.643100706993;12027.819582706266;12093.093018023683;652.1001018594404;653.7646447278976;658.1016911915201;671.214678825775;678.4550722475028;5401.162819762943;5557.944420555743;5603.187537806995;5649;5710.971445142831;102679;102989.48505257575;104039;104159.4792026035;107022;92790.60814087794;94444;94466.52766736122;94647;95795;82174.58912705518;82527;83538.5823070893;84184.73722894039;84645;11099.389533575732;11287.43562821872;11317.207795454238;11377.203595748219;11511.712207194887;39152;39232.215355692155;39413;39466.40800388054;39728.60271397249;49459.76350591264;49913.500864990885;50123.74690632764;50255.49744502508;50736.74631626818;13682.247476388895;13988.510402135877;14701.485448009269;15602.219889005732;16753.74869376985;18031.188596512926;18513.0743462829;18729.157187926303;18786.436406907815;18815.247390104243;14055;15462.458813941457;16200.837991619934;17463.12684365802;17593.120343983006;14122.576322710334;16211.756823647418;16516.587085322484;16963.321467141177;17109.23008464647;16507.422240221535;16560.420385286456;16787.580310492336;16830.66338673195;16918.915405422893;10725.678229653124;10922.672319830417;11515.905988931037;12001.979831714401;12334.50661973511;10834.945825270823;13396.93301533486;13813.930930345285;13916.791248131489;14011.08927919682 11:14:21 INFO - PID 1642 | __end_tp_report 11:14:21 INFO - PID 1642 | __start_cc_report 11:14:21 INFO - PID 1642 | _x_x_mozilla_cycle_collect,348 11:14:21 INFO - PID 1642 | __end_cc_report 11:14:21 INFO - PID 1642 | __startTimestamp1495217661012__endTimestamp 11:14:21 INFO - PID 1642 | 11:14:21 INFO - PID 1642 | ------- Summary: start ------- 11:14:21 INFO - PID 1642 | Number of tests: 6 11:14:21 INFO - PID 1642 | 11:14:21 INFO - PID 1642 | [#0] dojo.html Cycles:165 Average:6204.53 Median:6444.16 stddev:775.07 (12.0%) stddev-sans-first:747.01 11:14:21 INFO - PID 1642 | Values: 3462.8 3585.7 3660.5 3665.3 3668.4 5253.6 5610.3 5881.6 6176.1 6305.7 5119.0 6224.3 6224.6 6243.9 6341.7 4996.4 5484.7 5491.7 5505.5 5511.3 4591.9 4986.4 5584.4 5600.3 5637.9 5411.6 5434.1 5545.3 5589.8 5632.4 5529.1 5572.3 5573.2 5604.0 5615.9 5536.8 5540.1 5540.2 5557.5 5559.3 6114.7 6319.1 6319.3 6339.1 6346.6 6533.9 6588.5 6699.2 6776.4 6778.7 6698.6 6702.6 6703.0 6710.3 6730.9 6122.6 6288.5 6358.2 6388.1 6390.1 6776.9 6831.5 6842.6 6878.0 6911.9 6667.7 6678.1 6693.9 6707.0 6740.6 6587.2 6786.5 6872.7 6887.3 6903.7 6579.1 6594.9 6667.4 6781.3 6783.8 6775.5 6781.2 6788.4 6789.9 6809.2 5280.4 5349.4 5372.3 5422.8 5456.8 4442.8 4445.9 4785.8 4840.9 4916.3 6364.5 6364.8 6368.2 6371.7 6373.1 6825.2 6876.1 6895.9 6907.3 6937.4 5721.5 5975.2 6310.8 6347.7 6366.9 5005.9 5987.1 6053.8 6084.1 6106.8 6952.1 7026.1 7036.5 7041.2 7069.2 6607.1 6718.6 6886.7 6896.3 6909.0 6955.5 7016.2 7022.9 7055.5 7057.7 6737.5 6790.2 6795.9 6803.1 6809.9 6444.2 6651.6 6869.0 6930.4 6939.8 6431.1 6556.0 6619.2 6649.1 6672.0 6686.7 6691.2 6691.2 6694.3 6698.9 5578.7 6262.6 6345.3 6528.6 6530.2 6669.7 6700.2 6701.9 6702.2 6740.0 5288.6 6306.9 6360.2 6461.1 6521.2 11:14:21 INFO - PID 1642 | 11:14:21 INFO - PID 1642 | [#1] ext.html Cycles:180 Average:43278.78 Median:35876.64 stddev:72309.55 (201.6%) stddev-sans-first:72442.04 11:14:21 INFO - PID 1642 | Values: 794.3 814.5 840.8 1226.7 1289.1 26474.9 37330.1 37478.6 37651.8 37683.6 37890.4 38261.9 38280.6 38333.2 38335.6 7897.4 8060.4 8271.2 8396.9 8435.0 8390.7 8441.2 8466.7 8486.7 8497.3 8408.9 8416.4 8472.0 8491.8 8516.0 8346.8 8399.5 8443.9 8451.4 8462.2 7821.4 7918.0 8243.7 8254.1 8304.6 36680.1 36834.4 36900.1 37012.6 37219.7 3671.0 3702.5 3716.6 3718.3 3719.9 29986.9 30161.8 30181.4 30274.4 30428.0 3029.6 3054.2 3074.0 3076.0 3086.9 239693.0 305152.0 305597.0 306543.0 309495.0 47468.0 47571.8 47649.5 47760.5 47866.8 48868.8 48986.8 54374.0 54655.5 55241.7 12389.7 13984.2 14287.4 14395.1 14444.4 9385.4 10969.9 11475.6 11537.2 11579.3 13817.7 13832.8 13872.6 13880.4 14146.0 5141.4 6241.4 6242.5 6252.6 6285.0 849.0 1028.9 1059.3 1064.3 1086.7 21593.7 23517.2 23679.6 23959.3 24686.0 935.8 1002.6 1139.2 1169.8 1173.8 12275.9 12613.9 12637.2 12687.6 12690.0 263822.0 284073.0 284805.6 299646.0 300888.0 53909.7 53968.2 54361.0 54471.0 54634.5 1547.0 1551.6 1571.2 1586.7 1596.5 24315.6 24466.0 24488.3 24507.0 24924.9 38212.5 50295.0 51110.0 51179.2 51340.5 205822.0 252468.5 253569.0 254244.0 254552.5 35793.3 35960.0 35981.8 35989.0 36191.6 43742.8 44024.3 44058.6 45173.3 45500.3 29872.4 30626.1 31166.0 31351.8 31683.7 28033.2 28327.6 30716.4 31317.7 31505.5 37783.2 37933.8 37986.6 38007.2 38223.6 21790.7 27809.0 29412.6 29845.7 30536.4 24368.1 24674.4 24695.6 24748.3 24979.9 11:14:21 INFO - PID 1642 | 11:14:21 INFO - PID 1642 | [#2] jquery.html Cycles:180 Average:33395.71 Median:12285.70 stddev:83307.46 (678.1%) stddev-sans-first:83504.32 11:14:21 INFO - PID 1642 | Values: 396.4 409.6 416.2 424.6 439.9 20614.5 21243.5 21504.8 22072.7 22268.0 23004.2 23090.3 23213.0 23644.3 23710.3 6011.2 6130.3 6146.4 6199.1 6204.2 5955.6 6156.1 6160.8 6221.3 6233.0 5949.5 6116.1 6174.6 6187.8 6203.6 6051.4 6120.1 6155.4 6168.7 6180.4 4919.9 4933.6 5279.0 5371.5 5476.9 26350.2 27463.9 27792.0 27931.3 28053.4 2163.8 2222.1 2233.7 2235.4 2241.2 14816.9 17595.0 17735.6 17790.5 17955.6 2072.6 2095.1 2131.1 2134.8 2138.0 146858.0 148401.0 149331.5 152482.0 154047.0 12363.4 12826.3 12893.2 12970.4 12981.2 15483.4 16228.4 16451.8 16550.8 16552.8 8142.5 8679.6 8956.9 9054.0 9151.0 8446.2 8450.1 8484.9 8610.7 8623.4 7886.0 8012.5 8025.0 8068.8 8072.5 2201.9 2209.3 2214.9 2220.8 2393.1 939.7 957.5 990.5 1018.5 1023.9 20261.1 20631.6 20896.7 20979.5 20982.5 1011.1 1021.0 1031.8 1045.2 1050.0 10645.4 10707.8 10778.6 10783.5 10837.0 480471.0 482237.6 488619.0 496127.6 496465.0 97650.0 118196.4 121467.2 122000.0 124827.0 1172.9 1214.3 1229.4 1232.5 1235.0 19119.8 21286.8 21923.0 22336.0 22423.0 55858.7 57045.4 57142.4 57816.4 59020.1 71432.6 71562.0 72171.6 72286.0 72768.0 9069.6 9087.0 9214.8 9217.4 9273.9 13871.0 14429.1 14512.9 14881.0 14896.6 11801.9 11861.6 11995.0 12064.9 12237.9 11199.8 11254.5 11555.5 11649.9 11911.9 9350.9 12216.9 12536.1 12627.6 12635.1 8809.8 8839.4 8949.3 9217.9 9262.0 11880.5 11938.4 12099.5 12110.9 12200.3 11:14:21 INFO - PID 1642 | 11:14:21 INFO - PID 1642 | [#3] mootools.html Cycles:180 Average:7089.88 Median:3395.35 stddev:4490.65 (132.3%) stddev-sans-first:4481.98 11:14:21 INFO - PID 1642 | Values: 1274.8 1396.4 1419.3 1456.7 1496.8 10020.5 11380.8 11523.1 11876.0 11915.6 11984.5 12138.1 12208.4 12209.6 12210.5 2161.4 2538.9 2599.0 2599.1 2604.9 2429.9 2500.9 2559.6 2611.3 2617.5 2225.7 2592.7 2594.1 2616.3 2622.9 2564.2 2570.9 2571.2 2571.9 2574.0 1929.0 2537.5 2564.6 2569.3 2664.5 11629.5 11870.9 11891.0 11894.8 11910.9 6577.1 6807.5 6812.9 6831.3 6842.6 3977.7 4290.4 4320.2 4396.2 4398.7 1120.5 1140.2 1143.6 1241.4 1289.1 16587.5 16640.3 16712.5 16737.2 16758.8 5386.2 5609.9 5630.2 5669.6 5723.8 11551.9 11708.4 11724.6 11725.9 11742.9 6143.1 6491.1 6514.7 6544.8 6560.1 5805.3 6194.9 6209.9 6220.1 6226.9 3844.9 4338.7 4365.6 4397.4 4475.6 1267.8 1295.8 1312.4 1313.5 1313.7 799.9 800.4 825.7 831.3 857.9 5507.7 6517.5 6659.1 6750.3 6879.7 817.1 818.9 942.6 991.4 1099.8 3386.7 3403.9 4316.8 4324.0 4519.4 14418.3 14725.0 15012.5 15076.8 15205.8 14695.2 14857.1 14934.3 14954.8 14976.9 7448.7 7986.3 8110.6 8137.0 8172.0 6369.2 6777.7 7031.3 7176.7 7294.4 12046.6 12305.5 13461.7 13587.5 13803.1 11347.0 13596.7 13946.7 14134.8 14249.2 9461.2 9464.0 9464.2 9597.3 9628.1 9731.1 10594.6 10707.6 10848.1 10866.7 7424.8 8312.8 9123.7 9235.4 9292.6 8997.2 9047.0 9066.5 9095.2 9099.2 8136.5 8365.0 8372.8 8385.8 8411.5 5220.9 5903.9 6118.8 6134.3 6310.4 5412.5 6129.3 6129.9 6247.1 6317.6 11:14:21 INFO - PID 1642 | 11:14:21 INFO - PID 1642 | [#4] prototype.html Cycles:180 Average:5510.74 Median:6154.58 stddev:1717.24 (27.9%) stddev-sans-first:1677.61 11:14:21 INFO - PID 1642 | Values: 338.8 398.3 398.6 407.0 408.5 5157.8 5824.6 6282.6 6315.3 6422.5 4788.6 6325.1 6325.2 6360.2 6360.5 4622.7 4627.8 4634.8 4651.2 4654.4 4010.8 4202.4 4621.6 4651.4 4660.4 3785.8 4586.8 4589.0 4611.8 4612.3 4623.4 4636.5 4641.8 4644.4 4653.9 3237.0 3437.7 3890.8 3900.2 3951.6 6142.7 6236.6 6276.3 6293.9 6295.8 5087.9 5459.1 5518.5 5522.4 5527.6 5965.0 6703.1 6849.3 6922.8 6946.9 4360.0 5008.5 5020.0 5023.6 5040.2 7145.5 7172.8 7219.0 7249.7 7258.0 4340.2 5012.2 5125.4 5126.7 5148.7 4502.9 4656.3 5064.0 5065.5 5221.3 4767.9 5168.5 5275.4 5590.7 5688.1 6776.0 6826.2 6841.0 6844.3 6911.1 1720.6 1772.5 1783.0 1787.2 1821.3 1244.0 1248.2 1256.6 1263.1 1269.2 5344.2 6350.8 6396.0 6422.3 6424.4 5974.8 6217.3 6965.2 7082.4 7082.5 5686.1 6152.8 6365.7 6473.1 6515.7 3335.2 3710.2 3721.8 3730.2 3746.5 6351.0 6950.2 7024.8 7228.1 7308.6 7005.4 7319.6 7333.5 7353.7 7358.9 6089.1 6509.1 7161.9 7195.7 7312.7 5952.4 6516.9 7122.4 7126.7 7231.3 6742.7 6950.5 7020.8 7026.1 7071.0 6700.3 6751.8 7285.3 7332.2 7350.2 3667.3 4127.6 4510.2 4522.4 4540.1 7176.1 7189.4 7191.8 7201.8 7305.2 5593.9 5784.2 5878.8 6833.3 6937.2 5937.2 6161.7 6410.0 6416.6 6913.4 5814.5 6156.4 6510.7 6774.7 6775.9 6682.3 6890.6 6893.5 6894.0 6899.3 6578.9 6613.7 6676.9 6724.8 6751.5 11:14:21 INFO - PID 1642 | 11:14:21 INFO - PID 1642 | [#5] yui.html Cycles:180 Average:21298.61 Median:17592.80 stddev:29669.22 (168.6%) stddev-sans-first:29712.14 11:14:21 INFO - PID 1642 | Values: 702.0 816.5 845.3 852.9 859.3 14558.6 15206.5 16307.6 16737.7 16783.7 16885.2 17592.5 17612.6 17623.5 18341.8 974.4 977.7 991.7 994.2 997.3 1005.3 1009.2 1012.3 1012.7 1016.8 1052.2 1058.5 1062.9 1065.2 1066.2 1053.1 1059.3 1059.6 1060.8 1061.4 663.6 727.4 770.8 774.7 779.4 14778.2 16859.2 17069.5 17074.9 17089.7 16320.6 16371.5 16808.2 16911.7 16992.8 11994.8 14051.5 14342.9 14344.9 14380.0 3465.0 3563.1 3572.4 3597.0 3794.4 104430.5 113921.0 114311.4 116635.4 117152.4 13115.9 13355.1 13444.9 13453.8 13526.3 21040.7 25258.0 25499.6 25822.5 25861.0 6735.2 7676.4 7912.2 7939.7 7949.2 3978.9 4053.9 4060.7 4064.9 4078.3 4175.7 4231.3 4323.5 4342.5 4383.6 848.7 890.5 897.8 898.2 899.0 639.5 646.9 648.0 657.1 680.7 10651.6 11431.1 11896.6 12027.8 12093.1 652.1 653.8 658.1 671.2 678.5 5401.2 5557.9 5603.2 5649.0 5711.0 102679.0 102989.5 104039.0 104159.5 107022.0 92790.6 94444.0 94466.5 94647.0 95795.0 82174.6 82527.0 83538.6 84184.7 84645.0 11099.4 11287.4 11317.2 11377.2 11511.7 39152.0 39232.2 39413.0 39466.4 39728.6 49459.8 49913.5 50123.7 50255.5 50736.7 13682.2 13988.5 14701.5 15602.2 16753.7 18031.2 18513.1 18729.2 18786.4 18815.2 14055.0 15462.5 16200.8 17463.1 17593.1 14122.6 16211.8 16516.6 16963.3 17109.2 16507.4 16560.4 16787.6 16830.7 16918.9 10725.7 10922.7 11515.9 12002.0 12334.5 10834.9 13396.9 13813.9 13916.8 14011.1 11:14:21 INFO - PID 1642 | -------- Summary: end -------- 11:14:21 INFO - PID 1642 | 11:14:21 INFO - TEST-INFO | 1642: exit 0 11:14:21 INFO - TEST-OK | dromaeo_css | took 1113650ms 11:14:21 INFO - TEST-START | kraken 11:14:21 INFO - operating with platform_type : mac_ 11:14:21 INFO - Initialising browser for kraken test... 11:14:21 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpMJY1bv/profile http://localhost:49228/getInfo.html) 11:14:22 INFO - PID 1657 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2941: String.localeCompare is deprecated; use String.prototype.localeCompare instead 11:14:23 INFO - PID 1657 | 2017-05-19 11:14:23.961 plugin-container[1658:10260] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 11:14:23 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:23 INFO - PID 1657 | 2017-05-19 11:14:23.965 plugin-container[1658:10260] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a23, name = 'com.apple.CFPasteboardClient' 11:14:23 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:23 INFO - PID 1657 | 2017-05-19 11:14:23.965 plugin-container[1658:10260] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:14:24 INFO - PID 1657 | __metrics Screen width/height:1600/1200 11:14:24 INFO - PID 1657 | colorDepth:24 11:14:24 INFO - PID 1657 | Browser inner width/height: 1024/691 11:14:24 INFO - PID 1657 | __metrics 11:14:24 INFO - PID 1657 | 11:14:24 INFO - PID 1657 | ###!!! [Child][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 11:14:24 INFO - PID 1657 | 11:14:25 INFO - TEST-INFO | 1657: exit 0 11:14:25 INFO - Browser initialized. 11:14:25 INFO - Running cycle 1/1 for kraken test... 11:14:25 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/T/tmpMJY1bv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:14:27 INFO - PID 1660 | 2017-05-19 11:14:27.027 plugin-container[1661:10400] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 11:14:27 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:27 INFO - PID 1660 | 2017-05-19 11:14:27.028 plugin-container[1661:10400] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 11:14:27 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:27 INFO - PID 1660 | 2017-05-19 11:14:27.028 plugin-container[1661:10400] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:14:27 INFO - PID 1660 | 2017-05-19 11:14:27.763 plugin-container[1662:10447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 11:14:27 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:27 INFO - PID 1660 | 2017-05-19 11:14:27.764 plugin-container[1662:10447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c03, name = 'com.apple.CFPasteboardClient' 11:14:27 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:27 INFO - PID 1660 | 2017-05-19 11:14:27.764 plugin-container[1662:10447] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:14:37 INFO - PID 1660 | RSS: Main: 238997504 11:14:37 INFO - PID 1660 | 11:14:39 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 11:14:40 INFO - PID 1660 | RSS: Main: 251510784 11:14:40 INFO - PID 1660 | 11:14:43 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 11:14:44 INFO - PID 1660 | RSS: Main: 259432448 11:14:44 INFO - PID 1660 | 11:14:47 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 11:14:48 INFO - PID 1660 | RSS: Main: 262402048 11:14:48 INFO - PID 1660 | 11:14:50 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 11:14:50 INFO - PID 1660 | RSS: Main: 263450624 11:14:50 INFO - PID 1660 | 11:14:53 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:14:53 INFO - PID 1660 | RSS: Main: 263815168 11:14:53 INFO - PID 1660 | 11:14:56 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 11:14:57 INFO - PID 1660 | RSS: Main: 269770752 11:14:57 INFO - PID 1660 | 11:15:00 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 11:15:00 INFO - PID 1660 | RSS: Main: 269225984 11:15:00 INFO - PID 1660 | 11:15:03 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 11:15:04 INFO - PID 1660 | RSS: Main: 268230656 11:15:04 INFO - PID 1660 | 11:15:05 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:15:06 INFO - PID 1660 | RSS: Main: 269320192 11:15:06 INFO - PID 1660 | 11:15:08 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:15:08 INFO - PID 1660 | RSS: Main: 269492224 11:15:08 INFO - PID 1660 | 11:15:10 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:15:11 INFO - PID 1660 | RSS: Main: 268955648 11:15:11 INFO - PID 1660 | 11:15:14 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:15:15 INFO - PID 1660 | RSS: Main: 269041664 11:15:15 INFO - PID 1660 | 11:15:18 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:15:18 INFO - PID 1660 | RSS: Main: 269336576 11:15:18 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 11:15:20 INFO - PID 1660 | RSS: Main: 271134720 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | __start_tp_report 11:15:20 INFO - PID 1660 | _x_x_mozilla_page_load 11:15:20 INFO - PID 1660 | _x_x_mozilla_page_load_details 11:15:20 INFO - PID 1660 | |i|pagename|runs| 11:15:20 INFO - PID 1660 | |0;ai-astar;102;115;118;154;172;160;112;112;118;150 11:15:20 INFO - PID 1660 | |1;audio-beat-detection;151;150;115;156;127;149;149;110;139;111 11:15:20 INFO - PID 1660 | |2;audio-dft;201;198;201;213;226;197;198;232;280;197 11:15:20 INFO - PID 1660 | |3;audio-fft;83;61;63;93;78;49;63;85;95;82 11:15:20 INFO - PID 1660 | |4;audio-oscillator;91;70;83;74;95;105;110;86;72;94 11:15:20 INFO - PID 1660 | |5;imaging-gaussian-blur;91;91;91;90;91;89;89;90;91;90 11:15:20 INFO - PID 1660 | |6;imaging-darkroom;97;98;97;97;99;97;97;96;97;97 11:15:20 INFO - PID 1660 | |7;imaging-desaturate;92;94;92;92;90;91;92;93;94;91 11:15:20 INFO - PID 1660 | |8;json-parse-financial;44;54;75;77;78;77;61;52;50;49 11:15:20 INFO - PID 1660 | |9;json-stringify-tinderbox;59;56;71;62;67;63;57;59;58;56 11:15:20 INFO - PID 1660 | |10;stanford-crypto-aes;81;85;81;84;80;84;80;86;83;85 11:15:20 INFO - PID 1660 | |11;stanford-crypto-ccm;135;139;155;136;135;136;140;139;189;132 11:15:20 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;186;185;220;183;161;171;240;231;184;187 11:15:20 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;66;67;65;66;68;71;67;69;69;66 11:15:20 INFO - PID 1660 | __end_tp_report 11:15:20 INFO - PID 1660 | __start_cc_report 11:15:20 INFO - PID 1660 | _x_x_mozilla_cycle_collect,939 11:15:20 INFO - PID 1660 | __end_cc_report 11:15:20 INFO - PID 1660 | __startTimestamp1495217720700__endTimestamp 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | ------- Summary: start ------- 11:15:20 INFO - PID 1660 | Number of tests: 14 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:131.30 Median:134.00 stddev:24.87 (18.6%) stddev-sans-first:24.02 11:15:20 INFO - PID 1660 | Values: 102.0 115.0 118.0 154.0 172.0 160.0 112.0 112.0 118.0 150.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:149.00 stddev:18.23 (12.2%) stddev-sans-first:18.47 11:15:20 INFO - PID 1660 | Values: 151.0 150.0 115.0 156.0 127.0 149.0 149.0 110.0 139.0 111.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:214.30 Median:207.00 stddev:26.36 (12.7%) stddev-sans-first:27.51 11:15:20 INFO - PID 1660 | Values: 201.0 198.0 201.0 213.0 226.0 197.0 198.0 232.0 280.0 197.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:75.20 Median:82.50 stddev:15.30 (18.5%) stddev-sans-first:15.96 11:15:20 INFO - PID 1660 | Values: 83.0 61.0 63.0 93.0 78.0 49.0 63.0 85.0 95.0 82.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:88.00 Median:84.50 stddev:13.63 (16.1%) stddev-sans-first:14.41 11:15:20 INFO - PID 1660 | Values: 91.0 70.0 83.0 74.0 95.0 105.0 110.0 86.0 72.0 94.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:91.00 stddev:0.82 (0.9%) stddev-sans-first:0.83 11:15:20 INFO - PID 1660 | Values: 91.0 91.0 91.0 90.0 91.0 89.0 89.0 90.0 91.0 90.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:97.00 stddev:0.79 (0.8%) stddev-sans-first:0.83 11:15:20 INFO - PID 1660 | Values: 97.0 98.0 97.0 97.0 99.0 97.0 97.0 96.0 97.0 97.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:92.00 stddev:1.29 (1.4%) stddev-sans-first:1.36 11:15:20 INFO - PID 1660 | Values: 92.0 94.0 92.0 92.0 90.0 91.0 92.0 93.0 94.0 91.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:61.70 Median:68.00 stddev:13.65 (20.1%) stddev-sans-first:12.88 11:15:20 INFO - PID 1660 | Values: 44.0 54.0 75.0 77.0 78.0 77.0 61.0 52.0 50.0 49.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:60.50 stddev:4.98 (8.2%) stddev-sans-first:5.24 11:15:20 INFO - PID 1660 | Values: 59.0 56.0 71.0 62.0 67.0 63.0 57.0 59.0 58.0 56.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:82.90 Median:84.00 stddev:2.23 (2.7%) stddev-sans-first:2.26 11:15:20 INFO - PID 1660 | Values: 81.0 85.0 81.0 84.0 80.0 84.0 80.0 86.0 83.0 85.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:143.60 Median:139.00 stddev:17.14 (12.3%) stddev-sans-first:17.90 11:15:20 INFO - PID 1660 | Values: 135.0 139.0 155.0 136.0 135.0 136.0 140.0 139.0 189.0 132.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.80 Median:186.50 stddev:26.22 (14.1%) stddev-sans-first:27.62 11:15:20 INFO - PID 1660 | Values: 186.0 185.0 220.0 183.0 161.0 171.0 240.0 231.0 184.0 187.0 11:15:20 INFO - PID 1660 | 11:15:20 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.84 (2.7%) stddev-sans-first:1.88 11:15:20 INFO - PID 1660 | Values: 66.0 67.0 65.0 66.0 68.0 71.0 67.0 69.0 69.0 66.0 11:15:20 INFO - PID 1660 | -------- Summary: end -------- 11:15:20 INFO - PID 1660 | 11:15:21 INFO - TEST-INFO | 1660: exit 0 11:15:21 INFO - TEST-OK | kraken | took 59838ms 11:15:21 INFO - SUITE-END | took 1173s 11:15:21 INFO - Completed test suite (00:19:33) 11:15:21 INFO - javascript benchmark 11:15:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7935.903402060276, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3462.792232466052, 3585.748997570168, 3660.524131862861, 3665.2669466106768, 3668.413053911375, 5253.60597955153, 5610.326760788701, 5881.647101173932, 6176.135341052256, 6305.653189074599, 5118.974405127979, 6224.253089629239, 6224.626522408658, 6243.875122497548, 6341.7146228419715, 4996.400431948171, 5484.725763711818, 5491.670499770006, 5505.476979686923, 5511.311086114246, 4591.931121033187, 4986.4265609455015, 5584.4136365681625, 5600.327960644729, 5637.859053523674, 5411.621186516945, 5434.076207044812, 5545.334559852803, 5589.832305030855, 5632.408597097307, 5529.087700529408, 5572.331320241557, 5573.247611572427, 5604.019296623115, 5615.9157612635845, 5536.778528858841, 5540.141278101901, 5540.168974653794, 5557.472040156219, 5559.305086864142, 6114.694265286718, 6319.115323854664, 6319.304876463631, 6339.080833279195, 6346.587471814318, 6533.93466065338, 6588.538802283842, 6699.162604674416, 6776.42400395967, 6778.694958726869, 6698.56459330142, 6702.597844129319, 6702.994550817368, 6710.3289671033, 6730.9326906731285, 6122.602030867979, 6288.496920246369, 6358.1734374531015, 6388.105665206875, 6390.0734393512685, 6776.864462710718, 6831.487638427137, 6842.55523390978, 6877.965610171917, 6911.894096944465, 6667.6666166691475, 6678.065070890079, 6693.866122677519, 6706.96646516774, 6740.561863478858, 6587.176602924635, 6786.4910131739325, 6872.69072891721, 6887.345702158287, 6903.654817259217, 6579.144711187515, 6594.86810263792, 6667.433268172163, 6781.287964763727, 6783.796486105425, 6775.457963362919, 6781.220159681601, 6788.355106264996, 6789.93210067893, 6809.216940051859, 5280.392754833167, 5349.411564727877, 5372.301600791872, 5422.810201642924, 5456.7544460499375, 4442.756028312077, 4445.933311000337, 4785.832495862698, 4840.878978025577, 4916.336294600254, 6364.522660800459, 6364.840878978062, 6368.235811702625, 6371.681415929185, 6373.107764912916, 6825.249222585511, 6876.106106206161, 6895.931040689629, 6907.274736152732, 6937.445004399636, 5721.485066343966, 5975.19334889793, 6310.842228944313, 6347.682615869188, 6366.936330636634, 5005.89988200234, 5987.101934709828, 6053.7578496860515, 6084.148219249309, 6106.847328816726, 6952.061471701357, 7026.086608740831, 7036.50744447894, 7041.225465198721, 7069.222385537587, 6607.108040414482, 6718.630475323904, 6886.655667216719, 6896.344847239705, 6909.0, 6955.513114082168, 7016.158061032709, 7022.859542809218, 7055.506114571931, 7057.717691292496, 6737.494687898526, 6790.185177778698, 6795.864082718417, 6803.149606299213, 6809.863802724017, 6444.163413427151, 6651.567648102858, 6868.965655171693, 6930.445564354739, 6939.791806245821, 6431.099646049463, 6556.0493728410065, 6619.205695316593, 6649.135612370361, 6671.966640166768, 6686.732530698718, 6691.1636045494315, 6691.197056353269, 6694.263631000684, 6698.899516507354, 5578.693171875586, 6262.624242545462, 6345.270293916259, 6528.575642583217, 6530.216374035146, 6669.666516674244, 6700.162479690039, 6701.932980670131, 6702.229243637044, 6740.0, 5288.603354748332, 6306.905396418965, 6360.173177486897, 6461.0954466373805, 6521.152250207443], "value": 6151.989694546132, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [794.3406972213064, 814.4502460838933, 840.7771940435786, 1226.748516554106, 1289.0718682548572, 26474.867625661893, 37330.06674833127, 37478.62521374785, 37651.81174094129, 37683.62316376835, 37890.4316435253, 38261.852144435645, 38280.617193828126, 38333.23333533328, 38335.61664383356, 7897.368210543157, 8060.355171586259, 8271.17288271174, 8396.916030839675, 8434.957825210866, 8390.748277551684, 8441.198086181803, 8466.74599762005, 8486.66053357865, 8497.320214382866, 8408.873866892001, 8416.410851240416, 8471.983361996568, 8491.830163396728, 8515.95742021289, 8346.8330633387, 8399.454035487704, 8443.915560844374, 8451.408401411903, 8462.19609137128, 7821.374290056839, 7918.0, 8243.711470098518, 8254.05078415978, 8304.584770761498, 36680.082997925, 36834.447483287775, 36900.07749806249, 37012.62987370119, 37219.69731059427, 3671.0, 3702.500162478085, 3716.591174970751, 3718.3492888744363, 3719.9070023249365, 29986.85006574975, 30161.849190754125, 30181.396372072435, 30274.394512109855, 30428.0, 3029.6364436267568, 3054.1906394805396, 3074.0, 3075.9846200769075, 3086.922826929345, 239693.0, 305152.0, 305597.0, 306543.0, 309495.0, 47468.0, 47571.810704731965, 47649.52350476451, 47760.52239477561, 47866.760666196446, 48868.77828054327, 48986.75506622444, 54374.0, 54655.453445465835, 55241.723791380784, 12389.690257743629, 13984.230867302193, 14287.357068931922, 14395.064320819114, 14444.422223111169, 9385.436873787457, 10969.945150274198, 11475.598354057567, 11537.192396532162, 11579.305241685526, 13817.654558636115, 13832.792508112387, 13872.583822485341, 13880.375383107785, 14146.0, 5141.3830340358645, 6241.438270555672, 6242.500599951993, 6252.593581417284, 6285.0, 849.0109022988149, 1028.861103750999, 1059.3432072115338, 1064.2869277584036, 1086.7011571817814, 21593.67609485859, 23517.17689880846, 23679.644805327935, 23959.281221563382, 24686.01255949778, 935.7520257131865, 1002.588938535197, 1139.168407062832, 1169.789437901186, 1173.8356630071796, 12275.877241227652, 12613.87386126127, 12637.241765493916, 12687.619371418872, 12690.0, 263822.0, 284073.0, 284805.5759721188, 299646.0, 300888.0, 53909.730451347496, 53968.190477142874, 54361.0, 54471.008189099164, 54634.45365546373, 1547.048565132458, 1551.6276093737422, 1571.198688668787, 1586.7302558565073, 1596.481143628302, 24315.63526547068, 24466.0, 24488.265352039467, 24507.0, 24924.875375623007, 38212.47150113964, 50295.0, 51109.977800444525, 51179.232311514614, 51340.486595133574, 205822.0, 252468.4753152445, 253569.0, 254244.0, 254552.45447545286, 35793.28413431769, 35960.0, 35981.82009090042, 35989.0, 36191.638083618825, 43742.78128609336, 44024.27684401008, 44058.55941440545, 45173.322400163364, 45500.31749523826, 29872.402551949275, 30626.081217563507, 31166.0, 31351.84324078365, 31683.683163168072, 28033.159005229874, 28327.57508637331, 30716.385672286877, 31317.686823131477, 31505.52741708922, 37783.2443351137, 37933.81033094817, 37986.62013379941, 38007.2398552033, 38223.617763822, 21790.673139902596, 27809.0, 29412.55881161827, 29845.70154298516, 30536.389272213986, 24368.147114850894, 24674.383140421633, 24695.62956555689, 24748.30297067136, 24979.87590558393], "value": 17512.934114145173, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.3519645379804, 409.5945014435708, 416.190509459102, 424.64329962831215, 439.9406080179176, 20614.48463788406, 21243.468913277175, 21504.784952150494, 22072.668909966364, 22267.997940092693, 23004.194853180143, 23090.30729078126, 23213.0, 23644.290671279847, 23710.288691339287, 6011.218541589587, 6130.264368275791, 6146.416090471398, 6199.070139479091, 6204.224471941007, 5955.583109182359, 6156.138140660311, 6160.845978850542, 6221.253449586056, 6233.0, 5949.464548190662, 6116.0825876118715, 6174.629522228681, 6187.752489900398, 6203.627782333052, 6051.394860513958, 6120.081987701836, 6155.353687862755, 6168.660723660197, 6180.443760061571, 4919.94221242431, 4933.580645645125, 5279.0, 5371.543418809406, 5476.890462190774, 26350.209493715218, 27463.86268068647, 27792.02727904534, 27931.301717457023, 28053.438931221262, 2163.8485306028583, 2222.0889435331596, 2233.7319521657505, 2235.407616981501, 2241.1595651630637, 14816.925915370353, 17595.0, 17735.556611084827, 17790.46628601144, 17955.551111222194, 2072.585482903425, 2095.109578429177, 2131.1475409836034, 2134.797194266542, 2137.9893100534396, 146858.0, 148401.0, 149331.50668493286, 152481.9999999989, 154047.0, 12363.381830908598, 12826.294553799631, 12893.161944473766, 12970.416331265116, 12981.221126732426, 15483.380664773507, 16228.350865965229, 16451.83548164527, 16550.83449165517, 16552.84130110905, 8142.47073940192, 8679.609417576225, 8956.8656470153, 9054.049324820931, 9151.039140890243, 8446.155384461505, 8450.070492245848, 8484.915150848536, 8610.741677749678, 8623.396362254707, 7886.0, 8012.479188852706, 8025.036995560454, 8068.757937261891, 8072.515649060958, 2201.878896660699, 2209.3482422685347, 2214.933551993443, 2220.8445408821226, 2393.1419814817536, 939.6758118449125, 957.5260246178186, 990.5047476261869, 1018.5365658625308, 1023.8617786598862, 20261.088251028737, 20631.58736825285, 20896.686549701768, 20979.47551311199, 20982.475438113863, 1011.145581983223, 1021.0, 1031.83490641497, 1045.2265323660436, 1049.994750026245, 10645.40318952148, 10707.839382409271, 10778.568857245622, 10783.46082695862, 10837.0, 480471.0, 482237.5888120607, 488619.0, 496127.63553276187, 496465.0, 97650.0, 118196.40901795436, 121467.17799233018, 122000.0, 124827.0, 1172.9120315976165, 1214.2714371377103, 1229.360732419137, 1232.4515590562294, 1235.042841797596, 19119.75721578093, 21286.78713212848, 21923.0, 22336.0, 22423.0, 55858.720706396205, 57045.42954570401, 57142.42857571371, 57816.421835781104, 59020.14697795365, 71432.64283678548, 71562.0, 72171.63914180396, 72286.0, 72768.0, 9069.591868366071, 9087.0, 9214.769630759285, 9217.400868943498, 9273.907260927304, 13871.0, 14429.134251944917, 14512.927435362755, 14881.032732872329, 14896.553103406915, 11801.881981180079, 11861.644150675493, 11995.0, 12064.879351206375, 12237.938810305892, 11199.824018477942, 11254.493547790205, 11555.4800033997, 11649.941750291195, 11911.940440297743, 9350.906490935276, 12216.938915305367, 12536.059795515555, 12627.558035468715, 12635.05237107239, 8809.779755506164, 8839.425437346552, 8949.284057275532, 9217.86173207389, 9261.953690231505, 11880.524779008743, 11938.403079845799, 12099.516019359127, 12110.939445302716, 12200.328981905725], "value": 10446.487732627334, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1274.7514234724233, 1396.371632765256, 1419.2548911821293, 1456.6649670575766, 1496.7605183170695, 10020.498975051254, 11380.772384552303, 11523.13576481763, 11875.99054080403, 11915.582954596592, 11984.460699268531, 12138.089643276768, 12208.389580520961, 12209.633710988684, 12210.450529726162, 2161.416417567256, 2538.8984440622353, 2598.9610155847677, 2599.090318388568, 2604.9088281910135, 2429.866357350345, 2500.949981000388, 2559.5904655255163, 2611.308003379106, 2617.4634199988955, 2225.688403623494, 2592.7277635848245, 2594.0661361909697, 2616.3459135216194, 2622.8557429341377, 2564.230730780758, 2570.935726606831, 2571.2414837622946, 2571.897124115033, 2573.9742602574015, 1928.9614207715767, 2537.479816637585, 2564.6153077038407, 2569.306287302418, 2664.547027005414, 11629.534818607246, 11870.940645296805, 11890.989265912407, 11894.762104757943, 11910.928016478472, 6577.079208910708, 6807.4894382921475, 6812.897806532906, 6831.316868313179, 6842.589444633338, 3977.7016723745546, 4290.377895205209, 4320.17916595849, 4396.20868243716, 4398.692091553593, 1120.4621781544904, 1140.201858698914, 1143.5654451308533, 1241.4351470081092, 1289.071868254855, 16587.502374928772, 16640.251188696537, 16712.49862504127, 16737.163141842982, 16758.83241167585, 5386.245925570423, 5609.915851262234, 5630.239917611152, 5669.6314739541795, 5723.828285151452, 11551.88448115508, 11708.414579271002, 11724.648260552198, 11725.882741172649, 11742.941285293518, 6143.109249158847, 6491.123698300763, 6514.739410423625, 6544.836379090466, 6560.114384558024, 5805.332386775491, 6194.938050619436, 6209.9068513972325, 6220.098085777537, 6226.906596401058, 3844.9231015380096, 4338.7200775771225, 4365.563443655609, 4397.362382454527, 4475.5748203920575, 1267.8034904589804, 1295.7861952777687, 1312.3831799054428, 1313.481174935895, 1313.678148853537, 799.8800179972934, 800.3597122302135, 825.7481468152271, 831.3141658132065, 857.8670306102564, 5507.697076660822, 6517.478601711851, 6659.067730517731, 6750.257471678112, 6879.6560171991205, 817.0930267403178, 818.9073294687506, 942.5617088054088, 991.4447909170743, 1099.8460215569826, 3386.745994050456, 3403.9489407658907, 4316.827326906951, 4324.0, 4519.412476378116, 14418.279086045655, 14725.0, 15012.47456339023, 15076.773848392282, 15205.771913421308, 14695.191764452844, 14857.108573485626, 14934.25328733559, 14954.775678364833, 14976.925115374572, 7448.664810083559, 7986.281234688906, 8110.594470276581, 8137.0, 8171.957010747313, 6369.20384951881, 6777.695003724943, 7031.296870312928, 7176.677049532888, 7294.416446684147, 12046.578369757017, 12305.507779688713, 13461.730765384833, 13587.52443664467, 13803.10279831848, 11347.0, 13596.728065438438, 13946.721065578835, 14134.78797818054, 14249.216293103564, 9461.195798357217, 9463.952680236554, 9464.242860571276, 9597.328187026842, 9628.133467987915, 9731.12419882214, 10594.576216951236, 10707.571697132027, 10848.132149428184, 10866.728331791452, 7424.777256682308, 8312.750617481484, 9123.673463266825, 9235.399699019543, 9292.58183381763, 8997.190252877272, 9047.0, 9066.456012639263, 9095.181433671001, 9099.1810737034, 8136.471129376571, 8365.0, 8372.832543348977, 8385.83228335442, 8411.495310281403, 5220.9477905220465, 5903.881922361443, 6118.785842495491, 6134.294556125924, 6310.400511951358, 5412.512873841216, 6129.325774164749, 6129.90312847394, 6247.12540244357, 6317.5893566918], "value": 5373.900493110059, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.8034939734954, 398.31688653958463, 398.6013986013986, 406.99186016279657, 408.4547129582008, 5157.819476318335, 5824.592613321207, 6282.623042617446, 6315.305316415191, 6422.486201103912, 4788.592969597588, 6325.051242313655, 6325.209348831396, 6360.236771587404, 6360.459360954324, 4622.6533010024295, 4627.791749371269, 4634.844797169316, 4651.209294419957, 4654.441467023945, 4010.7994600270017, 4202.390653355262, 4621.58405743483, 4651.395318608576, 4660.4174478190225, 3785.848566057354, 4586.8394606188795, 4589.0, 4611.769411529427, 4612.308153776927, 4623.398958135421, 4636.516314779272, 4641.767911604406, 4644.373009643706, 4653.86038418848, 3236.951445728316, 3437.731097475654, 3890.7860067696265, 3900.2394533066063, 3951.624595663436, 6142.710030893518, 6236.6258024518675, 6276.278228003746, 6293.937060629427, 6295.779647712354, 5087.872803179913, 5459.071957767189, 5518.475744804237, 5522.447755224485, 5527.640703354269, 5965.0, 6703.061571380011, 6849.349311815369, 6922.757703480404, 6946.895796563055, 4360.0, 5008.474110218447, 5020.0, 5023.598112151019, 5040.193569028975, 7145.53231751787, 7172.820679483002, 7219.0, 7249.673764680602, 7257.983882256488, 4340.2187606230555, 5012.24816277554, 5125.384953805567, 5126.743947732829, 5148.716820574904, 4502.909941801146, 4656.301554766745, 5063.974680126649, 5065.493450654979, 5221.321228240305, 4767.879548306145, 5168.457311982263, 5275.391005743209, 5590.720463976785, 5688.132070192309, 6775.966120169466, 6826.180858297036, 6840.965795170992, 6844.315568443216, 6911.136107986502, 1720.6386658801791, 1772.53027947594, 1783.0, 1787.1511032259832, 1821.298799962024, 1243.9502419903104, 1248.2448118888124, 1256.616731896762, 1263.0968857267098, 1269.1686945051006, 5344.198370244494, 6350.793349263625, 6396.0405939109505, 6422.261439934374, 6424.38968298011, 5974.790882319098, 6217.253929528486, 6965.233824279325, 7082.39799617028, 7082.5396349237135, 5686.061799803069, 6152.78465253714, 6365.7268546290925, 6473.122794389575, 6515.73937042513, 3335.232896433834, 3710.18375957289, 3721.813909304524, 3730.235301763132, 3746.4754934309217, 6351.0, 6950.165980082322, 7024.754133605299, 7228.1326240850385, 7308.6345682715655, 7005.369516743517, 7319.634018299063, 7333.4866559340335, 7353.669084891193, 7358.8528229435115, 6089.117078023751, 6509.127069393703, 7161.928380716126, 7195.6761945713615, 7312.670929808065, 5952.422608008795, 6516.902246466401, 7122.394596459359, 7126.714931402686, 7231.2768723127265, 6742.73029078851, 6950.54821436605, 7020.7893763187185, 7026.086608740831, 7071.010058591904, 6700.296468870698, 6751.771915268048, 7285.344319011315, 7332.156801967629, 7350.228226036189, 3667.266546690726, 4127.628513433805, 4510.1656193604285, 4522.389477420637, 4540.137373898949, 7176.102987126609, 7189.424846012202, 7191.7842464726145, 7201.783946481614, 7305.2329505403295, 5593.9440605595055, 5784.165758778253, 5878.767287552955, 6833.3166683331265, 6937.202221744564, 5937.168796368425, 6161.72272247741, 6409.974404095322, 6416.620426608115, 6913.41235994926, 5814.476697097281, 6156.384361563987, 6510.674466276572, 6774.746671865721, 6775.86448271022, 6682.298358672464, 6890.552114112566, 6893.482988775962, 6893.965530172317, 6899.275576064441, 6578.901316480353, 6613.702383392662, 6676.9332306676315, 6724.831879203059, 6751.45988320943], "value": 4989.203156159124, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [701.9929800701992, 816.534575292083, 845.3068483843251, 852.869947319801, 859.3468963587671, 14558.563243102697, 15206.543803685907, 16307.59231019225, 16737.665246695058, 16783.74824377634, 16885.24016419261, 17592.472225833244, 17612.647747045085, 17623.471295861113, 18341.816581834177, 974.3569244298765, 977.7018009507098, 991.7124034030159, 994.1847684898386, 997.2919227348555, 1005.3214080495673, 1009.1573536097368, 1012.3217444312291, 1012.7063151686002, 1016.8474728790667, 1052.1582733812918, 1058.544825724938, 1062.8618279623677, 1065.169168048923, 1066.2323127348297, 1053.1311667873997, 1059.3008614314554, 1059.6397224943555, 1060.8249638809586, 1061.3631820907433, 663.6262935723055, 727.4035291061332, 770.838123993962, 774.7327172125664, 779.3570304498811, 14778.187199704013, 16859.24133413999, 17069.48791536256, 17074.914625426918, 17089.658206835917, 16320.591985200346, 16371.508854734377, 16808.15959202035, 16911.66176676472, 16992.830071699373, 11994.820077698841, 14051.50819721315, 14342.856571434257, 14344.928275358661, 14380.0, 3465.0, 3563.127033876691, 3572.4462708280257, 3597.0467826026124, 3794.3739283018267, 104430.47784761028, 113921.0, 114311.42844285726, 116635.41682291534, 117152.41423792827, 13115.934420327838, 13355.131916425402, 13444.865551344557, 13453.798193027113, 13526.32368381577, 21040.684389734168, 25258.0, 25499.617505737428, 25822.48355032889, 25861.0, 6735.191776986793, 7676.424268179909, 7912.248336408032, 7939.682412703427, 7949.205079492121, 3978.900527486836, 4053.9391909121387, 4060.6954478414236, 4064.918701625951, 4078.286299897506, 4175.749455032707, 4231.259529582311, 4323.459567554056, 4342.457192850894, 4383.62739167168, 848.7411339541376, 890.5191196753681, 897.8249241397865, 898.2185498616245, 899.0290486274938, 639.5139693832721, 646.8867948109062, 647.9643619600873, 657.1489920552896, 680.7243066557953, 10651.615290012249, 11431.14266430021, 11896.643100706993, 12027.819582706266, 12093.093018023683, 652.1001018594404, 653.7646447278976, 658.1016911915201, 671.214678825775, 678.4550722475028, 5401.162819762943, 5557.944420555743, 5603.187537806995, 5649.0, 5710.971445142831, 102679.0, 102989.48505257575, 104039.0, 104159.4792026035, 107022.0, 92790.60814087794, 94444.0, 94466.52766736122, 94647.0, 95795.0, 82174.58912705518, 82527.0, 83538.5823070893, 84184.73722894039, 84645.0, 11099.389533575732, 11287.43562821872, 11317.207795454238, 11377.203595748219, 11511.712207194887, 39152.0, 39232.215355692155, 39413.0, 39466.40800388054, 39728.60271397249, 49459.76350591264, 49913.500864990885, 50123.74690632764, 50255.49744502508, 50736.74631626818, 13682.247476388895, 13988.510402135877, 14701.485448009269, 15602.219889005732, 16753.74869376985, 18031.188596512926, 18513.0743462829, 18729.157187926303, 18786.436406907815, 18815.247390104243, 14055.0, 15462.458813941457, 16200.837991619934, 17463.12684365802, 17593.120343983006, 14122.576322710334, 16211.756823647418, 16516.587085322484, 16963.321467141177, 17109.23008464647, 16507.422240221535, 16560.420385286456, 16787.580310492336, 16830.66338673195, 16918.915405422893, 10725.678229653124, 10922.672319830417, 11515.905988931037, 12001.979831714401, 12334.50661973511, 10834.945825270823, 13396.93301533486, 13813.930930345285, 13916.791248131489, 14011.08927919682], "value": 8277.204366542865, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 118.0, 154.0, 172.0, 160.0, 112.0, 112.0, 118.0, 150.0], "value": 131.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 115.0, 156.0, 127.0, 149.0, 149.0, 110.0, 139.0, 111.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 198.0, 201.0, 213.0, 226.0, 197.0, 198.0, 232.0, 280.0, 197.0], "value": 214.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 63.0, 93.0, 78.0, 49.0, 63.0, 85.0, 95.0, 82.0], "value": 75.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 83.0, 74.0, 95.0, 105.0, 110.0, 86.0, 72.0, 94.0], "value": 88.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 90.0, 91.0, 89.0, 89.0, 90.0, 91.0, 90.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 97.0, 99.0, 97.0, 97.0, 96.0, 97.0, 97.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 92.0, 90.0, 91.0, 92.0, 93.0, 94.0, 91.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [44.0, 54.0, 75.0, 77.0, 78.0, 77.0, 61.0, 52.0, 50.0, 49.0], "value": 61.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 56.0, 71.0, 62.0, 67.0, 63.0, 57.0, 59.0, 58.0, 56.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 81.0, 84.0, 80.0, 84.0, 80.0, 86.0, 83.0, 85.0], "value": 82.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 139.0, 155.0, 136.0, 135.0, 136.0, 140.0, 139.0, 189.0, 132.0], "value": 143.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 220.0, 183.0, 161.0, 171.0, 240.0, 231.0, 184.0, 187.0], "value": 194.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 66.0, 68.0, 71.0, 67.0, 69.0, 69.0, 66.0], "value": 67.4, "unit": "score"}]}]} 11:15:21 INFO - Return code: 0 11:15:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:15:22 INFO - # TBPL SUCCESS # 11:15:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:15:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:15:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:15:22 INFO - 2017-05-19 11:15:22.025 screenresolution[1667:10836] starting screenresolution argv=screenresolution get 11:15:22 INFO - 2017-05-19 11:15:22.027 screenresolution[1667:10836] Display 0: 1600x1200x32@60 11:15:22 INFO - 2017-05-19 11:15:22.033 screenresolution[1668:10839] starting screenresolution argv=screenresolution list 11:15:22 INFO - Available Modes on Display 0 11:15:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:15:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:15:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:15:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:15:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:15:22 INFO - Intel Iris: 11:15:22 INFO - Chipset Model: Intel Iris 11:15:22 INFO - Type: GPU 11:15:22 INFO - Bus: Built-In 11:15:22 INFO - VRAM (Dynamic, Max): 1536 MB 11:15:22 INFO - Vendor: Intel (0x8086) 11:15:22 INFO - Device ID: 0x0a2e 11:15:22 INFO - Revision ID: 0x0009 11:15:22 INFO - Displays: 11:15:22 INFO - Display: 11:15:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:15:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:15:22 INFO - Display Serial Number: 1600x1200 60 11:15:22 INFO - Main Display: Yes 11:15:22 INFO - Mirror: Off 11:15:22 INFO - Online: Yes 11:15:22 INFO - Rotation: Supported 11:15:22 INFO - Return code: 0 11:15:22 INFO - Running post-action listener: _resource_record_post_action 11:15:22 INFO - [mozharness: 2017-05-19 18:15:22.267987Z] Finished run-tests step (success) 11:15:22 INFO - Running post-run listener: _resource_record_post_run 11:15:22 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 39701504; Write bytes: 575944704; Read time: 250; Write time: 1745 11:15:22 INFO - TinderboxPrint: CPU usage
54.1% 11:15:22 INFO - TinderboxPrint: I/O read bytes / time
39,701,504 / 250 11:15:22 INFO - TinderboxPrint: I/O write bytes / time
575,944,704 / 1,745 11:15:22 INFO - TinderboxPrint: CPU idle
2,197.4 (45.9%) 11:15:22 INFO - TinderboxPrint: CPU system
960.7 (20.1%) 11:15:22 INFO - TinderboxPrint: CPU user
1,631.6 (34.1%) 11:15:22 INFO - TinderboxPrint: Swap in / out
299,102,208 / 0 11:15:22 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 236900864; Write bytes: 232281600; Read time: 17275; Write time: 487 11:15:22 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 38755328; Write bytes: 313348608; Read time: 239; Write time: 1190 11:15:22 INFO - Running post-run listener: _upload_blobber_files 11:15:22 INFO - Blob upload gear active. 11:15:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:15:22 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 11:15:22 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'] 11:15:22 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 11:15:23 INFO - (blobuploader) - INFO - Open directory for files ... 11:15:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:15:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:15:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:24 INFO - (blobuploader) - INFO - Done attempting. 11:15:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:15:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:15:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:25 INFO - (blobuploader) - INFO - Done attempting. 11:15:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:15:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:15:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:26 INFO - (blobuploader) - INFO - Done attempting. 11:15:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:15:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:15:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:27 INFO - (blobuploader) - INFO - Done attempting. 11:15:27 INFO - (blobuploader) - INFO - Iteration through files over. 11:15:27 INFO - Return code: 0 11:15:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:15:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:15:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0d76b9ab9c57062172d3b614cf3abb7f4d92b4e12f947abfba7e8892483548d8f4330a0e64acc2c98ad07fe329ad91d1d9e99910ac3da9f87947c0df67ebb59", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7044a8ac30dd81090106e0713ecc9595d83a423258d094c387d42dc3ae8c0f325ba9b6ba6330eb65c08e7388a40c6fcad7b035ff0f576976b1f17576b8c2790", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/979e53149a183bf6c37f6d3a2d011bc7b36608a89eb0a2f4be9e5a388b871d2cb4ab2e2512890d4e3e0366b69fbdd66918cb2378e5da83cc3ea6ab91eb246bc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/762712d5cb892969692a2a1a6a7fcf94d8e30be2e537bb5644347961c4466c8e3abfed674958adde056fd557a7aec1e7551e72deceeb68db000479a5ff770cef"} 11:15:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:15:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:15:27 INFO - Contents: 11:15:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0d76b9ab9c57062172d3b614cf3abb7f4d92b4e12f947abfba7e8892483548d8f4330a0e64acc2c98ad07fe329ad91d1d9e99910ac3da9f87947c0df67ebb59", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7044a8ac30dd81090106e0713ecc9595d83a423258d094c387d42dc3ae8c0f325ba9b6ba6330eb65c08e7388a40c6fcad7b035ff0f576976b1f17576b8c2790", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/979e53149a183bf6c37f6d3a2d011bc7b36608a89eb0a2f4be9e5a388b871d2cb4ab2e2512890d4e3e0366b69fbdd66918cb2378e5da83cc3ea6ab91eb246bc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/762712d5cb892969692a2a1a6a7fcf94d8e30be2e537bb5644347961c4466c8e3abfed674958adde056fd557a7aec1e7551e72deceeb68db000479a5ff770cef"} 11:15:27 INFO - Running post-run listener: copy_logs_to_upload_dir 11:15:27 INFO - Copying logs to upload dir... 11:15:27 INFO - mkdir: /builds/slave/test/build/upload/logs 11:15:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.118534 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-19 11:15:27.957120) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 11:15:27.960796) ========= 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.6QmAqkB1AM/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.yNQeh3JErO/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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/b0d76b9ab9c57062172d3b614cf3abb7f4d92b4e12f947abfba7e8892483548d8f4330a0e64acc2c98ad07fe329ad91d1d9e99910ac3da9f87947c0df67ebb59", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7044a8ac30dd81090106e0713ecc9595d83a423258d094c387d42dc3ae8c0f325ba9b6ba6330eb65c08e7388a40c6fcad7b035ff0f576976b1f17576b8c2790", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/979e53149a183bf6c37f6d3a2d011bc7b36608a89eb0a2f4be9e5a388b871d2cb4ab2e2512890d4e3e0366b69fbdd66918cb2378e5da83cc3ea6ab91eb246bc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/762712d5cb892969692a2a1a6a7fcf94d8e30be2e537bb5644347961c4466c8e3abfed674958adde056fd557a7aec1e7551e72deceeb68db000479a5ff770cef"} build_url:https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011021 build_url: 'https://queue.taskcluster.net/v1/task/NEOpwZLqSnezgn5YpBQidw/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/b0d76b9ab9c57062172d3b614cf3abb7f4d92b4e12f947abfba7e8892483548d8f4330a0e64acc2c98ad07fe329ad91d1d9e99910ac3da9f87947c0df67ebb59", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7044a8ac30dd81090106e0713ecc9595d83a423258d094c387d42dc3ae8c0f325ba9b6ba6330eb65c08e7388a40c6fcad7b035ff0f576976b1f17576b8c2790", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/979e53149a183bf6c37f6d3a2d011bc7b36608a89eb0a2f4be9e5a388b871d2cb4ab2e2512890d4e3e0366b69fbdd66918cb2378e5da83cc3ea6ab91eb246bc7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/762712d5cb892969692a2a1a6a7fcf94d8e30be2e537bb5644347961c4466c8e3abfed674958adde056fd557a7aec1e7551e72deceeb68db000479a5ff770cef"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 11:15:27.993943) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 11:15:27.994293) ========= 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.6QmAqkB1AM/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.yNQeh3JErO/Listeners TMPDIR=/var/folders/w1/v8762b5s4415f2mkcqd4qsn000000w/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.004804 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 11:15:28.043474) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 11:15:28.043890) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 11:15:28.044233) ========= ========= Total master_lag: 0.17 =========