builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0380 starttime: 1495678903.03 results: success (0) revision: d10f5ccd882b965fcad39914f7c3c930d1301a41 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:43.028241) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:43.028862) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:43.029346) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:43.056330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:43.056750) ========= 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.afgFfLpwV6/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.NslJiPfwua/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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-24 19:21:43-- 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% 54.0M=0s 2017-05-24 19:21:43 (54.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.141865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:43.223287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:43.223713) ========= 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.afgFfLpwV6/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.NslJiPfwua/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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.026810 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:43.273074) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 19:21:43.273431) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d10f5ccd882b965fcad39914f7c3c930d1301a41 --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 d10f5ccd882b965fcad39914f7c3c930d1301a41 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.afgFfLpwV6/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.NslJiPfwua/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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-24 19:21:43,372 truncating revision to first 12 chars 2017-05-24 19:21:43,372 Setting DEBUG logging. 2017-05-24 19:21:43,372 attempt 1/10 2017-05-24 19:21:43,373 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d10f5ccd882b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 19:21:44,362 unpacking tar archive at: mozilla-central-d10f5ccd882b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.279363 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 19:21:44.574343) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:44.574884) ========= script_repo_revision: d10f5ccd882b965fcad39914f7c3c930d1301a41 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:44.575512) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:44.575974) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 19:21:44.597447) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-24 19:21:44.598076) ========= /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.afgFfLpwV6/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.NslJiPfwua/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:21:44 INFO - MultiFileLogger online at 20170524 19:21:44 in /builds/slave/test 19:21:44 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 19:21:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:21:44 INFO - {'append_to_log': False, 19:21:44 INFO - 'base_work_dir': '/builds/slave/test', 19:21:44 INFO - 'blob_upload_branch': 'Firefox', 19:21:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:21:44 INFO - 'branch': 'Firefox', 19:21:44 INFO - 'buildbot_json_path': 'buildprops.json', 19:21:44 INFO - 'config_files': ('talos/mac_config.py',), 19:21:44 INFO - 'default_actions': ('clobber', 19:21:44 INFO - 'read-buildbot-config', 19:21:44 INFO - 'download-and-extract', 19:21:44 INFO - 'populate-webroot', 19:21:44 INFO - 'create-virtualenv', 19:21:44 INFO - 'install', 19:21:44 INFO - 'run-tests'), 19:21:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:21:44 INFO - 'download_minidump_stackwalk': True, 19:21:44 INFO - 'download_symbols': 'ondemand', 19:21:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:21:44 INFO - 'tooltool.py': '/tools/tooltool.py', 19:21:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:21:44 INFO - '/tools/misc-python/virtualenv.py')}, 19:21:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:21:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:21:44 INFO - 'gecko_profile': False, 19:21:44 INFO - 'gecko_profile_interval': 0, 19:21:44 INFO - 'installer_path': 'installer.exe', 19:21:44 INFO - 'log_level': 'info', 19:21:44 INFO - 'log_name': 'talos', 19:21:44 INFO - 'log_to_console': True, 19:21:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:21:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:21:44 INFO - 'opt_config_files': (), 19:21:44 INFO - 'pip_index': False, 19:21:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:21:44 INFO - 'cmd': ('bash', 19:21:44 INFO - '-c', 19:21:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:21:44 INFO - 'enabled': True, 19:21:44 INFO - 'halt_on_failure': False, 19:21:44 INFO - 'name': 'check_screen_resolution'},), 19:21:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:21:44 INFO - 'cmd': ('bash', 19:21:44 INFO - '-c', 19:21:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:21:44 INFO - 'enabled': True, 19:21:44 INFO - 'halt_on_failure': False, 19:21:44 INFO - 'name': 'check_screen_resolution'},), 19:21:44 INFO - 'run_cmd_checks_enabled': True, 19:21:44 INFO - 'suite': 'dromaeojs-e10s', 19:21:44 INFO - 'system_bits': '32', 19:21:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:21:44 INFO - 'title': 't-yosemite-r7-0380', 19:21:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:21:44 INFO - 'use_talos_json': True, 19:21:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:21:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:21:44 INFO - 'work_dir': 'build'} 19:21:44 INFO - [mozharness: 2017-05-25 02:21:44.808282Z] Running clobber step. 19:21:44 INFO - Running pre-action listener: _resource_record_pre_action 19:21:44 INFO - Running main action method: clobber 19:21:44 INFO - rmtree: /builds/slave/test/build 19:21:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:21:47 INFO - Running post-action listener: _resource_record_post_action 19:21:47 INFO - [mozharness: 2017-05-25 02:21:47.249581Z] Finished clobber step (success) 19:21:47 INFO - [mozharness: 2017-05-25 02:21:47.249743Z] Running read-buildbot-config step. 19:21:47 INFO - Running pre-action listener: _resource_record_pre_action 19:21:47 INFO - Running main action method: read_buildbot_config 19:21:47 INFO - Using buildbot properties: 19:21:47 INFO - { 19:21:47 INFO - "project": "", 19:21:47 INFO - "product": "firefox", 19:21:47 INFO - "who": "ryanvm@gmail.com", 19:21:47 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 19:21:47 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:21:47 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:21:47 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:21:47 INFO - "stage_platform": "macosx64", 19:21:47 INFO - "basedir": "/builds/slave/test", 19:21:47 INFO - "buildnumber": 163, 19:21:47 INFO - "platform": "yosemite_r7", 19:21:47 INFO - "parent_task_id": "XDJj8RO-SfmikqU4UG5mSA", 19:21:47 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:21:47 INFO - "slavebuilddir": "test", 19:21:47 INFO - "taskId": "I8cIg5k5QV-DYEsd1dq-WQ", 19:21:47 INFO - "branch": "mozilla-central", 19:21:47 INFO - "script_repo_revision": "production", 19:21:47 INFO - "revision": "d10f5ccd882b965fcad39914f7c3c930d1301a41", 19:21:47 INFO - "slavename": "t-yosemite-r7-0380", 19:21:47 INFO - "repo_path": "mozilla-central" 19:21:47 INFO - } 19:21:47 INFO - Finding installer, test and symbols from parent task. 19:21:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I8cIg5k5QV-DYEsd1dq-WQ'}, attempt #1 19:21:47 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I8cIg5k5QV-DYEsd1dq-WQ'}, attempt #1 19:21:48 INFO - Task dependencies: NmX0iBzwQ66g3FVqZ5F2oA XDJj8RO-SfmikqU4UG5mSA 19:21:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA'}, attempt #1 19:21:48 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:21:48 INFO - Set test_url: https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/test_packages.json 19:21:48 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/target.crashreporter-symbols.zip 19:21:48 INFO - Running post-action listener: _resource_record_post_action 19:21:48 INFO - [mozharness: 2017-05-25 02:21:48.610861Z] Finished read-buildbot-config step (success) 19:21:48 INFO - [mozharness: 2017-05-25 02:21:48.610979Z] Running download-and-extract step. 19:21:48 INFO - Running pre-action listener: _resource_record_pre_action 19:21:48 INFO - Running main action method: download_and_extract 19:21:48 INFO - mkdir: /builds/slave/test/build/tests 19:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:21:48 INFO - trying https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:21:48 INFO - Downloading https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/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 19:21:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/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 19:21:49 INFO - Downloaded 1328 bytes. 19:21:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:21:49 INFO - Using the following test package requirements: 19:21:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:21:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:21:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:21:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:21:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'jsshell-mac.zip'], 19:21:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:21:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:21:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:21:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:21:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 19:21:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:21:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:21:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:21:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:21:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:21:49 INFO - Fetch https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:21:51 INFO - Content-Length response header: 57630778 19:21:51 INFO - Bytes received: 57630778 19:22:09 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 19:22:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:22:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:22:09 INFO - Fetch https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:22:10 INFO - Content-Length response header: 57630778 19:22:10 INFO - Bytes received: 57630778 19:22:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:22:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:22:27 INFO - Fetch https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:22:28 INFO - Content-Length response header: 14411859 19:22:28 INFO - Bytes received: 14411859 19:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:29 INFO - trying https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:22:29 INFO - Downloading https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:22:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/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 19:22:31 INFO - Downloaded 67257541 bytes. 19:22:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:22:31 INFO - mkdir: /builds/slave/test/properties 19:22:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:22:31 INFO - Writing to file /builds/slave/test/properties/build_url 19:22:31 INFO - Contents: 19:22:31 INFO - build_url:https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:22:31 INFO - Running post-action listener: _resource_record_post_action 19:22:31 INFO - Running post-action listener: set_extra_try_arguments 19:22:31 INFO - [mozharness: 2017-05-25 02:22:31.191838Z] Finished download-and-extract step (success) 19:22:31 INFO - [mozharness: 2017-05-25 02:22:31.191949Z] Running populate-webroot step. 19:22:31 INFO - Running pre-action listener: _resource_record_pre_action 19:22:31 INFO - Running main action method: populate_webroot 19:22:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:22:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:22:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:22:31 INFO - '--tppagecycles', 19:22:31 INFO - '7'], 19:22:31 INFO - 'tests': ['tsvgm']}}, 19:22:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:22:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:22:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:22:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:22:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:22:31 INFO - 'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:22:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:22:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:22:31 INFO - 'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['damp', 'tps']}, 19:22:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:22:31 INFO - 'tests': ['damp', 'tps']}, 19:22:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['dromaeo_dom']}, 19:22:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:22:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:22:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:22:31 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:22:31 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:22:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['a11yr', 19:22:31 INFO - 'ts_paint', 19:22:31 INFO - 'tpaint', 19:22:31 INFO - 'sessionrestore', 19:22:31 INFO - 'sessionrestore_no_auto_restore', 19:22:31 INFO - 'tabpaint']}, 19:22:31 INFO - 'other-e10s': {'tests': ['a11yr', 19:22:31 INFO - 'ts_paint', 19:22:31 INFO - 'tpaint', 19:22:31 INFO - 'sessionrestore', 19:22:31 INFO - 'sessionrestore_no_auto_restore', 19:22:31 INFO - 'tabpaint']}, 19:22:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:22:31 INFO - 'ts_paint', 19:22:31 INFO - 'tpaint', 19:22:31 INFO - 'sessionrestore', 19:22:31 INFO - 'sessionrestore_no_auto_restore', 19:22:31 INFO - 'tabpaint']}, 19:22:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:22:31 INFO - 'ts_paint', 19:22:31 INFO - 'tpaint', 19:22:31 INFO - 'sessionrestore', 19:22:31 INFO - 'sessionrestore_no_auto_restore', 19:22:31 INFO - 'tabpaint']}, 19:22:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['a11yr', 19:22:31 INFO - 'ts_paint', 19:22:31 INFO - 'tpaint', 19:22:31 INFO - 'sessionrestore', 19:22:31 INFO - 'sessionrestore_no_auto_restore', 19:22:31 INFO - 'tabpaint']}, 19:22:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['a11yr', 19:22:31 INFO - 'ts_paint', 19:22:31 INFO - 'tpaint', 19:22:31 INFO - 'sessionrestore', 19:22:31 INFO - 'sessionrestore_no_auto_restore', 19:22:31 INFO - 'tabpaint']}, 19:22:31 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:22:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:22:31 INFO - 'bloom_basic_ref']}, 19:22:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['tsvgx', 19:22:31 INFO - 'tsvgr_opacity', 19:22:31 INFO - 'tart', 19:22:31 INFO - 'tscrollx', 19:22:31 INFO - 'cart', 19:22:31 INFO - 'tsvg_static']}, 19:22:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:22:31 INFO - 'tsvgr_opacity', 19:22:31 INFO - 'tart', 19:22:31 INFO - 'tscrollx', 19:22:31 INFO - 'cart', 19:22:31 INFO - 'tsvg_static']}, 19:22:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:22:31 INFO - 'talos_options': ['--disable-e10s'], 19:22:31 INFO - 'tests': ['tp5o']}, 19:22:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:22:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:22:31 INFO - 'talos_options': ['--disable-e10s', 19:22:31 INFO - '--xperf_path', 19:22:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:31 INFO - 'tests': ['tp5n']}, 19:22:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:22:31 INFO - 'talos_options': ['--xperf_path', 19:22:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:22:31 INFO - 'tests': ['tp5n']}}, 19:22:31 INFO - 'talos.zip': {'path': '', 19:22:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:22:31 INFO - Running post-action listener: _resource_record_post_action 19:22:31 INFO - [mozharness: 2017-05-25 02:22:31.213546Z] Finished populate-webroot step (success) 19:22:31 INFO - [mozharness: 2017-05-25 02:22:31.213682Z] Running create-virtualenv step. 19:22:31 INFO - Running pre-action listener: _resource_record_pre_action 19:22:31 INFO - Running main action method: create_virtualenv 19:22:31 INFO - Creating virtualenv /builds/slave/test/build/venv 19:22:31 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 19:22:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:22:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:22:31 INFO - Using real prefix '/tools/python27' 19:22:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:22:32 INFO - Installing distribute.............................................................................................................................................................................................done. 19:22:34 INFO - Installing pip.................done. 19:22:34 INFO - Return code: 0 19:22:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:22:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:22:35 INFO - Reading from file tmpfile_stdout 19:22:35 INFO - Output received: 19:22:35 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) 19:22:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:22:35 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')]} 19:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:35 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')]} 19:22:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:35 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:35 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 19:22:35 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 19:22:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:35 INFO - 'HOME': '/Users/cltbld', 19:22:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:35 INFO - 'LOGNAME': 'cltbld', 19:22:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:35 INFO - 'MOZ_NO_REMOTE': '1', 19:22:35 INFO - 'NO_EM_RESTART': '1', 19:22:35 INFO - 'PAGER': '/bin/cat', 19:22:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:35 INFO - 'PWD': '/builds/slave/test', 19:22:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:35 INFO - 'SHELL': '/bin/bash', 19:22:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:35 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:35 INFO - 'USER': 'cltbld', 19:22:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:35 INFO - 'XPC_FLAGS': '0x0', 19:22:35 INFO - 'XPC_SERVICE_NAME': '0', 19:22:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:35 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 19:22:35 INFO - Cleaning up... 19:22:35 INFO - Return code: 0 19:22:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:22:35 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')]} 19:22:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:35 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')]} 19:22:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:35 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:35 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 19:22:35 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 19:22:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:35 INFO - 'HOME': '/Users/cltbld', 19:22:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:35 INFO - 'LOGNAME': 'cltbld', 19:22:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:35 INFO - 'MOZ_NO_REMOTE': '1', 19:22:35 INFO - 'NO_EM_RESTART': '1', 19:22:35 INFO - 'PAGER': '/bin/cat', 19:22:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:35 INFO - 'PWD': '/builds/slave/test', 19:22:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:35 INFO - 'SHELL': '/bin/bash', 19:22:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:35 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:35 INFO - 'USER': 'cltbld', 19:22:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:35 INFO - 'XPC_FLAGS': '0x0', 19:22:35 INFO - 'XPC_SERVICE_NAME': '0', 19:22:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:35 INFO - Downloading/unpacking psutil>=3.1.1 19:22:35 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 19:22:35 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 19:22:35 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 19:22:35 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 19:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:22:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:37 INFO - Installing collected packages: psutil 19:22:37 INFO - Running setup.py install for psutil 19:22:37 INFO - building 'psutil._psutil_osx' extension 19:22:37 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 19:22:38 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 19:22:38 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 19:22:38 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 19:22:38 INFO - building 'psutil._psutil_posix' extension 19:22:38 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 19:22:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:22:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:22:38 INFO - ^ 19:22:38 INFO - 1 warning generated. 19:22:38 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 19:22:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:22:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:22:38 INFO - Successfully installed psutil 19:22:38 INFO - Cleaning up... 19:22:38 INFO - Return code: 0 19:22:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:22:38 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')]} 19:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:38 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')]} 19:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:39 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:39 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 19:22:39 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 19:22:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:39 INFO - 'HOME': '/Users/cltbld', 19:22:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:39 INFO - 'LOGNAME': 'cltbld', 19:22:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:39 INFO - 'MOZ_NO_REMOTE': '1', 19:22:39 INFO - 'NO_EM_RESTART': '1', 19:22:39 INFO - 'PAGER': '/bin/cat', 19:22:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:39 INFO - 'PWD': '/builds/slave/test', 19:22:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:39 INFO - 'SHELL': '/bin/bash', 19:22:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:39 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:39 INFO - 'USER': 'cltbld', 19:22:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:39 INFO - 'XPC_FLAGS': '0x0', 19:22:39 INFO - 'XPC_SERVICE_NAME': '0', 19:22:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:22:39 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 19:22:39 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 19:22:39 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 19:22:39 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 19:22:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:22:41 INFO - Installing collected packages: mozsystemmonitor 19:22:41 INFO - Running setup.py install for mozsystemmonitor 19:22:41 INFO - Successfully installed mozsystemmonitor 19:22:41 INFO - Cleaning up... 19:22:41 INFO - Return code: 0 19:22:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:22:41 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')]} 19:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:41 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')]} 19:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:41 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:41 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 19:22:41 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 19:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:41 INFO - 'HOME': '/Users/cltbld', 19:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:41 INFO - 'LOGNAME': 'cltbld', 19:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:41 INFO - 'MOZ_NO_REMOTE': '1', 19:22:41 INFO - 'NO_EM_RESTART': '1', 19:22:41 INFO - 'PAGER': '/bin/cat', 19:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:41 INFO - 'PWD': '/builds/slave/test', 19:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:41 INFO - 'SHELL': '/bin/bash', 19:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:41 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:41 INFO - 'USER': 'cltbld', 19:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:41 INFO - 'XPC_FLAGS': '0x0', 19:22:41 INFO - 'XPC_SERVICE_NAME': '0', 19:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:42 INFO - Downloading/unpacking jsonschema==2.5.1 19:22:42 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 19:22:42 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 19:22:42 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 19:22:42 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 19:22:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:22:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 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 19:22:43 INFO - Downloading functools32-3.2.3-2.tar.gz 19:22:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:22:43 INFO - warning: no files found matching '*.txt' 19:22:43 INFO - no previously-included directories found matching 'build' 19:22:43 INFO - no previously-included directories found matching 'dist' 19:22:43 INFO - no previously-included directories found matching '.git*' 19:22:43 INFO - Installing collected packages: jsonschema, functools32 19:22:43 INFO - Running setup.py install for functools32 19:22:44 INFO - warning: no files found matching '*.txt' 19:22:44 INFO - no previously-included directories found matching 'build' 19:22:44 INFO - no previously-included directories found matching 'dist' 19:22:44 INFO - no previously-included directories found matching '.git*' 19:22:44 INFO - Successfully installed jsonschema functools32 19:22:44 INFO - Cleaning up... 19:22:44 INFO - Return code: 0 19:22:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:44 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:44 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 19:22:44 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 19:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:44 INFO - 'HOME': '/Users/cltbld', 19:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:44 INFO - 'LOGNAME': 'cltbld', 19:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:44 INFO - 'MOZ_NO_REMOTE': '1', 19:22:44 INFO - 'NO_EM_RESTART': '1', 19:22:44 INFO - 'PAGER': '/bin/cat', 19:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:44 INFO - 'PWD': '/builds/slave/test', 19:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:44 INFO - 'SHELL': '/bin/bash', 19:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:44 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:44 INFO - 'USER': 'cltbld', 19:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:44 INFO - 'XPC_FLAGS': '0x0', 19:22:44 INFO - 'XPC_SERVICE_NAME': '0', 19:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:22:44 INFO - Cleaning up... 19:22:44 INFO - Return code: 0 19:22:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:44 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:44 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 19:22:44 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 19:22:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:44 INFO - 'HOME': '/Users/cltbld', 19:22:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:44 INFO - 'LOGNAME': 'cltbld', 19:22:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:44 INFO - 'MOZ_NO_REMOTE': '1', 19:22:44 INFO - 'NO_EM_RESTART': '1', 19:22:44 INFO - 'PAGER': '/bin/cat', 19:22:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:44 INFO - 'PWD': '/builds/slave/test', 19:22:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:44 INFO - 'SHELL': '/bin/bash', 19:22:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:44 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:44 INFO - 'USER': 'cltbld', 19:22:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:44 INFO - 'XPC_FLAGS': '0x0', 19:22:44 INFO - 'XPC_SERVICE_NAME': '0', 19:22:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:44 INFO - Downloading/unpacking blobuploader==1.2.4 19:22:44 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 19:22:44 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 19:22:44 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 19:22:44 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 19:22:45 INFO - Downloading blobuploader-1.2.4.tar.gz 19:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:22:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:22:46 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 19:22:46 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 19:22:46 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 19:22:46 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 19:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:22:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:22:46 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 19:22:46 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 19:22:46 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 19:22:46 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 19:22:46 INFO - Downloading docopt-0.6.1.tar.gz 19:22:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:22:47 INFO - Installing collected packages: blobuploader, requests, docopt 19:22:47 INFO - Running setup.py install for blobuploader 19:22:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:22:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:22:47 INFO - Running setup.py install for requests 19:22:47 INFO - Running setup.py install for docopt 19:22:47 INFO - Successfully installed blobuploader requests docopt 19:22:47 INFO - Cleaning up... 19:22:47 INFO - Return code: 0 19:22:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22: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')]} 19:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22: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')]} 19:22:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:47 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:47 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 19:22:47 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 19:22:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:47 INFO - 'HOME': '/Users/cltbld', 19:22:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:47 INFO - 'LOGNAME': 'cltbld', 19:22:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:47 INFO - 'MOZ_NO_REMOTE': '1', 19:22:47 INFO - 'NO_EM_RESTART': '1', 19:22:47 INFO - 'PAGER': '/bin/cat', 19:22:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:47 INFO - 'PWD': '/builds/slave/test', 19:22:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:47 INFO - 'SHELL': '/bin/bash', 19:22:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:47 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:47 INFO - 'USER': 'cltbld', 19:22:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:47 INFO - 'XPC_FLAGS': '0x0', 19:22:47 INFO - 'XPC_SERVICE_NAME': '0', 19:22:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-ld5Ydd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-4ITBXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-1kNCeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-Fz22X4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-erq4dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-b2nz4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-qA9sly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-YvZG15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:22:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:22:48 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-ygwTyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:22:49 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-rw28rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:22:49 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-hH9jYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:22:49 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-sexl11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:22:49 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-9AXa_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:22:49 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-LABaHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:49 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-E6T3lV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:22:49 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-w45NBF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:22:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:22:49 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-JHwMcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:22:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:22:49 INFO - Running setup.py install for manifestparser 19:22:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:22:49 INFO - Running setup.py install for mozcrash 19:22:50 INFO - Running setup.py install for mozdebug 19:22:50 INFO - Running setup.py install for mozdevice 19:22:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:22:50 INFO - Running setup.py install for mozfile 19:22:50 INFO - Running setup.py install for mozhttpd 19:22:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:22:50 INFO - Running setup.py install for mozinfo 19:22:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:22:50 INFO - Running setup.py install for mozInstall 19:22:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:22:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:22:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:22:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:22:50 INFO - Running setup.py install for mozleak 19:22:51 INFO - Running setup.py install for mozlog 19:22:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:22:51 INFO - Running setup.py install for moznetwork 19:22:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:22:51 INFO - Running setup.py install for mozprocess 19:22:51 INFO - Running setup.py install for mozprofile 19:22:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:22:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:22:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:22:51 INFO - Running setup.py install for mozrunner 19:22:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:22:51 INFO - Running setup.py install for mozscreenshot 19:22:52 INFO - Running setup.py install for moztest 19:22:52 INFO - Running setup.py install for mozversion 19:22:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:22:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:22:52 INFO - Cleaning up... 19:22:52 INFO - Return code: 0 19:22:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22:52 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')]} 19:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:52 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')]} 19:22:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:52 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:52 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 19:22:52 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 19:22:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:52 INFO - 'HOME': '/Users/cltbld', 19:22:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:52 INFO - 'LOGNAME': 'cltbld', 19:22:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:52 INFO - 'MOZ_NO_REMOTE': '1', 19:22:52 INFO - 'NO_EM_RESTART': '1', 19:22:52 INFO - 'PAGER': '/bin/cat', 19:22:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:52 INFO - 'PWD': '/builds/slave/test', 19:22:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:52 INFO - 'SHELL': '/bin/bash', 19:22:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:52 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:52 INFO - 'USER': 'cltbld', 19:22:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:52 INFO - 'XPC_FLAGS': '0x0', 19:22:52 INFO - 'XPC_SERVICE_NAME': '0', 19:22:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:22:52 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-uLhXBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:22:52 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)) 19:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:22:52 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-z34R_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:22:52 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)) 19:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:22:52 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-oz7SIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:22:52 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)) 19:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:22:52 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-C_Mad7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-4dk7zs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:22:53 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)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-bbLUtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:22:53 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)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-mGj5ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:22:53 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)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-2ndhhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:22:53 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)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-z_ueLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:22:53 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)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-gXV2iY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:22:53 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)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-vEIRMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:22:53 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)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-rUu5pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:22:53 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)) 19:22:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:22:53 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-6WlVfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:22:54 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)) 19:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:22:54 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-98t92S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:22:54 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)) 19:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:54 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-q8YjDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:22:54 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)) 19:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:22:54 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-xe3oYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:22:54 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)) 19:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:22:54 INFO - Running setup.py (path:/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/pip-6kT3kS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:22:54 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)) 19:22:54 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)) 19:22:54 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)) 19:22:54 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)) 19:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:22:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:22:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:22:54 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 19:22:54 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 19:22:54 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 19:22:54 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 19:22:55 INFO - Downloading blessings-1.6.tar.gz 19:22:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:22:56 INFO - Installing collected packages: blessings 19:22:56 INFO - Running setup.py install for blessings 19:22:56 INFO - Successfully installed blessings 19:22:56 INFO - Cleaning up... 19:22:56 INFO - Return code: 0 19:22:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:22:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:22:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:22:56 INFO - Reading from file tmpfile_stdout 19:22:56 INFO - Current package versions: 19:22:56 INFO - blessings == 1.6 19:22:56 INFO - blobuploader == 1.2.4 19:22:56 INFO - docopt == 0.6.1 19:22:56 INFO - functools32 == 3.2.3-2 19:22:56 INFO - jsonschema == 2.5.1 19:22:56 INFO - manifestparser == 1.1 19:22:56 INFO - mozInstall == 1.12 19:22:56 INFO - mozcrash == 1.0 19:22:56 INFO - mozdebug == 0.1 19:22:56 INFO - mozdevice == 0.51 19:22:56 INFO - mozfile == 1.2 19:22:56 INFO - mozhttpd == 0.7 19:22:56 INFO - mozinfo == 0.9 19:22:56 INFO - mozleak == 0.1 19:22:56 INFO - mozlog == 3.4 19:22:56 INFO - moznetwork == 0.27 19:22:56 INFO - mozprocess == 0.25 19:22:56 INFO - mozprofile == 0.28 19:22:56 INFO - mozrunner == 6.13 19:22:56 INFO - mozscreenshot == 0.1 19:22:56 INFO - mozsystemmonitor == 0.3 19:22:56 INFO - moztest == 0.8 19:22:56 INFO - mozversion == 1.4 19:22:56 INFO - psutil == 3.1.1 19:22:56 INFO - requests == 1.2.3 19:22:56 INFO - wsgiref == 0.1.2 19:22:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:22: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')]} 19:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22: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')]} 19:22:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:56 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 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:56 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 19:22:56 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 19:22:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:56 INFO - 'HOME': '/Users/cltbld', 19:22:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:56 INFO - 'LOGNAME': 'cltbld', 19:22:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:56 INFO - 'MOZ_NO_REMOTE': '1', 19:22:56 INFO - 'NO_EM_RESTART': '1', 19:22:56 INFO - 'PAGER': '/bin/cat', 19:22:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:56 INFO - 'PWD': '/builds/slave/test', 19:22:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:56 INFO - 'SHELL': '/bin/bash', 19:22:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:56 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:56 INFO - 'USER': 'cltbld', 19:22:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:56 INFO - 'XPC_FLAGS': '0x0', 19:22:56 INFO - 'XPC_SERVICE_NAME': '0', 19:22:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:56 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)) 19:22:56 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)) 19:22:56 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)) 19:22:56 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)) 19:22:56 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)) 19:22:56 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)) 19:22:56 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)) 19:22:56 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)) 19:22:56 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)) 19:22:56 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:22:56 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 19:22:56 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 19:22:56 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 19:22:56 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 19:22:58 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:22:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:22:58 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)) 19:22:58 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)) 19:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:22:58 INFO - Installing collected packages: marionette-driver 19:22:58 INFO - Running setup.py install for marionette-driver 19:22:58 INFO - Successfully installed marionette-driver 19:22:58 INFO - Cleaning up... 19:22:58 INFO - Return code: 0 19:22:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:22:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1033a7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1033ab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefabcdffc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, '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.afgFfLpwV6/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.NslJiPfwua/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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 19:22:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:22:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:22:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:22:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:22:58 INFO - 'HOME': '/Users/cltbld', 19:22:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:22:58 INFO - 'LOGNAME': 'cltbld', 19:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:22:58 INFO - 'MOZ_NO_REMOTE': '1', 19:22:58 INFO - 'NO_EM_RESTART': '1', 19:22:58 INFO - 'PAGER': '/bin/cat', 19:22:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:22:58 INFO - 'PWD': '/builds/slave/test', 19:22:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:22:58 INFO - 'SHELL': '/bin/bash', 19:22:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:22:58 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:22:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:22:58 INFO - 'USER': 'cltbld', 19:22:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:22:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:22:58 INFO - 'XPC_FLAGS': '0x0', 19:22:58 INFO - 'XPC_SERVICE_NAME': '0', 19:22:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:22:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:22:58 INFO - Cleaning up... 19:22:58 INFO - Return code: 0 19:22:58 INFO - Running post-action listener: _resource_record_post_action 19:22:58 INFO - Running post-action listener: _start_resource_monitoring 19:22:58 INFO - Starting resource monitoring. 19:22:58 INFO - [mozharness: 2017-05-25 02:22:58.814851Z] Finished create-virtualenv step (success) 19:22:58 INFO - [mozharness: 2017-05-25 02:22:58.815833Z] Running install step. 19:22:58 INFO - Running pre-action listener: _resource_record_pre_action 19:22:58 INFO - Running main action method: install 19:22:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:22:59 INFO - Reading from file tmpfile_stdout 19:22:59 INFO - Detecting whether we're running mozinstall >=1.0... 19:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:22:59 INFO - Reading from file tmpfile_stdout 19:22:59 INFO - Output received: 19:22:59 INFO - Usage: mozinstall [options] installer 19:22:59 INFO - Options: 19:22:59 INFO - -h, --help show this help message and exit 19:22:59 INFO - -d DEST, --destination=DEST 19:22:59 INFO - Directory to install application into. [default: 19:22:59 INFO - "/builds/slave/test"] 19:22:59 INFO - --app=APP Application being installed. [default: firefox] 19:22:59 INFO - mkdir: /builds/slave/test/build/application 19:22:59 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'] 19:22:59 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 19:23:20 INFO - Reading from file tmpfile_stdout 19:23:20 INFO - Output received: 19:23:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:23:20 INFO - Running post-action listener: _resource_record_post_action 19:23:20 INFO - [mozharness: 2017-05-25 02:23:20.531085Z] Finished install step (success) 19:23:20 INFO - [mozharness: 2017-05-25 02:23:20.531232Z] Running run-tests step. 19:23:20 INFO - Running pre-action listener: _resource_record_pre_action 19:23:20 INFO - Running main action method: run_tests 19:23:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:23:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:23:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:23:20 INFO - 2017-05-24 19:23:20.545 screenresolution[1651:7638] starting screenresolution argv=screenresolution get 19:23:20 INFO - 2017-05-24 19:23:20.548 screenresolution[1651:7638] Display 0: 1600x1200x32@60 19:23:20 INFO - 2017-05-24 19:23:20.554 screenresolution[1652:7640] starting screenresolution argv=screenresolution list 19:23:20 INFO - Available Modes on Display 0 19:23:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:23:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:23:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:23:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:23:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:23:20 INFO - Intel Iris: 19:23:20 INFO - Chipset Model: Intel Iris 19:23:20 INFO - Type: GPU 19:23:20 INFO - Bus: Built-In 19:23:20 INFO - VRAM (Dynamic, Max): 1536 MB 19:23:20 INFO - Vendor: Intel (0x8086) 19:23:20 INFO - Device ID: 0x0a2e 19:23:20 INFO - Revision ID: 0x0009 19:23:20 INFO - Displays: 19:23:20 INFO - Display: 19:23:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:23:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:23:20 INFO - Display Serial Number: 1600x1200 60 19:23:20 INFO - Main Display: Yes 19:23:20 INFO - Mirror: Off 19:23:20 INFO - Online: Yes 19:23:20 INFO - Rotation: Supported 19:23:20 INFO - Return code: 0 19:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:23:20 INFO - Python 2.7.3 19:23:20 INFO - Return code: 0 19:23:20 INFO - grabbing minidump binary from tooltool 19:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:23:20 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 0x1030b7d30>, '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 0x10306bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1030acbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:23:20 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 19:23:20 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 19:23:20 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 19:23:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:23:20 INFO - Return code: 0 19:23:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:23:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:23:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:23:20 INFO - ENV: RUST_BACKTRACE is now 1 19:23:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:23:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:23:20 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/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0380', '--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 19:23:20 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/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0380 --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 19:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.afgFfLpwV6/Render', 19:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:23:20 INFO - 'HOME': '/Users/cltbld', 19:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:23:20 INFO - 'LOGNAME': 'cltbld', 19:23:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:23:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:23:20 INFO - 'MOZ_NO_REMOTE': '1', 19:23:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:23:20 INFO - 'NO_EM_RESTART': '1', 19:23:20 INFO - 'PAGER': '/bin/cat', 19:23:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:23:20 INFO - 'PWD': '/builds/slave/test', 19:23:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:23:20 INFO - 'RUST_BACKTRACE': '1', 19:23:20 INFO - 'SHELL': '/bin/bash', 19:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NslJiPfwua/Listeners', 19:23:20 INFO - 'TMPDIR': '/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/', 19:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:23:20 INFO - 'USER': 'cltbld', 19:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:23:20 INFO - 'XPC_FLAGS': '0x0', 19:23:20 INFO - 'XPC_SERVICE_NAME': '0', 19:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:23:20 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/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0380', '--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 19:23:21 INFO - mozversion application_buildid: 20170524174646 19:23:21 INFO - mozversion application_changeset: d10f5ccd882b965fcad39914f7c3c930d1301a41 19:23:21 INFO - mozversion application_display_name: Nightly 19:23:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:23:21 INFO - mozversion application_name: Firefox 19:23:21 INFO - mozversion application_remotingname: firefox 19:23:21 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:23:21 INFO - mozversion application_vendor: Mozilla 19:23:21 INFO - mozversion application_version: 55.0a1 19:23:21 INFO - mozversion platform_buildid: 20170524174646 19:23:21 INFO - mozversion platform_changeset: d10f5ccd882b965fcad39914f7c3c930d1301a41 19:23:21 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:23:21 INFO - mozversion platform_version: 55.0a1 19:23:21 INFO - using testdate: 1495679001 19:23:21 INFO - actual date: 1495679001 19:23:21 INFO - starting webserver on 'localhost:49230' 19:23:21 INFO - SUITE-START | Running 2 tests 19:23:21 INFO - TEST-START | dromaeo_css 19:23:21 INFO - operating with platform_type : mac_ 19:23:21 INFO - Initialising browser for dromaeo_css test... 19:23:21 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmp6ciTsz/profile 19:23:21 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 19:23:54 INFO - TEST-INFO | 1662: killed by SIGTERM 19:23:54 INFO - Browser initialized. 19:23:54 INFO - Running cycle 1/1 for dromaeo_css test... 19:23:54 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmp6ciTsz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:23:57 INFO - PID 1676 | 2017-05-24 19:23:57.011 plugin-container[1677:8219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9637, name = 'com.apple.tsm.portname' 19:23:57 INFO - PID 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:23:57 INFO - PID 1676 | 2017-05-24 19:23:57.012 plugin-container[1677:8219] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 19:23:57 INFO - PID 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:23:57 INFO - PID 1676 | 2017-05-24 19:23:57.012 plugin-container[1677:8219] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:23:57 INFO - PID 1676 | 2017-05-24 19:23:57.470 plugin-container[1678:8262] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 19:23:57 INFO - PID 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:23:57 INFO - PID 1676 | 2017-05-24 19:23:57.470 plugin-container[1678:8262] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b17, name = 'com.apple.CFPasteboardClient' 19:23:57 INFO - PID 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:23:57 INFO - PID 1676 | 2017-05-24 19:23:57.471 plugin-container[1678:8262] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:24:06 INFO - PID 1676 | RSS: Main: 246583296 19:24:06 INFO - PID 1676 | 19:27:09 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 19:27:10 INFO - PID 1676 | RSS: Main: 261578752 19:27:10 INFO - PID 1676 | 19:30:12 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 19:30:13 INFO - PID 1676 | RSS: Main: 273891328 19:30:13 INFO - PID 1676 | 19:33:14 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 19:33:15 INFO - PID 1676 | RSS: Main: 273809408 19:33:15 INFO - PID 1676 | 19:36:16 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 19:36:17 INFO - PID 1676 | RSS: Main: 277311488 19:36:17 INFO - PID 1676 | 19:39:20 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 19:39:20 INFO - PID 1676 | RSS: Main: 277495808 19:39:20 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 19:42:22 INFO - PID 1676 | RSS: Main: 279232512 19:42:22 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | __start_tp_report 19:42:22 INFO - PID 1676 | _x_x_mozilla_page_load 19:42:22 INFO - PID 1676 | _x_x_mozilla_page_load_details 19:42:22 INFO - PID 1676 | |i|pagename|runs| 19:42:22 INFO - PID 1676 | |0;dojo.html;3156.4476216662088;3588.7667301625374;3617.8733744318956;3627.9637203627963;3647.1429214134673;4790.928136077957;5858.619189752663;6200.472959798422;6218.502519798417;6277.717502712378;6201.875962480759;6222.875542489159;6268.780592679257;6284.780032698856;6308.085327627503;5011.348524691785;5258.815941442051;5378.381486129087;5439.48324909133;5450;5525.143602741581;5528.81980770188;5531.640443371188;5543.085390910495;5569.554435645159;5323.494268044529;5403.621746477709;5441.455854414567;5448.182772584105;5459.481349271813;5459.208414779874;5463.863403414906;5495.422980587021;5496.285482887239;5524.447555244484;5377.059014672417;5422.864428389283;5428.45715428454;5433.83698489042;5465.754041068161;6176.660283684396;6251.718672659696;6305.9369406305805;6324.272708638519;6333.366663333676;6481.4166724994975;6590.5716128451495;6702.128723265993;6716.361945615159;6732.83167920801;6810.557313774589;6827.249002609709;6830.975353696936;6843.349881761224;6869.107016087876;6206.720697568575;6363.7454501819875;6380.904286435707;6393.968030159819;6412.967935160387;5671.773129074873;6733.528652994243;6823.044773731682;6919.37725604698;6967.303269673093;6361.045843123476;6420;6479.416852483299;6543.770968016192;6589.209294884645;6707.765228216989;6780.254172041072;6781.559198652072;6796.082528858639;6824.761133360308;6639.3692599203;6647.335266473314;6647.60114393138;6652.434543063797;6684.565503242274;6095.664738439428;6401.455876250478;6469.353064693493;6651.900221496681;6690.69891854858;5321.6540924839765;5355.544778693855;5369.463053694677;5373.2208829719475;5404.594655400782;4760.428748550127;4762.690425122425;4771.570558649738;4777.378940737682;4815.807367705253;6056.031035034373;6105.9694701526205;6302.873942521124;6339.429451349401;6397.616143031433;6741.898871516931;6815.522913396114;6822.283660215705;6832.975053741979;6875.278095799969;6356.110144579762;6390.648514331663;6391.456726178234;6395.008773640093;6436.066770318278;5757.136429535603;5798.710064496842;5918.408159184133;5931.436513531207;5943.049112142036;6033.396660333931;6887.414569761526;6947.826304342432;6978.581285122909;6997.300269973166;6729.158855143107;6799.456043516606;6820.7271709133065;6822.488313376417;6831.043653888559;6940.375366217065;6977.476689248429;7008.264132266038;7017.333353331425;7026.859462810613;5368.32895888014;6400.871982560416;6461.515386345949;6620.337966203341;6667.166604174478;6720.563163394364;6773.119494465885;6786.93213067863;6808.557443766139;6837.62393068386;6202.72087756043;6344.651044192614;6366.395192456709;6511.576747511396;6535.901961470487;6417.614943103429;6523.3476652336285;6540.084388185563;6543.672816359068;6551.967240163769;6326.051092336186;6329.620222786648;6365.108884756045;6375.936240637534;6416.839579010562;6460.998545225498;6472.805815825533;6497.18785151856;6533.118029066015;6543.378379053982;6373.203349581302;6375.979843225062;6391.616503009835;6392.5844820088405;6394.328595497405 19:42:22 INFO - PID 1676 | |1;ext.html;650.300926504008;792.5680504125253;810.772983564602;1209.6129238643632;1274.5093139141427;38521.036974075665;39442.80278598607;39447.802760986226;39504;39548.60451395485;39511;39975.80012099935;39993.60006399935;40053;40089.39865902014;7587.127480339749;7760.961195194016;8245.093039765621;8328.08391076981;8425.578721063954;6906.274841141683;8276.296514796219;8394.412391132622;8399.160083991583;8408.285295749869;8251.876221856677;8365;8391;8403.915960840375;8408.117147699495;8280.089190189074;8359.95820020899;8376.120507346732;8392.992840859106;8400.327973762145;8104.6758129674745;8150.9184908150755;8162.347012239006;8195.71315003972;8200.630971606231;32920.176995575304;34186.80346187896;36883.63116368856;36967;37142.88571342837;3495.5106285120105;3518.0149558123767;3539.929201415983;3542.433210686278;3547.5210846535647;28156.71843281582;28468.861245550153;30541.69458305433;30613.928512501956;30686.69313306883;3093.706097920694;3123.8281894495794;3142.4814905540557;3144.5440411140257;3146.2134466383404;245990.3101453462;246033;249107;300039;300708.4964575185;46776.7661161692;46881.531184687716;47129.29306060412;47190;47552;48180.759096204296;48643;48812.75593622009;50209.49790502121;50818.74590627023;14217.004809663224;14270.001099923113;14379.352929118215;14389.064710793766;14414.927925360305;11352.318860868209;11428.542858285742;11547.037003709389;11635.709107272216;11666.591669291534;11641.825372619418;14176.291185440687;14218.715625687635;14240.501582444565;14291.714165716627;6310.0850376695125;6318.368163183645;6352.6823658816875;6354.633753742967;6355.093471958487;863.5811631358786;965.3918031640162;984.2765567308023;1053.099599842124;1057.1648397765832;24454;24784.628230576556;24858.005679772967;24897.751022489905;24919.377015574755;1113.5768408004906;1116.4919961417536;1140.8745038045809;1147.2714826085496;1151.694800877768;9384.812303753886;11045.729741079718;11375.829362559569;11384.257232679334;12680.80978785319;335235;336377;336546;336776;338591;54614;54950.900981980136;55088.44911550833;55205.72397138069;55237.72381138149;1498.9250537473301;1525.534711912855;1532.6245069957931;1533.2027345780132;1550.4650895441055;23999.60003999586;24152.275431737075;24158.033678652657;24397.39006524851;24420.389490262885;39131;39202;39256.01859953524;39340.60659393484;39424.605753943244;242449;275161;281519;281631;282252;35964.64035359718;36531.81734091419;36736.81631591825;36889;36942.815285923396;43511.9122021952;45144;46537.67311634337;46568.30147547857;46610.60168194959;25917.870410647825;30849.691503084683;31092.844535777178;31639;31675.841620791747;30818.84590577033;31106.533401998535;31543.684563154075;31675.20811979627;32351.35297294088;36988.81505592455;38615.648452304034;38621.034474138374;38659.6134038656;38726.80636596799;30486.542701859045;31114.91097811566;31462.291738524207;31665.8416707915;32163.51754723634;20462.79537204609;22415.32754017382;24750.25749227526;24977.125800596892;25022.499550009263 19:42:22 INFO - PID 1676 | |2;jquery.html;376.3958846052086;410.63658662084066;412.6265729514788;423.84953341563727;433.4278752047298;20597.897010514946;21041.158353665873;21418.785812141872;21450.463738406546;21708.457288567795;21914.671279930775;22346.329610111723;23306.65040024401;23583.41041473964;23657.526849463044;5365.517103460687;6093.817185484431;6313.873722525546;6355.586886852363;6395.392437718409;6163.982942814453;6183.567150299481;6303.117563541108;6357.173567436227;6359.173307470046;6165.198524191849;6209.627422354651;6213.751449941997;6225.097360882669;6228.19033525641;5999.790007349744;6171.166892469527;6195.783147589836;6277.843053923642;6315.8736825263695;5582.330120385539;5585.553155747531;5635.549156067505;5635.6618602883955;5645.096784514458;27008.324791880164;27754.444911101666;28046.158615241577;28635;28688.709008094687;2189.9233526826483;2247.1348530815753;2247.4606094537357;2254.7970682638643;2255.4699645583273;14397.856021439757;18047.548811279692;18543.516518678472;18582.256709731595;18655.90672046631;1827.7349784281337;2148.871067735941;2185.868847869133;2190.627593309141;2198.1756841184556;151508.242458787;156446.21776891043;157635;158017;159515.20242398712;12904.16122952005;13058.08593398472;13063.216207027608;13270.66823329405;13292.268925209013;16788.916055419646;16801.579960501087;16806.15969201535;16812.24344904482;16840.9895406276;8875.644974201088;9016.41393309446;9148.313876459291;9274.582643781046;9300.162985331353;8680.522571258514;8705.825883482295;8719.215270625675;8834.425762325429;8846.46921184731;6785.132474489494;8106.918930810734;8169.936273897289;8217.34261259098;8257.545834979133;2256.887155642244;2261.502469456717;2273.5111950930627;2274.8521346112448;2416.383822125382;943.551812888886;967.849983252597;971.455984648599;978.280963491833;1009.6617633092997;19981.400557983285;20130.597388052156;20529.89735051345;21278.829664368608;21392.75323463101;833.3000279764906;1002.4887307473095;1029.4286670897723;1032.7056788815153;1048.9947550262352;10558.419286939139;10838.729031774268;10872.402017888933;10918.781624367468;10942.507587158438;538853;576772;577901;586788;590217;101385;103259.93480130355;124727.3763631176;132165;134599.3270033663;1078.1374900079904;1177.3348058347005;1188.3761025461529;1222.9877701222874;1227.1655274413306;21508.247211347527;21521.89239053795;21923.12307507682;21946.780532194472;22137.00383482715;55128.72435637796;56239.43760562342;56360.71819640876;56558.58603534944;56837.715811420676;64647.67676161589;75322.24677753152;77363.61318193373;77912;78911;8831.072737362485;8909.821803564022;9038.051004644418;9091.272698183997;9109.407888487227;13823.585292441243;13870.791938121138;14059.578212653636;14081.788773168615;14109.2945352734;10721.839172412265;11784.469698863742;11950.940245299065;12011.978981786646;12046.51813927433;9481.478518681539;9574.808503830023;9817.803643926938;10787.730306742395;11303;9731.41611503312;9768.313713728781;9963.850542242017;11765.52937882475;12097.818532722193;8634.222919937236;8711.607977640895;8754.124587541195;9098.408603440756;9209.907900920905;10880.531128297578;11470.713232169262;12036.398180091135;12040.578579749666;12175.147739658492 19:42:22 INFO - PID 1676 | |3;mootools.html;1386.417704564083;1439.8920080993928;1448.2034880815556;1451.3904160252694;1460.846611105834;11708.004819590335;11721.472533735981;12247.958923491502;12274.956628686572;12276.447559859806;10503.474826258676;11830.349330786803;12287.38563071845;12291.385430728451;12313.815292770616;2518.4837108392826;2561.9487610247784;2562.7437256274316;2569.6145578163237;2573.562494375961;2493.5012997400504;2529.2791554407;2535.1000394859834;2536.5941449368106;2541.8601976891264;2491.302435318105;2528.8482691038503;2530.6710127683377;2540.0855691950883;2540.5808041673104;2393.8204634652297;2405.122130422401;2416.2871952773976;2421.8910149043336;2424.781769640732;2288.1762565476415;2425.927222183337;2442.096424322994;2447.8041756659604;2459.827812053157;11823.408829558573;11864.644060678194;11928.224665396721;11929.28424294545;11956.760864782742;6539.08452816606;6776.085228494188;6781.559198652072;6791.388775010273;6795.320467953215;4083.8774836754646;4249.405083288342;4304.096139810644;4314.115606300701;4324.200022995725;1180.752042071166;1225.5097960815656;1226.2887525235342;1237.5730373021293;1247.2204871955028;16596.751048734277;16736;16753.66492670152;16895.91552042232;17078.573035674082;5038.345015148007;5220.608454365862;5544.77820887168;5711.600187986801;5726.570507211975;11809.468573914022;11811.468483918245;11815.645530634094;11836.408179590986;11848.822267665992;6414.711337989802;6419.261784894704;6461.870762584722;6511.674416279167;6525.673716314165;6149.84625384369;6231.813045608548;6239.500839932794;6266.49868010558;6294.496440284858;3641.198936234024;3722.925541489209;3888.4361767543546;4246.129543443587;4346.521882592912;1277.1889849945167;1283.3006011723633;1284.8137020132028;1296.7730647136714;1298.2210673595955;620.92548894133;717.4834119433999;757.7461550380574;760.1220590218306;776.3401109057369;5842.357340692521;5867.119932010232;6058.939410605925;6091.390860913873;6259.906101408483;583.5419195931173;585.4526018172937;585.771549095853;601.8444586394133;603.8943184942617;3625.5649322081517;3717.312297225022;3722.3858063419775;3725.4784330193793;3741.0460332615007;14564.63588410277;15202.46791362297;15425.305861236271;15442;15462.84537154614;15135.243237838064;15222.923885380502;15367.001144925543;15374.9231253843;15402.921795474209;7560.735374261874;8151.30713889326;8381.874271885927;8711;8741.431806932656;5124.564412024871;5850.327212370632;6084.665343406155;6270.777198446259;6593.505487088391;12858;12860.807087893876;12970.28663423483;13558.322083895568;14033.929830351191;14058.282652404529;14146.858531414966;14226.928865355607;14235.857641423867;14383.42466301378;9268.814623707352;9479.146876781122;9626.518674066408;9643.807123857623;9653.227741780782;11091.667249982513;11179.944100279447;11310.660680179608;11361.147913906325;11389;9167.312451566026;9280.58237379306;9368.81262374735;9495.477748723886;9553.092456216647;9027.45835249887;9119.452832830051;9138.862917056116;9144.451332920045;9177.311701622539;8211.917880821115;8265.214804593554;8308.12764659702;8425.115362886809;8493.915060849313;6113.694315284307;6127.632342059491;6134.8159555213415;6170.969145154425;6228.626282423069;5602.9719851402115;5696.971515142398;6147.139400483847;6186;6233.688315584112 19:42:22 INFO - PID 1676 | |4;prototype.html;313.2638299995012;383.41337753237536;400.39340399295054;406.68888300450163;408.2834625232716;5817.825465236039;5951.315598706145;6285.6857157142185;6379;6443.935560644392;6100.938990610103;6254.43710066094;6261.530385221104;6286.8113956581265;6303.464205542523;4191.853285135021;4797.18447863864;4811.786578174044;4837.879053023667;4855.951440485585;4202.474690663667;4485.506594274627;4567.634589232854;4714.834980775673;4730.432348118231;4731.313959475874;4753.239481682931;4774.952250477503;4780.665353425262;4781.976090119544;4808.447028591721;4808.903821923577;4809.663323567352;4810.11012962603;4831.4926932671915;3519.595246546629;3635.363811333006;4136.476749182996;4165.687573431975;4262.936055959163;6086.817395478142;6093.512518998514;6111.174991376151;6119.479844213247;6138.907916381258;5195.038917800219;5714.685692286923;5733.312002559678;5760.567957403169;5763.855903602402;5580.9117222141695;6586.670666466657;6591.571547849375;6600.2079750429775;6617.963042544045;4343.283358245885;4395.1868904252815;4413.801378937956;4426.756528390937;4430.756308403036;6744.673087784707;6840.263894442213;6949.409300209438;6952.261934516371;7121.643917804089;4445.155420470101;4454;4457.665675074382;4460;4466.64266858657;4182.937255941163;4532.456105267389;4542.568455996675;4545.931811022837;4555.794989225493;4891.584215341736;5172.844814655567;5239.6376941994595;5417.160340147205;5431.099115309689;5804.765165411347;6103.42017508336;6301.802657495061;6679.632620205935;6699.531032827753;1317.3479127831786;1388.590365842079;1435.6339133521094;1545.1501674079211;1563.5856498028031;999.4009584664478;1010.6563768318807;1026.7832618347281;1030.4594631026664;1032.8605638238741;6136.662483563447;6279.811605651837;6299.370062993664;6320.494360451153;6326.493880489642;6322.241331040304;6920.30796920314;6990.475714321345;7014.403775679022;7022.297770223039;5376.274202982548;6110.602810817282;6342.5243106767175;6373.681315934277;6435.74257029723;3536.797488853802;3664.633536646314;3686.09690625793;3687.3178461984617;3694.224212915264;6583.703733332011;7042.647867606599;7181.210066892638;7244.45666575009;7249.05762250904;6117.755289788448;6971.233164351917;7243.348098671145;7257.709691612276;7292.562446253241;5401.594880383908;7102.609356485443;7110.857782844314;7209.315115064061;7275.781726548212;6383.7765678202895;6559.147310849559;6845.5550389224545;7019.508634395747;7084.185318688416;6280.9685951569945;6579.197186054112;6895.4138898194215;6954.652267386509;7067.363937245467;5674.687892165805;7095.58750024994;7217.422606191387;7277.235890231661;7297.927020729725;3743.9064023399633;4074.7351422157467;4203.327467605169;4346.6088052075465;4458.509563947899;5645.322561292671;6940.6748739172945;7059.929400705927;7077.646117693992;7088.645567721696;6201.224846894138;6600.768973085919;6728.562643428161;6782.593044417351;6814.8637027260165;6274.435300822765;6772;6828.692708828214;6831.316868313129;6881.793546193622;5496.890062198654;5904.881902362014;6419.390157934989;6618.768343107968;6658.83352916181;6050.425209605257;6419.775307864389;6619.867602647823;6819.2498825128205;6819.795406137824;6560.475161987125;6601.471882249504;6617.7683781067435;6619.3380661933425;6681.231658359351 19:42:22 INFO - PID 1676 | |5;yui.html;773.895524104246;827.0736774812203;837.4221786966989;863.9870401943969;875.3434923807145;16571.17144142794;16974.236159372827;17044.31822727091;19347.613047739036;19992.30026949057;18142.274309027624;18797.718034229496;18931.52671183221;18978.240870365204;19126.71309930352;959.1751094059103;998.8701468809045;1001.2690735762898;1002.0380434782618;1012.8733908261468;1009.9091081802636;1019.7195771162916;1022.3354819367396;1022.5143057047917;1028.9536970836293;1054.557086023868;1078.3691540448847;1088.9782204355909;1095.1457862866976;1096.358630201329;1061.9522121504551;1076.332673742277;1083.8970297821734;1087.782443511297;1087.9184061195442;803.9075506316732;804.2158895077312;806.0971711682953;806.5120602035743;808.1958451340895;14793.92603036978;15223.619409514851;15225.771613425808;18097.54756131094;18100.90949545257;14559.854401455956;17518.649627007388;17765.200565974563;17867.017314047724;18068.819311806845;14718.632034199123;14862.554123376316;14888.404463821435;14891.255437228096;14898.329575169144;3363.411403004489;3492.9650703492894;3558.1638314995957;3621.2757448510138;3653.0319465341704;133370;145428;146247;148840.25579872032;150549;12519.56181533642;12578.68553286157;12595.93702031484;12722.236665800081;12734.426950787052;23403.765962340498;26553.336166595604;26679.599806002927;26687.733122668913;27168.72831271662;7846;7866.842663146705;7871;7883.132855385903;7890.250426209501;3988.16248587794;4001.5398229204015;4050.3721923101966;4058.898527536835;4059.1475790083823;3743.7192210584317;4367.71609845359;4379.1241751649795;4454.688171828006;4506.3465797459185;863.9740807775777;884.7345796261096;894.2622336572354;897.9012308646044;899.469313105271;648.1509222917992;653.4282502810041;667.3393340592778;670.7551743613643;677.3497442455298;9983.800323993479;10468.68593942183;10827.83758243627;12077.879221207675;12343.506259749509;655.2661019657918;658.2200092889941;667.5494041521953;673.3233100733731;691.339770519153;5661.915071273934;5857.179994800731;6091.66495842724;6113.786017489367;6634.336566343327;138992;139580;141886.87169692462;143409;144873;83232;102745.97254027514;120163;122015.38992304982;123548;70897.6455117721;100570;100692.49653751684;101284.49357753017;102318.48840755748;12446.688832778891;12941.54704585335;12956.546520871725;12995.28525931045;12995.48018079304;44261.33607995818;44678.776606116764;45395.54604453913;45864.62406127821;46316;60395.09407358805;60690.08964865619;61263.38736612577;61432;62573.68713156405;16788.748168777234;16810.495685129466;17210.82789172092;17301.221445035255;17302.74045889338;18633.534161645526;18857;19020.334288299844;19138.52153696169;19214.615707686047;14089.788653170415;17019.829801701824;17357.132143392526;17530.73703894468;17647.82352176513;16021.439249626206;16419.671606567565;17563.82436175673;17675.82324176742;17691.638588668113;15685.980411273687;16695.08177050274;17036.574085647957;17205.397811076553;17289.827101729326;10993.340399576051;12106.970907472964;12192.512299508275;12274.631761047183;12375.504979801068;10239.897601023895;13205.603831885059;13296.86703132995;13324.666883327995;13330.666733331356 19:42:22 INFO - PID 1676 | __end_tp_report 19:42:22 INFO - PID 1676 | __start_cc_report 19:42:22 INFO - PID 1676 | _x_x_mozilla_cycle_collect,401 19:42:22 INFO - PID 1676 | __end_cc_report 19:42:22 INFO - PID 1676 | __startTimestamp1495680142809__endTimestamp 19:42:22 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | ------- Summary: start ------- 19:42:22 INFO - PID 1676 | Number of tests: 6 19:42:22 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | [#0] dojo.html Cycles:165 Average:6157.90 Median:6390.65 stddev:739.87 (11.6%) stddev-sans-first:703.68 19:42:22 INFO - PID 1676 | Values: 3156.4 3588.8 3617.9 3628.0 3647.1 4790.9 5858.6 6200.5 6218.5 6277.7 6201.9 6222.9 6268.8 6284.8 6308.1 5011.3 5258.8 5378.4 5439.5 5450.0 5525.1 5528.8 5531.6 5543.1 5569.6 5323.5 5403.6 5441.5 5448.2 5459.5 5459.2 5463.9 5495.4 5496.3 5524.4 5377.1 5422.9 5428.5 5433.8 5465.8 6176.7 6251.7 6305.9 6324.3 6333.4 6481.4 6590.6 6702.1 6716.4 6732.8 6810.6 6827.2 6831.0 6843.3 6869.1 6206.7 6363.7 6380.9 6394.0 6413.0 5671.8 6733.5 6823.0 6919.4 6967.3 6361.0 6420.0 6479.4 6543.8 6589.2 6707.8 6780.3 6781.6 6796.1 6824.8 6639.4 6647.3 6647.6 6652.4 6684.6 6095.7 6401.5 6469.4 6651.9 6690.7 5321.7 5355.5 5369.5 5373.2 5404.6 4760.4 4762.7 4771.6 4777.4 4815.8 6056.0 6106.0 6302.9 6339.4 6397.6 6741.9 6815.5 6822.3 6833.0 6875.3 6356.1 6390.6 6391.5 6395.0 6436.1 5757.1 5798.7 5918.4 5931.4 5943.0 6033.4 6887.4 6947.8 6978.6 6997.3 6729.2 6799.5 6820.7 6822.5 6831.0 6940.4 6977.5 7008.3 7017.3 7026.9 5368.3 6400.9 6461.5 6620.3 6667.2 6720.6 6773.1 6786.9 6808.6 6837.6 6202.7 6344.7 6366.4 6511.6 6535.9 6417.6 6523.3 6540.1 6543.7 6552.0 6326.1 6329.6 6365.1 6375.9 6416.8 6461.0 6472.8 6497.2 6533.1 6543.4 6373.2 6376.0 6391.6 6392.6 6394.3 19:42:22 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | [#1] ext.html Cycles:180 Average:44682.62 Median:3506.76 stddev:77293.22 (2204.1%) stddev-sans-first:77439.34 19:42:22 INFO - PID 1676 | Values: 650.3 792.6 810.8 1209.6 1274.5 38521.0 39442.8 39447.8 39504.0 39548.6 39511.0 39975.8 39993.6 40053.0 40089.4 7587.1 7761.0 8245.1 8328.1 8425.6 6906.3 8276.3 8394.4 8399.2 8408.3 8251.9 8365.0 8391.0 8403.9 8408.1 8280.1 8360.0 8376.1 8393.0 8400.3 8104.7 8150.9 8162.3 8195.7 8200.6 32920.2 34186.8 36883.6 36967.0 37142.9 3495.5 3518.0 3539.9 3542.4 3547.5 28156.7 28468.9 30541.7 30613.9 30686.7 3093.7 3123.8 3142.5 3144.5 3146.2 245990.3 246033.0 249107.0 300039.0 300708.5 46776.8 46881.5 47129.3 47190.0 47552.0 48180.8 48643.0 48812.8 50209.5 50818.7 14217.0 14270.0 14379.4 14389.1 14414.9 11352.3 11428.5 11547.0 11635.7 11666.6 11641.8 14176.3 14218.7 14240.5 14291.7 6310.1 6318.4 6352.7 6354.6 6355.1 863.6 965.4 984.3 1053.1 1057.2 24454.0 24784.6 24858.0 24897.8 24919.4 1113.6 1116.5 1140.9 1147.3 1151.7 9384.8 11045.7 11375.8 11384.3 12680.8 335235.0 336377.0 336546.0 336776.0 338591.0 54614.0 54950.9 55088.4 55205.7 55237.7 1498.9 1525.5 1532.6 1533.2 1550.5 23999.6 24152.3 24158.0 24397.4 24420.4 39131.0 39202.0 39256.0 39340.6 39424.6 242449.0 275161.0 281519.0 281631.0 282252.0 35964.6 36531.8 36736.8 36889.0 36942.8 43511.9 45144.0 46537.7 46568.3 46610.6 25917.9 30849.7 31092.8 31639.0 31675.8 30818.8 31106.5 31543.7 31675.2 32351.4 36988.8 38615.6 38621.0 38659.6 38726.8 30486.5 31114.9 31462.3 31665.8 32163.5 20462.8 22415.3 24750.3 24977.1 25022.5 19:42:22 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | [#2] jquery.html Cycles:180 Average:36017.96 Median:23445.03 stddev:96850.69 (413.1%) stddev-sans-first:97085.41 19:42:22 INFO - PID 1676 | Values: 376.4 410.6 412.6 423.8 433.4 20597.9 21041.2 21418.8 21450.5 21708.5 21914.7 22346.3 23306.7 23583.4 23657.5 5365.5 6093.8 6313.9 6355.6 6395.4 6164.0 6183.6 6303.1 6357.2 6359.2 6165.2 6209.6 6213.8 6225.1 6228.2 5999.8 6171.2 6195.8 6277.8 6315.9 5582.3 5585.6 5635.5 5635.7 5645.1 27008.3 27754.4 28046.2 28635.0 28688.7 2189.9 2247.1 2247.5 2254.8 2255.5 14397.9 18047.5 18543.5 18582.3 18655.9 1827.7 2148.9 2185.9 2190.6 2198.2 151508.2 156446.2 157635.0 158017.0 159515.2 12904.2 13058.1 13063.2 13270.7 13292.3 16788.9 16801.6 16806.2 16812.2 16841.0 8875.6 9016.4 9148.3 9274.6 9300.2 8680.5 8705.8 8719.2 8834.4 8846.5 6785.1 8106.9 8169.9 8217.3 8257.5 2256.9 2261.5 2273.5 2274.9 2416.4 943.6 967.8 971.5 978.3 1009.7 19981.4 20130.6 20529.9 21278.8 21392.8 833.3 1002.5 1029.4 1032.7 1049.0 10558.4 10838.7 10872.4 10918.8 10942.5 538853.0 576772.0 577901.0 586788.0 590217.0 101385.0 103259.9 124727.4 132165.0 134599.3 1078.1 1177.3 1188.4 1223.0 1227.2 21508.2 21521.9 21923.1 21946.8 22137.0 55128.7 56239.4 56360.7 56558.6 56837.7 64647.7 75322.2 77363.6 77912.0 78911.0 8831.1 8909.8 9038.1 9091.3 9109.4 13823.6 13870.8 14059.6 14081.8 14109.3 10721.8 11784.5 11950.9 12012.0 12046.5 9481.5 9574.8 9817.8 10787.7 11303.0 9731.4 9768.3 9963.9 11765.5 12097.8 8634.2 8711.6 8754.1 9098.4 9209.9 10880.5 11470.7 12036.4 12040.6 12175.1 19:42:22 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | [#3] mootools.html Cycles:180 Average:7139.43 Median:3633.38 stddev:4647.50 (127.9%) stddev-sans-first:4640.44 19:42:22 INFO - PID 1676 | Values: 1386.4 1439.9 1448.2 1451.4 1460.8 11708.0 11721.5 12248.0 12275.0 12276.4 10503.5 11830.3 12287.4 12291.4 12313.8 2518.5 2561.9 2562.7 2569.6 2573.6 2493.5 2529.3 2535.1 2536.6 2541.9 2491.3 2528.8 2530.7 2540.1 2540.6 2393.8 2405.1 2416.3 2421.9 2424.8 2288.2 2425.9 2442.1 2447.8 2459.8 11823.4 11864.6 11928.2 11929.3 11956.8 6539.1 6776.1 6781.6 6791.4 6795.3 4083.9 4249.4 4304.1 4314.1 4324.2 1180.8 1225.5 1226.3 1237.6 1247.2 16596.8 16736.0 16753.7 16895.9 17078.6 5038.3 5220.6 5544.8 5711.6 5726.6 11809.5 11811.5 11815.6 11836.4 11848.8 6414.7 6419.3 6461.9 6511.7 6525.7 6149.8 6231.8 6239.5 6266.5 6294.5 3641.2 3722.9 3888.4 4246.1 4346.5 1277.2 1283.3 1284.8 1296.8 1298.2 620.9 717.5 757.7 760.1 776.3 5842.4 5867.1 6058.9 6091.4 6259.9 583.5 585.5 585.8 601.8 603.9 3625.6 3717.3 3722.4 3725.5 3741.0 14564.6 15202.5 15425.3 15442.0 15462.8 15135.2 15222.9 15367.0 15374.9 15402.9 7560.7 8151.3 8381.9 8711.0 8741.4 5124.6 5850.3 6084.7 6270.8 6593.5 12858.0 12860.8 12970.3 13558.3 14033.9 14058.3 14146.9 14226.9 14235.9 14383.4 9268.8 9479.1 9626.5 9643.8 9653.2 11091.7 11179.9 11310.7 11361.1 11389.0 9167.3 9280.6 9368.8 9495.5 9553.1 9027.5 9119.5 9138.9 9144.5 9177.3 8211.9 8265.2 8308.1 8425.1 8493.9 6113.7 6127.6 6134.8 6171.0 6228.6 5603.0 5697.0 6147.1 6186.0 6233.7 19:42:22 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | [#4] prototype.html Cycles:180 Average:5439.84 Median:6114.47 stddev:1717.50 (28.1%) stddev-sans-first:1678.66 19:42:22 INFO - PID 1676 | Values: 313.3 383.4 400.4 406.7 408.3 5817.8 5951.3 6285.7 6379.0 6443.9 6100.9 6254.4 6261.5 6286.8 6303.5 4191.9 4797.2 4811.8 4837.9 4856.0 4202.5 4485.5 4567.6 4714.8 4730.4 4731.3 4753.2 4775.0 4780.7 4782.0 4808.4 4808.9 4809.7 4810.1 4831.5 3519.6 3635.4 4136.5 4165.7 4262.9 6086.8 6093.5 6111.2 6119.5 6138.9 5195.0 5714.7 5733.3 5760.6 5763.9 5580.9 6586.7 6591.6 6600.2 6618.0 4343.3 4395.2 4413.8 4426.8 4430.8 6744.7 6840.3 6949.4 6952.3 7121.6 4445.2 4454.0 4457.7 4460.0 4466.6 4182.9 4532.5 4542.6 4545.9 4555.8 4891.6 5172.8 5239.6 5417.2 5431.1 5804.8 6103.4 6301.8 6679.6 6699.5 1317.3 1388.6 1435.6 1545.2 1563.6 999.4 1010.7 1026.8 1030.5 1032.9 6136.7 6279.8 6299.4 6320.5 6326.5 6322.2 6920.3 6990.5 7014.4 7022.3 5376.3 6110.6 6342.5 6373.7 6435.7 3536.8 3664.6 3686.1 3687.3 3694.2 6583.7 7042.6 7181.2 7244.5 7249.1 6117.8 6971.2 7243.3 7257.7 7292.6 5401.6 7102.6 7110.9 7209.3 7275.8 6383.8 6559.1 6845.6 7019.5 7084.2 6281.0 6579.2 6895.4 6954.7 7067.4 5674.7 7095.6 7217.4 7277.2 7297.9 3743.9 4074.7 4203.3 4346.6 4458.5 5645.3 6940.7 7059.9 7077.6 7088.6 6201.2 6600.8 6728.6 6782.6 6814.9 6274.4 6772.0 6828.7 6831.3 6881.8 5496.9 5904.9 6419.4 6618.8 6658.8 6050.4 6419.8 6619.9 6819.2 6819.8 6560.5 6601.5 6617.8 6619.3 6681.2 19:42:22 INFO - PID 1676 | 19:42:22 INFO - PID 1676 | [#5] yui.html Cycles:180 Average:24768.58 Median:17661.82 stddev:37754.16 (213.8%) stddev-sans-first:37817.09 19:42:22 INFO - PID 1676 | Values: 773.9 827.1 837.4 864.0 875.3 16571.2 16974.2 17044.3 19347.6 19992.3 18142.3 18797.7 18931.5 18978.2 19126.7 959.2 998.9 1001.3 1002.0 1012.9 1009.9 1019.7 1022.3 1022.5 1029.0 1054.6 1078.4 1089.0 1095.1 1096.4 1062.0 1076.3 1083.9 1087.8 1087.9 803.9 804.2 806.1 806.5 808.2 14793.9 15223.6 15225.8 18097.5 18100.9 14559.9 17518.6 17765.2 17867.0 18068.8 14718.6 14862.6 14888.4 14891.3 14898.3 3363.4 3493.0 3558.2 3621.3 3653.0 133370.0 145428.0 146247.0 148840.3 150549.0 12519.6 12578.7 12595.9 12722.2 12734.4 23403.8 26553.3 26679.6 26687.7 27168.7 7846.0 7866.8 7871.0 7883.1 7890.3 3988.2 4001.5 4050.4 4058.9 4059.1 3743.7 4367.7 4379.1 4454.7 4506.3 864.0 884.7 894.3 897.9 899.5 648.2 653.4 667.3 670.8 677.3 9983.8 10468.7 10827.8 12077.9 12343.5 655.3 658.2 667.5 673.3 691.3 5661.9 5857.2 6091.7 6113.8 6634.3 138992.0 139580.0 141886.9 143409.0 144873.0 83232.0 102746.0 120163.0 122015.4 123548.0 70897.6 100570.0 100692.5 101284.5 102318.5 12446.7 12941.5 12956.5 12995.3 12995.5 44261.3 44678.8 45395.5 45864.6 46316.0 60395.1 60690.1 61263.4 61432.0 62573.7 16788.7 16810.5 17210.8 17301.2 17302.7 18633.5 18857.0 19020.3 19138.5 19214.6 14089.8 17019.8 17357.1 17530.7 17647.8 16021.4 16419.7 17563.8 17675.8 17691.6 15686.0 16695.1 17036.6 17205.4 17289.8 10993.3 12107.0 12192.5 12274.6 12375.5 10239.9 13205.6 13296.9 13324.7 13330.7 19:42:22 INFO - PID 1676 | -------- Summary: end -------- 19:42:22 INFO - PID 1676 | 19:42:23 INFO - TEST-INFO | 1676: exit 0 19:42:23 INFO - TEST-OK | dromaeo_css | took 1142109ms 19:42:23 INFO - TEST-START | kraken 19:42:23 INFO - operating with platform_type : mac_ 19:42:23 INFO - Initialising browser for kraken test... 19:42:23 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpBv8sSN/profile 19:42:23 INFO - TEST-INFO | started process 1795 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 19:42:56 INFO - TEST-INFO | 1795: killed by SIGTERM 19:42:56 INFO - Browser initialized. 19:42:56 INFO - Running cycle 1/1 for kraken test... 19:42:56 INFO - TEST-INFO | started process 1803 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/40/__rckyn52mngk9ww2dj8d78400000w/T/tmpBv8sSN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:42:58 INFO - PID 1803 | 2017-05-24 19:42:58.836 plugin-container[1804:11026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 19:42:58 INFO - PID 1803 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:42:58 INFO - PID 1803 | 2017-05-24 19:42:58.836 plugin-container[1804:11026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b1b, name = 'com.apple.CFPasteboardClient' 19:42:58 INFO - PID 1803 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:42:58 INFO - PID 1803 | 2017-05-24 19:42:58.836 plugin-container[1804:11026] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:42:59 INFO - PID 1803 | 2017-05-24 19:42:59.392 plugin-container[1805:11068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x974f, name = 'com.apple.tsm.portname' 19:42:59 INFO - PID 1803 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:42:59 INFO - PID 1803 | 2017-05-24 19:42:59.393 plugin-container[1805:11068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 19:42:59 INFO - PID 1803 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:42:59 INFO - PID 1803 | 2017-05-24 19:42:59.393 plugin-container[1805:11068] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:43:08 INFO - PID 1803 | RSS: Main: 246198272 19:43:08 INFO - PID 1803 | 19:43:11 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 19:43:12 INFO - PID 1803 | RSS: Main: 260087808 19:43:12 INFO - PID 1803 | 19:43:15 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 19:43:15 INFO - PID 1803 | RSS: Main: 269262848 19:43:15 INFO - PID 1803 | 19:43:19 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 19:43:19 INFO - PID 1803 | RSS: Main: 272252928 19:43:19 INFO - PID 1803 | 19:43:21 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 19:43:22 INFO - PID 1803 | RSS: Main: 273203200 19:43:22 INFO - PID 1803 | 19:43:24 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:43:24 INFO - PID 1803 | RSS: Main: 273698816 19:43:24 INFO - PID 1803 | 19:43:27 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 19:43:28 INFO - PID 1803 | RSS: Main: 278618112 19:43:28 INFO - PID 1803 | 19:43:31 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 19:43:32 INFO - PID 1803 | RSS: Main: 282914816 19:43:32 INFO - PID 1803 | 19:43:35 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 19:43:35 INFO - PID 1803 | RSS: Main: 281784320 19:43:35 INFO - PID 1803 | 19:43:37 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:43:38 INFO - PID 1803 | RSS: Main: 281681920 19:43:38 INFO - PID 1803 | 19:43:39 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:43:40 INFO - PID 1803 | RSS: Main: 281550848 19:43:40 INFO - PID 1803 | 19:43:42 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:43:43 INFO - PID 1803 | RSS: Main: 281477120 19:43:43 INFO - PID 1803 | 19:43:46 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:43:47 INFO - PID 1803 | RSS: Main: 281419776 19:43:47 INFO - PID 1803 | 19:43:49 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:43:50 INFO - PID 1803 | RSS: Main: 281362432 19:43:50 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 19:43:52 INFO - PID 1803 | RSS: Main: 283193344 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | __start_tp_report 19:43:52 INFO - PID 1803 | _x_x_mozilla_page_load 19:43:52 INFO - PID 1803 | _x_x_mozilla_page_load_details 19:43:52 INFO - PID 1803 | |i|pagename|runs| 19:43:52 INFO - PID 1803 | |0;ai-astar;99;106;166;170;169;119;120;149;112;115 19:43:52 INFO - PID 1803 | |1;audio-beat-detection;129;134;145;184;143;115;125;145;128;134 19:43:52 INFO - PID 1803 | |2;audio-dft;200;197;208;215;236;212;197;197;199;198 19:43:52 INFO - PID 1803 | |3;audio-fft;91;89;83;93;59;57;58;70;76;56 19:43:52 INFO - PID 1803 | |4;audio-oscillator;78;70;83;71;71;70;87;70;63;87 19:43:52 INFO - PID 1803 | |5;imaging-gaussian-blur;89;88;90;90;93;122;89;90;90;96 19:43:52 INFO - PID 1803 | |6;imaging-darkroom;101;156;144;98;102;97;96;96;97;95 19:43:52 INFO - PID 1803 | |7;imaging-desaturate;92;94;116;150;129;90;93;91;90;90 19:43:52 INFO - PID 1803 | |8;json-parse-financial;50;50;52;50;51;49;49;53;52;48 19:43:52 INFO - PID 1803 | |9;json-stringify-tinderbox;54;57;56;66;65;64;58;58;55;66 19:43:52 INFO - PID 1803 | |10;stanford-crypto-aes;87;87;83;104;103;110;116;77;128;114 19:43:52 INFO - PID 1803 | |11;stanford-crypto-ccm;138;135;137;137;140;150;158;161;154;136 19:43:52 INFO - PID 1803 | |12;stanford-crypto-pbkdf2;186;181;181;185;186;183;193;187;184;193 19:43:52 INFO - PID 1803 | |13;stanford-crypto-sha256-iterative;70;68;71;57;85;84;99;96;81;64 19:43:52 INFO - PID 1803 | __end_tp_report 19:43:52 INFO - PID 1803 | __start_cc_report 19:43:52 INFO - PID 1803 | _x_x_mozilla_cycle_collect,1064 19:43:52 INFO - PID 1803 | __end_cc_report 19:43:52 INFO - PID 1803 | __startTimestamp1495680232693__endTimestamp 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | ------- Summary: start ------- 19:43:52 INFO - PID 1803 | Number of tests: 14 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#0] ai-astar Cycles:10 Average:132.50 Median:157.50 stddev:27.93 (17.7%) stddev-sans-first:26.87 19:43:52 INFO - PID 1803 | Values: 99.0 106.0 166.0 170.0 169.0 119.0 120.0 149.0 112.0 115.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:138.50 stddev:18.71 (13.5%) stddev-sans-first:19.54 19:43:52 INFO - PID 1803 | Values: 129.0 134.0 145.0 184.0 143.0 115.0 125.0 145.0 128.0 134.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#2] audio-dft Cycles:10 Average:205.90 Median:204.00 stddev:12.53 (6.1%) stddev-sans-first:13.11 19:43:52 INFO - PID 1803 | Values: 200.0 197.0 208.0 215.0 236.0 212.0 197.0 197.0 199.0 198.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#3] audio-fft Cycles:10 Average:73.20 Median:79.50 stddev:15.14 (19.0%) stddev-sans-first:14.63 19:43:52 INFO - PID 1803 | Values: 91.0 89.0 83.0 93.0 59.0 57.0 58.0 70.0 76.0 56.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:74.50 stddev:8.25 (11.1%) stddev-sans-first:8.67 19:43:52 INFO - PID 1803 | Values: 78.0 70.0 83.0 71.0 71.0 70.0 87.0 70.0 63.0 87.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:90.00 stddev:10.21 (11.3%) stddev-sans-first:10.69 19:43:52 INFO - PID 1803 | Values: 89.0 88.0 90.0 90.0 93.0 122.0 89.0 90.0 90.0 96.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:96.00 stddev:22.32 (23.2%) stddev-sans-first:23.52 19:43:52 INFO - PID 1803 | Values: 101.0 156.0 144.0 98.0 102.0 97.0 96.0 96.0 97.0 95.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#7] imaging-desaturate Cycles:10 Average:103.50 Median:90.50 stddev:21.09 (23.3%) stddev-sans-first:21.96 19:43:52 INFO - PID 1803 | Values: 92.0 94.0 116.0 150.0 129.0 90.0 93.0 91.0 90.0 90.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.50 stddev:1.58 (3.1%) stddev-sans-first:1.67 19:43:52 INFO - PID 1803 | Values: 50.0 50.0 52.0 50.0 51.0 49.0 49.0 53.0 52.0 48.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:61.00 stddev:4.79 (7.9%) stddev-sans-first:4.59 19:43:52 INFO - PID 1803 | Values: 54.0 57.0 56.0 66.0 65.0 64.0 58.0 58.0 55.0 66.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#10] stanford-crypto-aes Cycles:10 Average:100.90 Median:102.50 stddev:16.70 (16.3%) stddev-sans-first:16.93 19:43:52 INFO - PID 1803 | Values: 87.0 87.0 83.0 104.0 103.0 110.0 116.0 77.0 128.0 114.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#11] stanford-crypto-ccm Cycles:10 Average:144.60 Median:145.00 stddev:10.07 (6.9%) stddev-sans-first:10.39 19:43:52 INFO - PID 1803 | Values: 138.0 135.0 137.0 137.0 140.0 150.0 158.0 161.0 154.0 136.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.90 Median:186.00 stddev:4.25 (2.3%) stddev-sans-first:4.51 19:43:52 INFO - PID 1803 | Values: 186.0 181.0 181.0 185.0 186.0 183.0 193.0 187.0 184.0 193.0 19:43:52 INFO - PID 1803 | 19:43:52 INFO - PID 1803 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.50 Median:82.50 stddev:13.77 (16.7%) stddev-sans-first:14.34 19:43:52 INFO - PID 1803 | Values: 70.0 68.0 71.0 57.0 85.0 84.0 99.0 96.0 81.0 64.0 19:43:52 INFO - PID 1803 | -------- Summary: end -------- 19:43:52 INFO - PID 1803 | 19:43:53 INFO - TEST-INFO | 1803: exit 0 19:43:53 INFO - TEST-OK | kraken | took 90140ms 19:43:53 INFO - SUITE-END | took 1232s 19:43:53 INFO - Completed test suite (00:20:32) 19:43:53 INFO - javascript benchmark 19:43:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7957.285655583061, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3156.4476216662088, 3588.7667301625374, 3617.8733744318956, 3627.9637203627963, 3647.1429214134673, 4790.928136077957, 5858.619189752663, 6200.472959798422, 6218.502519798417, 6277.717502712378, 6201.875962480759, 6222.875542489159, 6268.780592679257, 6284.780032698856, 6308.085327627503, 5011.348524691785, 5258.815941442051, 5378.381486129087, 5439.48324909133, 5450.0, 5525.143602741581, 5528.81980770188, 5531.640443371188, 5543.085390910495, 5569.554435645159, 5323.494268044529, 5403.621746477709, 5441.455854414567, 5448.182772584105, 5459.481349271813, 5459.208414779874, 5463.863403414906, 5495.422980587021, 5496.285482887239, 5524.447555244484, 5377.059014672417, 5422.864428389283, 5428.45715428454, 5433.83698489042, 5465.754041068161, 6176.660283684396, 6251.718672659696, 6305.9369406305805, 6324.272708638519, 6333.366663333676, 6481.4166724994975, 6590.5716128451495, 6702.128723265993, 6716.361945615159, 6732.83167920801, 6810.557313774589, 6827.249002609709, 6830.975353696936, 6843.349881761224, 6869.107016087876, 6206.720697568575, 6363.7454501819875, 6380.904286435707, 6393.968030159819, 6412.967935160387, 5671.773129074873, 6733.528652994243, 6823.044773731682, 6919.37725604698, 6967.303269673093, 6361.045843123476, 6420.0, 6479.416852483299, 6543.770968016192, 6589.209294884645, 6707.765228216989, 6780.254172041072, 6781.559198652072, 6796.082528858639, 6824.761133360308, 6639.3692599203, 6647.335266473314, 6647.60114393138, 6652.434543063797, 6684.565503242274, 6095.664738439428, 6401.455876250478, 6469.353064693493, 6651.900221496681, 6690.69891854858, 5321.6540924839765, 5355.544778693855, 5369.463053694677, 5373.2208829719475, 5404.594655400782, 4760.428748550127, 4762.690425122425, 4771.570558649738, 4777.378940737682, 4815.807367705253, 6056.031035034373, 6105.9694701526205, 6302.873942521124, 6339.429451349401, 6397.616143031433, 6741.898871516931, 6815.522913396114, 6822.283660215705, 6832.975053741979, 6875.278095799969, 6356.110144579762, 6390.648514331663, 6391.456726178234, 6395.008773640093, 6436.066770318278, 5757.136429535603, 5798.710064496842, 5918.408159184133, 5931.436513531207, 5943.049112142036, 6033.396660333931, 6887.414569761526, 6947.826304342432, 6978.581285122909, 6997.300269973166, 6729.158855143107, 6799.456043516606, 6820.7271709133065, 6822.488313376417, 6831.043653888559, 6940.375366217065, 6977.476689248429, 7008.264132266038, 7017.333353331425, 7026.859462810613, 5368.32895888014, 6400.871982560416, 6461.515386345949, 6620.337966203341, 6667.166604174478, 6720.563163394364, 6773.119494465885, 6786.93213067863, 6808.557443766139, 6837.62393068386, 6202.72087756043, 6344.651044192614, 6366.395192456709, 6511.576747511396, 6535.901961470487, 6417.614943103429, 6523.3476652336285, 6540.084388185563, 6543.672816359068, 6551.967240163769, 6326.051092336186, 6329.620222786648, 6365.108884756045, 6375.936240637534, 6416.839579010562, 6460.998545225498, 6472.805815825533, 6497.18785151856, 6533.118029066015, 6543.378379053982, 6373.203349581302, 6375.979843225062, 6391.616503009835, 6392.5844820088405, 6394.328595497405], "value": 6108.503479666093, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.300926504008, 792.5680504125253, 810.772983564602, 1209.6129238643632, 1274.5093139141427, 38521.036974075665, 39442.80278598607, 39447.802760986226, 39504.0, 39548.60451395485, 39511.0, 39975.80012099935, 39993.60006399935, 40053.0, 40089.39865902014, 7587.127480339749, 7760.961195194016, 8245.093039765621, 8328.08391076981, 8425.578721063954, 6906.274841141683, 8276.296514796219, 8394.412391132622, 8399.160083991583, 8408.285295749869, 8251.876221856677, 8365.0, 8391.0, 8403.915960840375, 8408.117147699495, 8280.089190189074, 8359.95820020899, 8376.120507346732, 8392.992840859106, 8400.327973762145, 8104.6758129674745, 8150.9184908150755, 8162.347012239006, 8195.71315003972, 8200.630971606231, 32920.176995575304, 34186.80346187896, 36883.63116368856, 36967.0, 37142.88571342837, 3495.5106285120105, 3518.0149558123767, 3539.929201415983, 3542.433210686278, 3547.5210846535647, 28156.71843281582, 28468.861245550153, 30541.69458305433, 30613.928512501956, 30686.69313306883, 3093.706097920694, 3123.8281894495794, 3142.4814905540557, 3144.5440411140257, 3146.2134466383404, 245990.3101453462, 246033.0, 249107.0, 300039.0, 300708.4964575185, 46776.7661161692, 46881.531184687716, 47129.29306060412, 47190.0, 47552.0, 48180.759096204296, 48643.0, 48812.75593622009, 50209.49790502121, 50818.74590627023, 14217.004809663224, 14270.001099923113, 14379.352929118215, 14389.064710793766, 14414.927925360305, 11352.318860868209, 11428.542858285742, 11547.037003709389, 11635.709107272216, 11666.591669291534, 11641.825372619418, 14176.291185440687, 14218.715625687635, 14240.501582444565, 14291.714165716627, 6310.0850376695125, 6318.368163183645, 6352.6823658816875, 6354.633753742967, 6355.093471958487, 863.5811631358786, 965.3918031640162, 984.2765567308023, 1053.099599842124, 1057.1648397765832, 24454.0, 24784.628230576556, 24858.005679772967, 24897.751022489905, 24919.377015574755, 1113.5768408004906, 1116.4919961417536, 1140.8745038045809, 1147.2714826085496, 1151.694800877768, 9384.812303753886, 11045.729741079718, 11375.829362559569, 11384.257232679334, 12680.80978785319, 335235.0, 336377.0, 336546.0, 336776.0, 338591.0, 54614.0, 54950.900981980136, 55088.44911550833, 55205.72397138069, 55237.72381138149, 1498.9250537473301, 1525.534711912855, 1532.6245069957931, 1533.2027345780132, 1550.4650895441055, 23999.60003999586, 24152.275431737075, 24158.033678652657, 24397.39006524851, 24420.389490262885, 39131.0, 39202.0, 39256.01859953524, 39340.60659393484, 39424.605753943244, 242449.0, 275161.0, 281519.0, 281631.0, 282252.0, 35964.64035359718, 36531.81734091419, 36736.81631591825, 36889.0, 36942.815285923396, 43511.9122021952, 45144.0, 46537.67311634337, 46568.30147547857, 46610.60168194959, 25917.870410647825, 30849.691503084683, 31092.844535777178, 31639.0, 31675.841620791747, 30818.84590577033, 31106.533401998535, 31543.684563154075, 31675.20811979627, 32351.35297294088, 36988.81505592455, 38615.648452304034, 38621.034474138374, 38659.6134038656, 38726.80636596799, 30486.542701859045, 31114.91097811566, 31462.291738524207, 31665.8416707915, 32163.51754723634, 20462.79537204609, 22415.32754017382, 24750.25749227526, 24977.125800596892, 25022.499550009263], "value": 17527.50211682316, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.3958846052086, 410.63658662084066, 412.6265729514788, 423.84953341563727, 433.4278752047298, 20597.897010514946, 21041.158353665873, 21418.785812141872, 21450.463738406546, 21708.457288567795, 21914.671279930775, 22346.329610111723, 23306.65040024401, 23583.41041473964, 23657.526849463044, 5365.517103460687, 6093.817185484431, 6313.873722525546, 6355.586886852363, 6395.392437718409, 6163.982942814453, 6183.567150299481, 6303.117563541108, 6357.173567436227, 6359.173307470046, 6165.198524191849, 6209.627422354651, 6213.751449941997, 6225.097360882669, 6228.19033525641, 5999.790007349744, 6171.166892469527, 6195.783147589836, 6277.843053923642, 6315.8736825263695, 5582.330120385539, 5585.553155747531, 5635.549156067505, 5635.6618602883955, 5645.096784514458, 27008.324791880164, 27754.444911101666, 28046.158615241577, 28635.0, 28688.709008094687, 2189.9233526826483, 2247.1348530815753, 2247.4606094537357, 2254.7970682638643, 2255.4699645583273, 14397.856021439757, 18047.548811279692, 18543.516518678472, 18582.256709731595, 18655.90672046631, 1827.7349784281337, 2148.871067735941, 2185.868847869133, 2190.627593309141, 2198.1756841184556, 151508.242458787, 156446.21776891043, 157635.0, 158017.0, 159515.20242398712, 12904.16122952005, 13058.08593398472, 13063.216207027608, 13270.66823329405, 13292.268925209013, 16788.916055419646, 16801.579960501087, 16806.15969201535, 16812.24344904482, 16840.9895406276, 8875.644974201088, 9016.41393309446, 9148.313876459291, 9274.582643781046, 9300.162985331353, 8680.522571258514, 8705.825883482295, 8719.215270625675, 8834.425762325429, 8846.46921184731, 6785.132474489494, 8106.918930810734, 8169.936273897289, 8217.34261259098, 8257.545834979133, 2256.887155642244, 2261.502469456717, 2273.5111950930627, 2274.8521346112448, 2416.383822125382, 943.551812888886, 967.849983252597, 971.455984648599, 978.280963491833, 1009.6617633092997, 19981.400557983285, 20130.597388052156, 20529.89735051345, 21278.829664368608, 21392.75323463101, 833.3000279764906, 1002.4887307473095, 1029.4286670897723, 1032.7056788815153, 1048.9947550262352, 10558.419286939139, 10838.729031774268, 10872.402017888933, 10918.781624367468, 10942.507587158438, 538853.0, 576772.0, 577901.0, 586788.0, 590217.0, 101385.0, 103259.93480130355, 124727.3763631176, 132165.0, 134599.3270033663, 1078.1374900079904, 1177.3348058347005, 1188.3761025461529, 1222.9877701222874, 1227.1655274413306, 21508.247211347527, 21521.89239053795, 21923.12307507682, 21946.780532194472, 22137.00383482715, 55128.72435637796, 56239.43760562342, 56360.71819640876, 56558.58603534944, 56837.715811420676, 64647.67676161589, 75322.24677753152, 77363.61318193373, 77912.0, 78911.0, 8831.072737362485, 8909.821803564022, 9038.051004644418, 9091.272698183997, 9109.407888487227, 13823.585292441243, 13870.791938121138, 14059.578212653636, 14081.788773168615, 14109.2945352734, 10721.839172412265, 11784.469698863742, 11950.940245299065, 12011.978981786646, 12046.51813927433, 9481.478518681539, 9574.808503830023, 9817.803643926938, 10787.730306742395, 11303.0, 9731.41611503312, 9768.313713728781, 9963.850542242017, 11765.52937882475, 12097.818532722193, 8634.222919937236, 8711.607977640895, 8754.124587541195, 9098.408603440756, 9209.907900920905, 10880.531128297578, 11470.713232169262, 12036.398180091135, 12040.578579749666, 12175.147739658492], "value": 10468.49893548282, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.417704564083, 1439.8920080993928, 1448.2034880815556, 1451.3904160252694, 1460.846611105834, 11708.004819590335, 11721.472533735981, 12247.958923491502, 12274.956628686572, 12276.447559859806, 10503.474826258676, 11830.349330786803, 12287.38563071845, 12291.385430728451, 12313.815292770616, 2518.4837108392826, 2561.9487610247784, 2562.7437256274316, 2569.6145578163237, 2573.562494375961, 2493.5012997400504, 2529.2791554407, 2535.1000394859834, 2536.5941449368106, 2541.8601976891264, 2491.302435318105, 2528.8482691038503, 2530.6710127683377, 2540.0855691950883, 2540.5808041673104, 2393.8204634652297, 2405.122130422401, 2416.2871952773976, 2421.8910149043336, 2424.781769640732, 2288.1762565476415, 2425.927222183337, 2442.096424322994, 2447.8041756659604, 2459.827812053157, 11823.408829558573, 11864.644060678194, 11928.224665396721, 11929.28424294545, 11956.760864782742, 6539.08452816606, 6776.085228494188, 6781.559198652072, 6791.388775010273, 6795.320467953215, 4083.8774836754646, 4249.405083288342, 4304.096139810644, 4314.115606300701, 4324.200022995725, 1180.752042071166, 1225.5097960815656, 1226.2887525235342, 1237.5730373021293, 1247.2204871955028, 16596.751048734277, 16736.0, 16753.66492670152, 16895.91552042232, 17078.573035674082, 5038.345015148007, 5220.608454365862, 5544.77820887168, 5711.600187986801, 5726.570507211975, 11809.468573914022, 11811.468483918245, 11815.645530634094, 11836.408179590986, 11848.822267665992, 6414.711337989802, 6419.261784894704, 6461.870762584722, 6511.674416279167, 6525.673716314165, 6149.84625384369, 6231.813045608548, 6239.500839932794, 6266.49868010558, 6294.496440284858, 3641.198936234024, 3722.925541489209, 3888.4361767543546, 4246.129543443587, 4346.521882592912, 1277.1889849945167, 1283.3006011723633, 1284.8137020132028, 1296.7730647136714, 1298.2210673595955, 620.92548894133, 717.4834119433999, 757.7461550380574, 760.1220590218306, 776.3401109057369, 5842.357340692521, 5867.119932010232, 6058.939410605925, 6091.390860913873, 6259.906101408483, 583.5419195931173, 585.4526018172937, 585.771549095853, 601.8444586394133, 603.8943184942617, 3625.5649322081517, 3717.312297225022, 3722.3858063419775, 3725.4784330193793, 3741.0460332615007, 14564.63588410277, 15202.46791362297, 15425.305861236271, 15442.0, 15462.84537154614, 15135.243237838064, 15222.923885380502, 15367.001144925543, 15374.9231253843, 15402.921795474209, 7560.735374261874, 8151.30713889326, 8381.874271885927, 8711.0, 8741.431806932656, 5124.564412024871, 5850.327212370632, 6084.665343406155, 6270.777198446259, 6593.505487088391, 12858.0, 12860.807087893876, 12970.28663423483, 13558.322083895568, 14033.929830351191, 14058.282652404529, 14146.858531414966, 14226.928865355607, 14235.857641423867, 14383.42466301378, 9268.814623707352, 9479.146876781122, 9626.518674066408, 9643.807123857623, 9653.227741780782, 11091.667249982513, 11179.944100279447, 11310.660680179608, 11361.147913906325, 11389.0, 9167.312451566026, 9280.58237379306, 9368.81262374735, 9495.477748723886, 9553.092456216647, 9027.45835249887, 9119.452832830051, 9138.862917056116, 9144.451332920045, 9177.311701622539, 8211.917880821115, 8265.214804593554, 8308.12764659702, 8425.115362886809, 8493.915060849313, 6113.694315284307, 6127.632342059491, 6134.8159555213415, 6170.969145154425, 6228.626282423069, 5602.9719851402115, 5696.971515142398, 6147.139400483847, 6186.0, 6233.688315584112], "value": 5285.756591517547, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.2638299995012, 383.41337753237536, 400.39340399295054, 406.68888300450163, 408.2834625232716, 5817.825465236039, 5951.315598706145, 6285.6857157142185, 6379.0, 6443.935560644392, 6100.938990610103, 6254.43710066094, 6261.530385221104, 6286.8113956581265, 6303.464205542523, 4191.853285135021, 4797.18447863864, 4811.786578174044, 4837.879053023667, 4855.951440485585, 4202.474690663667, 4485.506594274627, 4567.634589232854, 4714.834980775673, 4730.432348118231, 4731.313959475874, 4753.239481682931, 4774.952250477503, 4780.665353425262, 4781.976090119544, 4808.447028591721, 4808.903821923577, 4809.663323567352, 4810.11012962603, 4831.4926932671915, 3519.595246546629, 3635.363811333006, 4136.476749182996, 4165.687573431975, 4262.936055959163, 6086.817395478142, 6093.512518998514, 6111.174991376151, 6119.479844213247, 6138.907916381258, 5195.038917800219, 5714.685692286923, 5733.312002559678, 5760.567957403169, 5763.855903602402, 5580.9117222141695, 6586.670666466657, 6591.571547849375, 6600.2079750429775, 6617.963042544045, 4343.283358245885, 4395.1868904252815, 4413.801378937956, 4426.756528390937, 4430.756308403036, 6744.673087784707, 6840.263894442213, 6949.409300209438, 6952.261934516371, 7121.643917804089, 4445.155420470101, 4454.0, 4457.665675074382, 4460.0, 4466.64266858657, 4182.937255941163, 4532.456105267389, 4542.568455996675, 4545.931811022837, 4555.794989225493, 4891.584215341736, 5172.844814655567, 5239.6376941994595, 5417.160340147205, 5431.099115309689, 5804.765165411347, 6103.42017508336, 6301.802657495061, 6679.632620205935, 6699.531032827753, 1317.3479127831786, 1388.590365842079, 1435.6339133521094, 1545.1501674079211, 1563.5856498028031, 999.4009584664478, 1010.6563768318807, 1026.7832618347281, 1030.4594631026664, 1032.8605638238741, 6136.662483563447, 6279.811605651837, 6299.370062993664, 6320.494360451153, 6326.493880489642, 6322.241331040304, 6920.30796920314, 6990.475714321345, 7014.403775679022, 7022.297770223039, 5376.274202982548, 6110.602810817282, 6342.5243106767175, 6373.681315934277, 6435.74257029723, 3536.797488853802, 3664.633536646314, 3686.09690625793, 3687.3178461984617, 3694.224212915264, 6583.703733332011, 7042.647867606599, 7181.210066892638, 7244.45666575009, 7249.05762250904, 6117.755289788448, 6971.233164351917, 7243.348098671145, 7257.709691612276, 7292.562446253241, 5401.594880383908, 7102.609356485443, 7110.857782844314, 7209.315115064061, 7275.781726548212, 6383.7765678202895, 6559.147310849559, 6845.5550389224545, 7019.508634395747, 7084.185318688416, 6280.9685951569945, 6579.197186054112, 6895.4138898194215, 6954.652267386509, 7067.363937245467, 5674.687892165805, 7095.58750024994, 7217.422606191387, 7277.235890231661, 7297.927020729725, 3743.9064023399633, 4074.7351422157467, 4203.327467605169, 4346.6088052075465, 4458.509563947899, 5645.322561292671, 6940.6748739172945, 7059.929400705927, 7077.646117693992, 7088.645567721696, 6201.224846894138, 6600.768973085919, 6728.562643428161, 6782.593044417351, 6814.8637027260165, 6274.435300822765, 6772.0, 6828.692708828214, 6831.316868313129, 6881.793546193622, 5496.890062198654, 5904.881902362014, 6419.390157934989, 6618.768343107968, 6658.83352916181, 6050.425209605257, 6419.775307864389, 6619.867602647823, 6819.2498825128205, 6819.795406137824, 6560.475161987125, 6601.471882249504, 6617.7683781067435, 6619.3380661933425, 6681.231658359351], "value": 4888.087972427303, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [773.895524104246, 827.0736774812203, 837.4221786966989, 863.9870401943969, 875.3434923807145, 16571.17144142794, 16974.236159372827, 17044.31822727091, 19347.613047739036, 19992.30026949057, 18142.274309027624, 18797.718034229496, 18931.52671183221, 18978.240870365204, 19126.71309930352, 959.1751094059103, 998.8701468809045, 1001.2690735762898, 1002.0380434782618, 1012.8733908261468, 1009.9091081802636, 1019.7195771162916, 1022.3354819367396, 1022.5143057047917, 1028.9536970836293, 1054.557086023868, 1078.3691540448847, 1088.9782204355909, 1095.1457862866976, 1096.358630201329, 1061.9522121504551, 1076.332673742277, 1083.8970297821734, 1087.782443511297, 1087.9184061195442, 803.9075506316732, 804.2158895077312, 806.0971711682953, 806.5120602035743, 808.1958451340895, 14793.92603036978, 15223.619409514851, 15225.771613425808, 18097.54756131094, 18100.90949545257, 14559.854401455956, 17518.649627007388, 17765.200565974563, 17867.017314047724, 18068.819311806845, 14718.632034199123, 14862.554123376316, 14888.404463821435, 14891.255437228096, 14898.329575169144, 3363.411403004489, 3492.9650703492894, 3558.1638314995957, 3621.2757448510138, 3653.0319465341704, 133370.0, 145428.0, 146247.0, 148840.25579872032, 150549.0, 12519.56181533642, 12578.68553286157, 12595.93702031484, 12722.236665800081, 12734.426950787052, 23403.765962340498, 26553.336166595604, 26679.599806002927, 26687.733122668913, 27168.72831271662, 7846.0, 7866.842663146705, 7871.0, 7883.132855385903, 7890.250426209501, 3988.16248587794, 4001.5398229204015, 4050.3721923101966, 4058.898527536835, 4059.1475790083823, 3743.7192210584317, 4367.71609845359, 4379.1241751649795, 4454.688171828006, 4506.3465797459185, 863.9740807775777, 884.7345796261096, 894.2622336572354, 897.9012308646044, 899.469313105271, 648.1509222917992, 653.4282502810041, 667.3393340592778, 670.7551743613643, 677.3497442455298, 9983.800323993479, 10468.68593942183, 10827.83758243627, 12077.879221207675, 12343.506259749509, 655.2661019657918, 658.2200092889941, 667.5494041521953, 673.3233100733731, 691.339770519153, 5661.915071273934, 5857.179994800731, 6091.66495842724, 6113.786017489367, 6634.336566343327, 138992.0, 139580.0, 141886.87169692462, 143409.0, 144873.0, 83232.0, 102745.97254027514, 120163.0, 122015.38992304982, 123548.0, 70897.6455117721, 100570.0, 100692.49653751684, 101284.49357753017, 102318.48840755748, 12446.688832778891, 12941.54704585335, 12956.546520871725, 12995.28525931045, 12995.48018079304, 44261.33607995818, 44678.776606116764, 45395.54604453913, 45864.62406127821, 46316.0, 60395.09407358805, 60690.08964865619, 61263.38736612577, 61432.0, 62573.68713156405, 16788.748168777234, 16810.495685129466, 17210.82789172092, 17301.221445035255, 17302.74045889338, 18633.534161645526, 18857.0, 19020.334288299844, 19138.52153696169, 19214.615707686047, 14089.788653170415, 17019.829801701824, 17357.132143392526, 17530.73703894468, 17647.82352176513, 16021.439249626206, 16419.671606567565, 17563.82436175673, 17675.82324176742, 17691.638588668113, 15685.980411273687, 16695.08177050274, 17036.574085647957, 17205.397811076553, 17289.827101729326, 10993.340399576051, 12106.970907472964, 12192.512299508275, 12274.631761047183, 12375.504979801068, 10239.897601023895, 13205.603831885059, 13296.86703132995, 13324.666883327995, 13330.666733331356], "value": 8765.441841197791, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.4000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 106.0, 166.0, 170.0, 169.0, 119.0, 120.0, 149.0, 112.0, 115.0], "value": 132.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 134.0, 145.0, 184.0, 143.0, 115.0, 125.0, 145.0, 128.0, 134.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 197.0, 208.0, 215.0, 236.0, 212.0, 197.0, 197.0, 199.0, 198.0], "value": 205.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 83.0, 93.0, 59.0, 57.0, 58.0, 70.0, 76.0, 56.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 70.0, 83.0, 71.0, 71.0, 70.0, 87.0, 70.0, 63.0, 87.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 90.0, 93.0, 122.0, 89.0, 90.0, 90.0, 96.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 156.0, 144.0, 98.0, 102.0, 97.0, 96.0, 96.0, 97.0, 95.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 116.0, 150.0, 129.0, 90.0, 93.0, 91.0, 90.0, 90.0], "value": 103.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 50.0, 51.0, 49.0, 49.0, 53.0, 52.0, 48.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 56.0, 66.0, 65.0, 64.0, 58.0, 58.0, 55.0, 66.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 83.0, 104.0, 103.0, 110.0, 116.0, 77.0, 128.0, 114.0], "value": 100.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 137.0, 137.0, 140.0, 150.0, 158.0, 161.0, 154.0, 136.0], "value": 144.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 181.0, 181.0, 185.0, 186.0, 183.0, 193.0, 187.0, 184.0, 193.0], "value": 185.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 71.0, 57.0, 85.0, 84.0, 99.0, 96.0, 81.0, 64.0], "value": 77.5, "unit": "score"}]}]} 19:43:53 INFO - Return code: 0 19:43:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:43:54 INFO - # TBPL SUCCESS # 19:43:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:43:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:43:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:43:54 INFO - 2017-05-24 19:43:54.029 screenresolution[1815:11453] starting screenresolution argv=screenresolution get 19:43:54 INFO - 2017-05-24 19:43:54.032 screenresolution[1815:11453] Display 0: 1600x1200x32@60 19:43:54 INFO - 2017-05-24 19:43:54.038 screenresolution[1816:11457] starting screenresolution argv=screenresolution list 19:43:54 INFO - Available Modes on Display 0 19:43:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:43:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:43:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:43:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:43:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:43:54 INFO - Intel Iris: 19:43:54 INFO - Chipset Model: Intel Iris 19:43:54 INFO - Type: GPU 19:43:54 INFO - Bus: Built-In 19:43:54 INFO - VRAM (Dynamic, Max): 1536 MB 19:43:54 INFO - Vendor: Intel (0x8086) 19:43:54 INFO - Device ID: 0x0a2e 19:43:54 INFO - Revision ID: 0x0009 19:43:54 INFO - Displays: 19:43:54 INFO - Display: 19:43:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:43:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:43:54 INFO - Display Serial Number: 1600x1200 60 19:43:54 INFO - Main Display: Yes 19:43:54 INFO - Mirror: Off 19:43:54 INFO - Online: Yes 19:43:54 INFO - Rotation: Supported 19:43:54 INFO - Return code: 0 19:43:54 INFO - Running post-action listener: _resource_record_post_action 19:43:54 INFO - [mozharness: 2017-05-25 02:43:54.273141Z] Finished run-tests step (success) 19:43:54 INFO - Running post-run listener: _resource_record_post_run 19:43:54 INFO - Total resource usage - Wall time: 1255s; CPU: 53.0%; Read bytes: 41421824; Write bytes: 568409088; Read time: 281; Write time: 1812 19:43:54 INFO - TinderboxPrint: CPU usage
53.5% 19:43:54 INFO - TinderboxPrint: I/O read bytes / time
41,421,824 / 281 19:43:54 INFO - TinderboxPrint: I/O write bytes / time
568,409,088 / 1,812 19:43:54 INFO - TinderboxPrint: CPU idle
2,335.5 (46.5%) 19:43:54 INFO - TinderboxPrint: CPU system
1,022.5 (20.4%) 19:43:54 INFO - TinderboxPrint: CPU user
1,663.6 (33.1%) 19:43:54 INFO - TinderboxPrint: Swap in / out
283,713,536 / 0 19:43:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228844032; Write bytes: 229430784; Read time: 17031; Write time: 481 19:43:54 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 40348672; Write bytes: 320534016; Read time: 261; Write time: 1281 19:43:54 INFO - Running post-run listener: _upload_blobber_files 19:43:54 INFO - Blob upload gear active. 19:43:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:43:54 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 19:43:54 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'] 19:43:54 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 19:43:55 INFO - (blobuploader) - INFO - Open directory for files ... 19:43:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:43:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:43:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:56 INFO - (blobuploader) - INFO - Done attempting. 19:43:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:43:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:43:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:57 INFO - (blobuploader) - INFO - Done attempting. 19:43:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:43:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:43:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:43:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:43:58 INFO - (blobuploader) - INFO - Done attempting. 19:43:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:43:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:43:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:44:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:44:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:44:01 INFO - (blobuploader) - INFO - Done attempting. 19:44:01 INFO - (blobuploader) - INFO - Iteration through files over. 19:44:01 INFO - Return code: 0 19:44:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:44:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:44:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f224b6f3c495cf7af61af0f1971ab09133bd56eb071aa5db0315fee98425bab330a175e84ad21a65c86e1814869010ed5f01047c440e85efb2a6b4a31f788f4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4da387fba1c54749b2d6c66cdd3f87735fefafc641ef55428946dff35599762afe1425fcb8f89455c8524afd4073a551e5f7aacf91c6b91e324913375aac2f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d431364dda536265cbdda09853bfa5fd7311e1f9dd2729a200e945721afa568edfcd9189a1d6c09543f7d3049f02e197a56fb1282288dde52d99cce3c84ae81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9aa1d7ca4862764299d7870197c43029a053603050539bebd65e46c317085f7c70c3c5255fe96e21ab8828a8726af7550c2c193ce9740aa30082b86dbcb5277"} 19:44:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:44:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:44:01 INFO - Contents: 19:44:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6f224b6f3c495cf7af61af0f1971ab09133bd56eb071aa5db0315fee98425bab330a175e84ad21a65c86e1814869010ed5f01047c440e85efb2a6b4a31f788f4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4da387fba1c54749b2d6c66cdd3f87735fefafc641ef55428946dff35599762afe1425fcb8f89455c8524afd4073a551e5f7aacf91c6b91e324913375aac2f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d431364dda536265cbdda09853bfa5fd7311e1f9dd2729a200e945721afa568edfcd9189a1d6c09543f7d3049f02e197a56fb1282288dde52d99cce3c84ae81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9aa1d7ca4862764299d7870197c43029a053603050539bebd65e46c317085f7c70c3c5255fe96e21ab8828a8726af7550c2c193ce9740aa30082b86dbcb5277"} 19:44:01 INFO - Running post-run listener: copy_logs_to_upload_dir 19:44:01 INFO - Copying logs to upload dir... 19:44:01 INFO - mkdir: /builds/slave/test/build/upload/logs 19:44:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1337.275779 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-05-24 19:44:01.921894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 19:44:01.925779) ========= 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.afgFfLpwV6/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.NslJiPfwua/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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/6f224b6f3c495cf7af61af0f1971ab09133bd56eb071aa5db0315fee98425bab330a175e84ad21a65c86e1814869010ed5f01047c440e85efb2a6b4a31f788f4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4da387fba1c54749b2d6c66cdd3f87735fefafc641ef55428946dff35599762afe1425fcb8f89455c8524afd4073a551e5f7aacf91c6b91e324913375aac2f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d431364dda536265cbdda09853bfa5fd7311e1f9dd2729a200e945721afa568edfcd9189a1d6c09543f7d3049f02e197a56fb1282288dde52d99cce3c84ae81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9aa1d7ca4862764299d7870197c43029a053603050539bebd65e46c317085f7c70c3c5255fe96e21ab8828a8726af7550c2c193ce9740aa30082b86dbcb5277"} build_url:https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011054 build_url: 'https://queue.taskcluster.net/v1/task/XDJj8RO-SfmikqU4UG5mSA/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/6f224b6f3c495cf7af61af0f1971ab09133bd56eb071aa5db0315fee98425bab330a175e84ad21a65c86e1814869010ed5f01047c440e85efb2a6b4a31f788f4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b4da387fba1c54749b2d6c66cdd3f87735fefafc641ef55428946dff35599762afe1425fcb8f89455c8524afd4073a551e5f7aacf91c6b91e324913375aac2f7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d431364dda536265cbdda09853bfa5fd7311e1f9dd2729a200e945721afa568edfcd9189a1d6c09543f7d3049f02e197a56fb1282288dde52d99cce3c84ae81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9aa1d7ca4862764299d7870197c43029a053603050539bebd65e46c317085f7c70c3c5255fe96e21ab8828a8726af7550c2c193ce9740aa30082b86dbcb5277"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 19:44:01.965177) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:44:01.965511) ========= 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.afgFfLpwV6/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.NslJiPfwua/Listeners TMPDIR=/var/folders/40/__rckyn52mngk9ww2dj8d78400000w/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.005140 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 19:44:02.013922) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 19:44:02.014252) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 19:44:02.014578) ========= ========= Total master_lag: 0.19 =========