builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0038 starttime: 1495624706.47 results: success (0) revision: 291a11111bdd05c5cd55dd552da4b1285ceba9b2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:26.468928) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:26.469485) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:26.469821) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:26.495928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:26.496413) ========= 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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:18:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 137M=0s 2017-05-24 04:18:26 (137 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.440391 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:26.960177) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:26.960532) ========= 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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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.030187 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:27.011049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 04:18:27.011428) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 291a11111bdd05c5cd55dd552da4b1285ceba9b2 --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 291a11111bdd05c5cd55dd552da4b1285ceba9b2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3RZe9CKqqQ/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.UNln8dEGlF/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:18:27,115 truncating revision to first 12 chars 2017-05-24 04:18:27,115 Setting DEBUG logging. 2017-05-24 04:18:27,115 attempt 1/10 2017-05-24 04:18:27,115 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/291a11111bdd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 04:18:28,328 unpacking tar archive at: mozilla-central-291a11111bdd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.487401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 04:18:28.521813) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:28.522205) ========= script_repo_revision: 291a11111bdd05c5cd55dd552da4b1285ceba9b2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:28.522639) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:28.522928) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 04:18:28.542538) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2017-05-24 04:18:28.543231) ========= /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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:18:28 INFO - MultiFileLogger online at 20170524 04:18:28 in /builds/slave/test 04:18:28 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 04:18:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:18:28 INFO - {'append_to_log': False, 04:18:28 INFO - 'base_work_dir': '/builds/slave/test', 04:18:28 INFO - 'blob_upload_branch': 'Firefox', 04:18:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:18:28 INFO - 'branch': 'Firefox', 04:18:28 INFO - 'buildbot_json_path': 'buildprops.json', 04:18:28 INFO - 'config_files': ('talos/mac_config.py',), 04:18:28 INFO - 'default_actions': ('clobber', 04:18:28 INFO - 'read-buildbot-config', 04:18:28 INFO - 'download-and-extract', 04:18:28 INFO - 'populate-webroot', 04:18:28 INFO - 'create-virtualenv', 04:18:28 INFO - 'install', 04:18:28 INFO - 'run-tests'), 04:18:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:18:28 INFO - 'download_minidump_stackwalk': True, 04:18:28 INFO - 'download_symbols': 'ondemand', 04:18:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:18:28 INFO - 'tooltool.py': '/tools/tooltool.py', 04:18:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:18:28 INFO - '/tools/misc-python/virtualenv.py')}, 04:18:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:18:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:18:28 INFO - 'gecko_profile': False, 04:18:28 INFO - 'gecko_profile_interval': 0, 04:18:28 INFO - 'installer_path': 'installer.exe', 04:18:28 INFO - 'log_level': 'info', 04:18:28 INFO - 'log_name': 'talos', 04:18:28 INFO - 'log_to_console': True, 04:18:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:18:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:18:28 INFO - 'opt_config_files': (), 04:18:28 INFO - 'pip_index': False, 04:18:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:18:28 INFO - 'cmd': ('bash', 04:18:28 INFO - '-c', 04:18:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:18:28 INFO - 'enabled': True, 04:18:28 INFO - 'halt_on_failure': False, 04:18:28 INFO - 'name': 'check_screen_resolution'},), 04:18:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:18:28 INFO - 'cmd': ('bash', 04:18:28 INFO - '-c', 04:18:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:18:28 INFO - 'enabled': True, 04:18:28 INFO - 'halt_on_failure': False, 04:18:28 INFO - 'name': 'check_screen_resolution'},), 04:18:28 INFO - 'run_cmd_checks_enabled': True, 04:18:28 INFO - 'suite': 'dromaeojs-e10s', 04:18:28 INFO - 'system_bits': '32', 04:18:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:18:28 INFO - 'title': 't-yosemite-r7-0038', 04:18:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:18:28 INFO - 'use_talos_json': True, 04:18:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:18:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:18:28 INFO - 'work_dir': 'build'} 04:18:28 INFO - [mozharness: 2017-05-24 11:18:28.757439Z] Running clobber step. 04:18:28 INFO - Running pre-action listener: _resource_record_pre_action 04:18:28 INFO - Running main action method: clobber 04:18:28 INFO - rmtree: /builds/slave/test/build 04:18:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:18:31 INFO - Running post-action listener: _resource_record_post_action 04:18:31 INFO - [mozharness: 2017-05-24 11:18:31.364389Z] Finished clobber step (success) 04:18:31 INFO - [mozharness: 2017-05-24 11:18:31.364562Z] Running read-buildbot-config step. 04:18:31 INFO - Running pre-action listener: _resource_record_pre_action 04:18:31 INFO - Running main action method: read_buildbot_config 04:18:31 INFO - Using buildbot properties: 04:18:31 INFO - { 04:18:31 INFO - "project": "", 04:18:31 INFO - "product": "firefox", 04:18:31 INFO - "who": "archaeopteryx@coole-files.de", 04:18:31 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 04:18:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 04:18:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:18:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 04:18:31 INFO - "stage_platform": "macosx64", 04:18:31 INFO - "basedir": "/builds/slave/test", 04:18:31 INFO - "buildnumber": 74, 04:18:31 INFO - "platform": "yosemite_r7", 04:18:31 INFO - "parent_task_id": "aXa5sCSFRNuZqMgmgOCx1g", 04:18:31 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:18:31 INFO - "slavebuilddir": "test", 04:18:31 INFO - "taskId": "CErPoo6mT4eN_1SCR1-vZw", 04:18:31 INFO - "branch": "mozilla-central", 04:18:31 INFO - "script_repo_revision": "production", 04:18:31 INFO - "revision": "291a11111bdd05c5cd55dd552da4b1285ceba9b2", 04:18:31 INFO - "slavename": "t-yosemite-r7-0038", 04:18:31 INFO - "repo_path": "mozilla-central" 04:18:31 INFO - } 04:18:31 INFO - Finding installer, test and symbols from parent task. 04:18:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CErPoo6mT4eN_1SCR1-vZw'}, attempt #1 04:18:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CErPoo6mT4eN_1SCR1-vZw'}, attempt #1 04:18:32 INFO - Task dependencies: OIffbwaiSvqxzZnIhMt5ug aXa5sCSFRNuZqMgmgOCx1g 04:18:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g'}, attempt #1 04:18:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:18:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/test_packages.json 04:18:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/target.crashreporter-symbols.zip 04:18:33 INFO - Running post-action listener: _resource_record_post_action 04:18:33 INFO - [mozharness: 2017-05-24 11:18:33.092887Z] Finished read-buildbot-config step (success) 04:18:33 INFO - [mozharness: 2017-05-24 11:18:33.093027Z] Running download-and-extract step. 04:18:33 INFO - Running pre-action listener: _resource_record_pre_action 04:18:33 INFO - Running main action method: download_and_extract 04:18:33 INFO - mkdir: /builds/slave/test/build/tests 04:18:33 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')]} 04:18:33 INFO - trying https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:18:33 INFO - Downloading https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/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 04:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/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 04:18:33 INFO - Downloaded 1325 bytes. 04:18:33 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:18:33 INFO - Using the following test package requirements: 04:18:33 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:18:33 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:18:33 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:18:33 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:18:33 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'jsshell-mac.zip'], 04:18:33 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:18:33 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:18:33 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:18:33 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:18:33 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:18:33 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:18:33 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:18:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:18:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:18:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:18:33 INFO - Fetch https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:18:35 INFO - Content-Length response header: 57625745 04:18:35 INFO - Bytes received: 57625745 04:18:53 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 04:18:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:18:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:18:53 INFO - Fetch https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:19:00 INFO - Content-Length response header: 57625745 04:19:00 INFO - Bytes received: 57625745 04:19:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:19:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:19:18 INFO - Fetch https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:19:19 INFO - Content-Length response header: 14411859 04:19:19 INFO - Bytes received: 14411859 04:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:19 INFO - trying https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:19:19 INFO - Downloading https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:19:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/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 04:19:21 INFO - Downloaded 68020979 bytes. 04:19:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:19:21 INFO - mkdir: /builds/slave/test/properties 04:19:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:19:21 INFO - Writing to file /builds/slave/test/properties/build_url 04:19:21 INFO - Contents: 04:19:21 INFO - build_url:https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:19:21 INFO - Running post-action listener: _resource_record_post_action 04:19:21 INFO - Running post-action listener: set_extra_try_arguments 04:19:21 INFO - [mozharness: 2017-05-24 11:19:21.564514Z] Finished download-and-extract step (success) 04:19:21 INFO - [mozharness: 2017-05-24 11:19:21.564657Z] Running populate-webroot step. 04:19:21 INFO - Running pre-action listener: _resource_record_pre_action 04:19:21 INFO - Running main action method: populate_webroot 04:19:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:19:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:19:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:19:21 INFO - '--tppagecycles', 04:19:21 INFO - '7'], 04:19:21 INFO - 'tests': ['tsvgm']}}, 04:19:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:19:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:19:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:19:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:19:21 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:19:21 INFO - 'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:19:21 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:19:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:19:21 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:19:21 INFO - 'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['damp', 'tps']}, 04:19:21 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:19:21 INFO - 'tests': ['damp', 'tps']}, 04:19:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['dromaeo_dom']}, 04:19:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:19:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 04:19:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:19:21 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:19:21 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:19:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['a11yr', 04:19:21 INFO - 'ts_paint', 04:19:21 INFO - 'tpaint', 04:19:21 INFO - 'sessionrestore', 04:19:21 INFO - 'sessionrestore_no_auto_restore', 04:19:21 INFO - 'tabpaint']}, 04:19:21 INFO - 'other-e10s': {'tests': ['a11yr', 04:19:21 INFO - 'ts_paint', 04:19:21 INFO - 'tpaint', 04:19:21 INFO - 'sessionrestore', 04:19:21 INFO - 'sessionrestore_no_auto_restore', 04:19:21 INFO - 'tabpaint']}, 04:19:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:19:21 INFO - 'ts_paint', 04:19:21 INFO - 'tpaint', 04:19:21 INFO - 'sessionrestore', 04:19:21 INFO - 'sessionrestore_no_auto_restore', 04:19:21 INFO - 'tabpaint']}, 04:19:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:19:21 INFO - 'ts_paint', 04:19:21 INFO - 'tpaint', 04:19:21 INFO - 'sessionrestore', 04:19:21 INFO - 'sessionrestore_no_auto_restore', 04:19:21 INFO - 'tabpaint']}, 04:19:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['a11yr', 04:19:21 INFO - 'ts_paint', 04:19:21 INFO - 'tpaint', 04:19:21 INFO - 'sessionrestore', 04:19:21 INFO - 'sessionrestore_no_auto_restore', 04:19:21 INFO - 'tabpaint']}, 04:19:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['a11yr', 04:19:21 INFO - 'ts_paint', 04:19:21 INFO - 'tpaint', 04:19:21 INFO - 'sessionrestore', 04:19:21 INFO - 'sessionrestore_no_auto_restore', 04:19:21 INFO - 'tabpaint']}, 04:19:21 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 04:19:21 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 04:19:21 INFO - 'bloom_basic_ref']}, 04:19:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['tsvgx', 04:19:21 INFO - 'tsvgr_opacity', 04:19:21 INFO - 'tart', 04:19:21 INFO - 'tscrollx', 04:19:21 INFO - 'cart', 04:19:21 INFO - 'tsvg_static']}, 04:19:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:19:21 INFO - 'tsvgr_opacity', 04:19:21 INFO - 'tart', 04:19:21 INFO - 'tscrollx', 04:19:21 INFO - 'cart', 04:19:21 INFO - 'tsvg_static']}, 04:19:21 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:19:21 INFO - 'talos_options': ['--disable-e10s'], 04:19:21 INFO - 'tests': ['tp5o']}, 04:19:21 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:19:21 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:19:21 INFO - 'talos_options': ['--disable-e10s', 04:19:21 INFO - '--xperf_path', 04:19:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:19:21 INFO - 'tests': ['tp5n']}, 04:19:21 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:19:21 INFO - 'talos_options': ['--xperf_path', 04:19:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:19:21 INFO - 'tests': ['tp5n']}}, 04:19:21 INFO - 'talos.zip': {'path': '', 04:19:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:19:21 INFO - Running post-action listener: _resource_record_post_action 04:19:21 INFO - [mozharness: 2017-05-24 11:19:21.586497Z] Finished populate-webroot step (success) 04:19:21 INFO - [mozharness: 2017-05-24 11:19:21.586638Z] Running create-virtualenv step. 04:19:21 INFO - Running pre-action listener: _resource_record_pre_action 04:19:21 INFO - Running main action method: create_virtualenv 04:19:21 INFO - Creating virtualenv /builds/slave/test/build/venv 04:19:21 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 04:19:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:19:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:19:21 INFO - Using real prefix '/tools/python27' 04:19:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:19:22 INFO - Installing distribute.............................................................................................................................................................................................done. 04:19:25 INFO - Installing pip.................done. 04:19:25 INFO - Return code: 0 04:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:19:25 INFO - Reading from file tmpfile_stdout 04:19:25 INFO - Output received: 04:19:25 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) 04:19:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:19:25 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')]} 04:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:26 INFO - 'HOME': '/Users/cltbld', 04:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:26 INFO - 'LOGNAME': 'cltbld', 04:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:26 INFO - 'MOZ_NO_REMOTE': '1', 04:19:26 INFO - 'NO_EM_RESTART': '1', 04:19:26 INFO - 'PAGER': '/bin/cat', 04:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:26 INFO - 'PWD': '/builds/slave/test', 04:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:26 INFO - 'SHELL': '/bin/bash', 04:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:26 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:26 INFO - 'USER': 'cltbld', 04:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:26 INFO - 'XPC_FLAGS': '0x0', 04:19:26 INFO - 'XPC_SERVICE_NAME': '0', 04:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:26 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 04:19:26 INFO - Cleaning up... 04:19:26 INFO - Return code: 0 04:19:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:27 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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:27 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 04:19:27 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 04:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:27 INFO - 'HOME': '/Users/cltbld', 04:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:27 INFO - 'LOGNAME': 'cltbld', 04:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:27 INFO - 'MOZ_NO_REMOTE': '1', 04:19:27 INFO - 'NO_EM_RESTART': '1', 04:19:27 INFO - 'PAGER': '/bin/cat', 04:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:27 INFO - 'PWD': '/builds/slave/test', 04:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:27 INFO - 'SHELL': '/bin/bash', 04:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:27 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:27 INFO - 'USER': 'cltbld', 04:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:27 INFO - 'XPC_FLAGS': '0x0', 04:19:27 INFO - 'XPC_SERVICE_NAME': '0', 04:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:27 INFO - Downloading/unpacking psutil>=3.1.1 04:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:19:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:19:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:19:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:19:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:19:29 INFO - Installing collected packages: psutil 04:19:29 INFO - Running setup.py install for psutil 04:19:29 INFO - building 'psutil._psutil_osx' extension 04:19:29 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 04:19:30 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 04:19:30 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 04:19:30 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 04:19:30 INFO - building 'psutil._psutil_posix' extension 04:19:30 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 04:19:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:19:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:19:30 INFO - ^ 04:19:30 INFO - 1 warning generated. 04:19:30 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 04:19:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:19:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:19:30 INFO - Successfully installed psutil 04:19:30 INFO - Cleaning up... 04:19:30 INFO - Return code: 0 04:19:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:19:30 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')]} 04:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:30 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')]} 04:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:30 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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:30 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 04:19:30 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 04:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:30 INFO - 'HOME': '/Users/cltbld', 04:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:30 INFO - 'LOGNAME': 'cltbld', 04:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:30 INFO - 'MOZ_NO_REMOTE': '1', 04:19:30 INFO - 'NO_EM_RESTART': '1', 04:19:30 INFO - 'PAGER': '/bin/cat', 04:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:30 INFO - 'PWD': '/builds/slave/test', 04:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:30 INFO - 'SHELL': '/bin/bash', 04:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:30 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:30 INFO - 'USER': 'cltbld', 04:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:30 INFO - 'XPC_FLAGS': '0x0', 04:19:30 INFO - 'XPC_SERVICE_NAME': '0', 04:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:19:31 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 04:19:31 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 04:19:31 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 04:19:31 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 04:19:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:19:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:19:32 INFO - Installing collected packages: mozsystemmonitor 04:19:32 INFO - Running setup.py install for mozsystemmonitor 04:19:32 INFO - Successfully installed mozsystemmonitor 04:19:32 INFO - Cleaning up... 04:19:32 INFO - Return code: 0 04:19:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:19:32 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')]} 04:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:32 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')]} 04:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:32 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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:32 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 04:19:32 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 04:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:32 INFO - 'HOME': '/Users/cltbld', 04:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:32 INFO - 'LOGNAME': 'cltbld', 04:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:32 INFO - 'MOZ_NO_REMOTE': '1', 04:19:32 INFO - 'NO_EM_RESTART': '1', 04:19:32 INFO - 'PAGER': '/bin/cat', 04:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:32 INFO - 'PWD': '/builds/slave/test', 04:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:32 INFO - 'SHELL': '/bin/bash', 04:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:32 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:32 INFO - 'USER': 'cltbld', 04:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:32 INFO - 'XPC_FLAGS': '0x0', 04:19:32 INFO - 'XPC_SERVICE_NAME': '0', 04:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:33 INFO - Downloading/unpacking jsonschema==2.5.1 04:19:33 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 04:19:33 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 04:19:33 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 04:19:33 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 04:19:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:19:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:19:34 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 04:19:34 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 04:19:34 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 04:19:34 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 04:19:34 INFO - Downloading functools32-3.2.3-2.tar.gz 04:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:19:34 INFO - warning: no files found matching '*.txt' 04:19:34 INFO - no previously-included directories found matching 'build' 04:19:34 INFO - no previously-included directories found matching 'dist' 04:19:34 INFO - no previously-included directories found matching '.git*' 04:19:34 INFO - Installing collected packages: jsonschema, functools32 04:19:34 INFO - Running setup.py install for functools32 04:19:34 INFO - warning: no files found matching '*.txt' 04:19:34 INFO - no previously-included directories found matching 'build' 04:19:34 INFO - no previously-included directories found matching 'dist' 04:19:34 INFO - no previously-included directories found matching '.git*' 04:19:34 INFO - Successfully installed jsonschema functools32 04:19:34 INFO - Cleaning up... 04:19:34 INFO - Return code: 0 04:19:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:19:34 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')]} 04:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:34 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')]} 04:19:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:34 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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:34 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 04:19:34 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 04:19:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:34 INFO - 'HOME': '/Users/cltbld', 04:19:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:34 INFO - 'LOGNAME': 'cltbld', 04:19:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:34 INFO - 'MOZ_NO_REMOTE': '1', 04:19:34 INFO - 'NO_EM_RESTART': '1', 04:19:34 INFO - 'PAGER': '/bin/cat', 04:19:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:34 INFO - 'PWD': '/builds/slave/test', 04:19:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:34 INFO - 'SHELL': '/bin/bash', 04:19:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:34 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:34 INFO - 'USER': 'cltbld', 04:19:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:34 INFO - 'XPC_FLAGS': '0x0', 04:19:34 INFO - 'XPC_SERVICE_NAME': '0', 04:19:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:19:35 INFO - Cleaning up... 04:19:35 INFO - Return code: 0 04:19:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:19: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')]} 04:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19: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')]} 04:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19: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', '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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:19: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 blobuploader==1.2.4 04:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:35 INFO - 'HOME': '/Users/cltbld', 04:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:35 INFO - 'LOGNAME': 'cltbld', 04:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:35 INFO - 'MOZ_NO_REMOTE': '1', 04:19:35 INFO - 'NO_EM_RESTART': '1', 04:19:35 INFO - 'PAGER': '/bin/cat', 04:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:35 INFO - 'PWD': '/builds/slave/test', 04:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:35 INFO - 'SHELL': '/bin/bash', 04:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:35 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:35 INFO - 'USER': 'cltbld', 04:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:35 INFO - 'XPC_FLAGS': '0x0', 04:19:35 INFO - 'XPC_SERVICE_NAME': '0', 04:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:35 INFO - Downloading/unpacking blobuploader==1.2.4 04:19: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 04:19: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 04:19: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 04:19: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 04:19:36 INFO - Downloading blobuploader-1.2.4.tar.gz 04:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:19:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:19:37 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 04:19:37 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 04:19:37 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 04:19:37 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 04:19:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:19:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:19:37 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 04:19:37 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 04:19:37 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 04:19:37 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 04:19:37 INFO - Downloading docopt-0.6.1.tar.gz 04:19:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:19:37 INFO - Installing collected packages: blobuploader, requests, docopt 04:19:37 INFO - Running setup.py install for blobuploader 04:19:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:19:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:19:38 INFO - Running setup.py install for requests 04:19:38 INFO - Running setup.py install for docopt 04:19:38 INFO - Successfully installed blobuploader requests docopt 04:19:38 INFO - Cleaning up... 04:19:38 INFO - Return code: 0 04:19:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19: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')]} 04:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19: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')]} 04:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:38 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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:38 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 04:19:38 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 04:19:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:38 INFO - 'HOME': '/Users/cltbld', 04:19:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:38 INFO - 'LOGNAME': 'cltbld', 04:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:38 INFO - 'MOZ_NO_REMOTE': '1', 04:19:38 INFO - 'NO_EM_RESTART': '1', 04:19:38 INFO - 'PAGER': '/bin/cat', 04:19:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:38 INFO - 'PWD': '/builds/slave/test', 04:19:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:38 INFO - 'SHELL': '/bin/bash', 04:19:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:38 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:38 INFO - 'USER': 'cltbld', 04:19:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:38 INFO - 'XPC_FLAGS': '0x0', 04:19:38 INFO - 'XPC_SERVICE_NAME': '0', 04:19:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-BEmY6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-LUksdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-HAccG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-v3Qm8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-71d3sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-HpXBmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-0O2jU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-JSeror-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-MDiD_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:19:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:19:39 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-QXrwq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:19:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-tJEmaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:19:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-kvzmFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:19:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-x6ED39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:19:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-OlGKUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-27Tw9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:19:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-km6B76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:19:40 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-zg_KLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:19:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:19:40 INFO - Running setup.py install for manifestparser 04:19:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:19:40 INFO - Running setup.py install for mozcrash 04:19:41 INFO - Running setup.py install for mozdebug 04:19:41 INFO - Running setup.py install for mozdevice 04:19:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:19:41 INFO - Running setup.py install for mozfile 04:19:41 INFO - Running setup.py install for mozhttpd 04:19:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:19:41 INFO - Running setup.py install for mozinfo 04:19:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:19:41 INFO - Running setup.py install for mozInstall 04:19:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:19:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:19:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:19:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:19:41 INFO - Running setup.py install for mozleak 04:19:42 INFO - Running setup.py install for mozlog 04:19:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:19:42 INFO - Running setup.py install for moznetwork 04:19:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:19:42 INFO - Running setup.py install for mozprocess 04:19:42 INFO - Running setup.py install for mozprofile 04:19:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:19:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:19:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:19:42 INFO - Running setup.py install for mozrunner 04:19:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:19:42 INFO - Running setup.py install for mozscreenshot 04:19:43 INFO - Running setup.py install for moztest 04:19:43 INFO - Running setup.py install for mozversion 04:19:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:19:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:19:43 INFO - Cleaning up... 04:19:43 INFO - Return code: 0 04:19:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19:43 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')]} 04:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:43 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')]} 04:19:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:43 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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:43 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 04:19:43 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 04:19:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:43 INFO - 'HOME': '/Users/cltbld', 04:19:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:43 INFO - 'LOGNAME': 'cltbld', 04:19:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:43 INFO - 'MOZ_NO_REMOTE': '1', 04:19:43 INFO - 'NO_EM_RESTART': '1', 04:19:43 INFO - 'PAGER': '/bin/cat', 04:19:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:43 INFO - 'PWD': '/builds/slave/test', 04:19:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:43 INFO - 'SHELL': '/bin/bash', 04:19:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:43 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:43 INFO - 'USER': 'cltbld', 04:19:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:43 INFO - 'XPC_FLAGS': '0x0', 04:19:43 INFO - 'XPC_SERVICE_NAME': '0', 04:19:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:19:43 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-XraklP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:19:43 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)) 04:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:19:43 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-7UnVbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:19:43 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)) 04:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:19:43 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-q5HIGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:19:43 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)) 04:19:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:19:43 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-rQVzJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:19:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-xMH_wR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-jYzEA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-UYquWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-gnRJAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-26F6Fe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-d3zer7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-yaWm2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-2R3uyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-Mcgd2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:19:44 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)) 04:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:19:44 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-hXWCKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:19:45 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)) 04:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:45 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-G2SPM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:19:45 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)) 04:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:19:45 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-e4GN0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:19:45 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)) 04:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:19:45 INFO - Running setup.py (path:/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/pip-KUtPuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:19:45 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)) 04:19:45 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)) 04:19:45 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)) 04:19:45 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)) 04:19:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:19:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:19:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:19:45 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 04:19:45 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 04:19:45 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 04:19:45 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 04:19:46 INFO - Downloading blessings-1.6.tar.gz 04:19:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:19:46 INFO - Installing collected packages: blessings 04:19:46 INFO - Running setup.py install for blessings 04:19:47 INFO - Successfully installed blessings 04:19:47 INFO - Cleaning up... 04:19:47 INFO - Return code: 0 04:19:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:19:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:19:47 INFO - Reading from file tmpfile_stdout 04:19:47 INFO - Current package versions: 04:19:47 INFO - blessings == 1.6 04:19:47 INFO - blobuploader == 1.2.4 04:19:47 INFO - docopt == 0.6.1 04:19:47 INFO - functools32 == 3.2.3-2 04:19:47 INFO - jsonschema == 2.5.1 04:19:47 INFO - manifestparser == 1.1 04:19:47 INFO - mozInstall == 1.12 04:19:47 INFO - mozcrash == 1.0 04:19:47 INFO - mozdebug == 0.1 04:19:47 INFO - mozdevice == 0.50 04:19:47 INFO - mozfile == 1.2 04:19:47 INFO - mozhttpd == 0.7 04:19:47 INFO - mozinfo == 0.9 04:19:47 INFO - mozleak == 0.1 04:19:47 INFO - mozlog == 3.4 04:19:47 INFO - moznetwork == 0.27 04:19:47 INFO - mozprocess == 0.25 04:19:47 INFO - mozprofile == 0.28 04:19:47 INFO - mozrunner == 6.13 04:19:47 INFO - mozscreenshot == 0.1 04:19:47 INFO - mozsystemmonitor == 0.3 04:19:47 INFO - moztest == 0.8 04:19:47 INFO - mozversion == 1.4 04:19:47 INFO - psutil == 3.1.1 04:19:47 INFO - requests == 1.2.3 04:19:47 INFO - wsgiref == 0.1.2 04:19:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:19: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')]} 04:19:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19: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')]} 04:19:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:47 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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:47 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 04:19:47 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 04:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:47 INFO - 'HOME': '/Users/cltbld', 04:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:47 INFO - 'LOGNAME': 'cltbld', 04:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:47 INFO - 'MOZ_NO_REMOTE': '1', 04:19:47 INFO - 'NO_EM_RESTART': '1', 04:19:47 INFO - 'PAGER': '/bin/cat', 04:19:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:47 INFO - 'PWD': '/builds/slave/test', 04:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:47 INFO - 'SHELL': '/bin/bash', 04:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:47 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:47 INFO - 'USER': 'cltbld', 04:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:47 INFO - 'XPC_FLAGS': '0x0', 04:19:47 INFO - 'XPC_SERVICE_NAME': '0', 04:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:47 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)) 04:19:47 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)) 04:19:47 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)) 04:19:47 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)) 04:19:47 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)) 04:19:47 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)) 04:19:47 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)) 04:19:47 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)) 04:19:47 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)) 04:19:47 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:19:47 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 04:19:47 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 04:19:47 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 04:19:47 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 04:19:49 INFO - Downloading marionette_driver-1.4.0.tar.gz 04:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 04:19:49 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)) 04:19:49 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)) 04:19:49 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)) 04:19:49 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)) 04:19:49 INFO - Installing collected packages: marionette-driver 04:19:49 INFO - Running setup.py install for marionette-driver 04:19:49 INFO - Successfully installed marionette-driver 04:19:49 INFO - Cleaning up... 04:19:49 INFO - Return code: 0 04:19:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:19:49 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')]} 04:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:19:49 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')]} 04:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:19:49 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 0x106461b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106465768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a944f250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, '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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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 04:19:49 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 04:19:49 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 04:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:19:49 INFO - 'HOME': '/Users/cltbld', 04:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:19:49 INFO - 'LOGNAME': 'cltbld', 04:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:19:49 INFO - 'MOZ_NO_REMOTE': '1', 04:19:49 INFO - 'NO_EM_RESTART': '1', 04:19:49 INFO - 'PAGER': '/bin/cat', 04:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:19:49 INFO - 'PWD': '/builds/slave/test', 04:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:19:49 INFO - 'SHELL': '/bin/bash', 04:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:19:49 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:19:49 INFO - 'USER': 'cltbld', 04:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:19:49 INFO - 'XPC_FLAGS': '0x0', 04:19:49 INFO - 'XPC_SERVICE_NAME': '0', 04:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:19:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:19:49 INFO - Cleaning up... 04:19:49 INFO - Return code: 0 04:19:49 INFO - Running post-action listener: _resource_record_post_action 04:19:49 INFO - Running post-action listener: _start_resource_monitoring 04:19:49 INFO - Starting resource monitoring. 04:19:49 INFO - [mozharness: 2017-05-24 11:19:49.735914Z] Finished create-virtualenv step (success) 04:19:49 INFO - [mozharness: 2017-05-24 11:19:49.736954Z] Running install step. 04:19:49 INFO - Running pre-action listener: _resource_record_pre_action 04:19:49 INFO - Running main action method: install 04:19:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:19:50 INFO - Reading from file tmpfile_stdout 04:19:50 INFO - Detecting whether we're running mozinstall >=1.0... 04:19:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:19:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:19:50 INFO - Reading from file tmpfile_stdout 04:19:50 INFO - Output received: 04:19:50 INFO - Usage: mozinstall [options] installer 04:19:50 INFO - Options: 04:19:50 INFO - -h, --help show this help message and exit 04:19:50 INFO - -d DEST, --destination=DEST 04:19:50 INFO - Directory to install application into. [default: 04:19:50 INFO - "/builds/slave/test"] 04:19:50 INFO - --app=APP Application being installed. [default: firefox] 04:19:50 INFO - mkdir: /builds/slave/test/build/application 04:19:50 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'] 04:19:50 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 04:20:11 INFO - Reading from file tmpfile_stdout 04:20:11 INFO - Output received: 04:20:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:20:11 INFO - Running post-action listener: _resource_record_post_action 04:20:11 INFO - [mozharness: 2017-05-24 11:20:11.420358Z] Finished install step (success) 04:20:11 INFO - [mozharness: 2017-05-24 11:20:11.420531Z] Running run-tests step. 04:20:11 INFO - Running pre-action listener: _resource_record_pre_action 04:20:11 INFO - Running main action method: run_tests 04:20:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:20:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:20:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:20:11 INFO - 2017-05-24 04:20:11.435 screenresolution[1628:7605] starting screenresolution argv=screenresolution get 04:20:11 INFO - 2017-05-24 04:20:11.437 screenresolution[1628:7605] Display 0: 1600x1200x32@60 04:20:11 INFO - 2017-05-24 04:20:11.443 screenresolution[1629:7607] starting screenresolution argv=screenresolution list 04:20:11 INFO - Available Modes on Display 0 04:20:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:20:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:20:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:20:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:20:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:20:11 INFO - Intel Iris: 04:20:11 INFO - Chipset Model: Intel Iris 04:20:11 INFO - Type: GPU 04:20:11 INFO - Bus: Built-In 04:20:11 INFO - VRAM (Dynamic, Max): 1536 MB 04:20:11 INFO - Vendor: Intel (0x8086) 04:20:11 INFO - Device ID: 0x0a2e 04:20:11 INFO - Revision ID: 0x0009 04:20:11 INFO - Displays: 04:20:11 INFO - Display: 04:20:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:20:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:20:11 INFO - Display Serial Number: 1600x1200 60 04:20:11 INFO - Main Display: Yes 04:20:11 INFO - Mirror: Off 04:20:11 INFO - Online: Yes 04:20:11 INFO - Rotation: Supported 04:20:11 INFO - Return code: 0 04:20:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:20:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:20:11 INFO - Python 2.7.3 04:20:11 INFO - Return code: 0 04:20:11 INFO - grabbing minidump binary from tooltool 04:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:20:11 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 0x106171d30>, '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 0x106125db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106166be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:20:11 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 04:20:11 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 04:20:11 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 04:20:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:20:11 INFO - Return code: 0 04:20:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:20:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:20:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:20:11 INFO - ENV: RUST_BACKTRACE is now 1 04:20:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:20:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:20:11 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/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--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 04:20:11 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/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0038 --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 04:20:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3RZe9CKqqQ/Render', 04:20:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:20:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:20:11 INFO - 'HOME': '/Users/cltbld', 04:20:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:20:11 INFO - 'LOGNAME': 'cltbld', 04:20:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:20:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:20:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:20:11 INFO - 'MOZ_NO_REMOTE': '1', 04:20:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:20:11 INFO - 'NO_EM_RESTART': '1', 04:20:11 INFO - 'PAGER': '/bin/cat', 04:20:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:20:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:20:11 INFO - 'PWD': '/builds/slave/test', 04:20:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:20:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:20:11 INFO - 'RUST_BACKTRACE': '1', 04:20:11 INFO - 'SHELL': '/bin/bash', 04:20:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UNln8dEGlF/Listeners', 04:20:11 INFO - 'TMPDIR': '/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/', 04:20:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:20:11 INFO - 'USER': 'cltbld', 04:20:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:20:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:20:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:20:11 INFO - 'XPC_FLAGS': '0x0', 04:20:11 INFO - 'XPC_SERVICE_NAME': '0', 04:20:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:20:11 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/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0038', '--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 04:20:12 INFO - mozversion application_buildid: 20170524022847 04:20:12 INFO - mozversion application_changeset: 291a11111bdd05c5cd55dd552da4b1285ceba9b2 04:20:12 INFO - mozversion application_display_name: Nightly 04:20:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:20:12 INFO - mozversion application_name: Firefox 04:20:12 INFO - mozversion application_remotingname: firefox 04:20:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:20:12 INFO - mozversion application_vendor: Mozilla 04:20:12 INFO - mozversion application_version: 55.0a1 04:20:12 INFO - mozversion platform_buildid: 20170524022847 04:20:12 INFO - mozversion platform_changeset: 291a11111bdd05c5cd55dd552da4b1285ceba9b2 04:20:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:20:12 INFO - mozversion platform_version: 55.0a1 04:20:12 INFO - using testdate: 1495624812 04:20:12 INFO - actual date: 1495624812 04:20:12 INFO - starting webserver on 'localhost:49226' 04:20:12 INFO - SUITE-START | Running 2 tests 04:20:12 INFO - TEST-START | dromaeo_css 04:20:12 INFO - operating with platform_type : mac_ 04:20:12 INFO - Initialising browser for dromaeo_css test... 04:20:12 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/tmpc1yB3k/profile 04:20:12 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 04:20:45 INFO - TEST-INFO | 1639: killed by SIGTERM 04:20:45 INFO - Browser initialized. 04:20:45 INFO - Running cycle 1/1 for dromaeo_css test... 04:20:45 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/tmpc1yB3k/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:20:47 INFO - PID 1646 | 2017-05-24 04:20:47.801 plugin-container[1647:8081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x994b, name = 'com.apple.tsm.portname' 04:20:47 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:20:47 INFO - PID 1646 | 2017-05-24 04:20:47.801 plugin-container[1647:8081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 04:20:47 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:20:47 INFO - PID 1646 | 2017-05-24 04:20:47.801 plugin-container[1647:8081] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:20:48 INFO - PID 1646 | 2017-05-24 04:20:48.224 plugin-container[1648:8123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x483f, name = 'com.apple.tsm.portname' 04:20:48 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:20:48 INFO - PID 1646 | 2017-05-24 04:20:48.225 plugin-container[1648:8123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 04:20:48 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:20:48 INFO - PID 1646 | 2017-05-24 04:20:48.225 plugin-container[1648:8123] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:20:57 INFO - PID 1646 | RSS: Main: 246423552 04:20:57 INFO - PID 1646 | 04:24:00 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 04:24:02 INFO - PID 1646 | RSS: Main: 257892352 04:24:02 INFO - PID 1646 | 04:27:04 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 04:27:04 INFO - PID 1646 | RSS: Main: 279511040 04:27:04 INFO - PID 1646 | 04:30:06 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 04:30:06 INFO - PID 1646 | RSS: Main: 279334912 04:30:06 INFO - PID 1646 | 04:33:08 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 04:33:09 INFO - PID 1646 | RSS: Main: 283615232 04:33:09 INFO - PID 1646 | 04:36:11 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 04:36:12 INFO - PID 1646 | RSS: Main: 283787264 04:36:12 INFO - PID 1646 | 04:39:13 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 04:39:14 INFO - PID 1646 | RSS: Main: 285122560 04:39:14 INFO - PID 1646 | 04:39:14 INFO - PID 1646 | __start_tp_report 04:39:14 INFO - PID 1646 | _x_x_mozilla_page_load 04:39:14 INFO - PID 1646 | _x_x_mozilla_page_load_details 04:39:14 INFO - PID 1646 | |i|pagename|runs| 04:39:14 INFO - PID 1646 | |0;dojo.html;3171.6669749676284;3414.7268218542517;3586.7847929124273;3598.118460977061;3612.530371051763;4973.5026497350245;5105.9022310203345;6024.337322894478;6109.633421994683;6250.906236406452;5535.335759708831;5784.797532086378;6150.1697270868535;6192.102145188945;6292.465140463055;5385.192221166817;5401.135818269068;5488.862778430531;5488.91766623501;5503.477169668875;4480.865574032784;5351.2240725095035;5480.506754392104;5524;5531.806386776464;5474.91787623186;5531.502164805167;5545.916811247835;5550.222968784374;5572.721363931808;5334.893302133935;5388.084025715638;5424.647397919122;5441.673499589998;5446.319210098738;5335.439778823206;5360.115580929142;5394.460553944614;5428.891422171574;5450.264214331054;6103.23709536308;6259.280182778994;6264.655443950581;6276.215473065867;6278.4663303619245;6368.172137622126;6485;6508.9023664645065;6613.470922326251;6618.106555615027;6767.864642707118;6791.3548212919695;6796.116504854337;6826.93173068268;6832.180138383376;6345.111684364193;6346.2067241594805;6351.745930162834;6369.968150159312;6374.394432528902;6701.1288532491435;6818.011388348662;6915.51591388598;6938.202106757686;6956.130483689539;6384.968075159594;6405.19935008124;6508.21901371829;6534.705938232791;6554.70503827329;6412.454941330039;6498.870022599522;6626.86746265072;6743.325667433218;6755.121834161528;6646.567973081735;6654.86690266192;6656.534042617067;6662.100616416722;6670.366315200048;6390.648514331663;6690.966545167243;6704.061431399608;6704.765333213411;6719;4526.88682782933;5263.552523056113;5305.973470132624;5336.46635336471;5365.08793505105;4789.87437952081;4806.927896081562;4815.398075240595;4837.637177211724;4904.975475122602;6194.628322300677;6336;6355.3009168991375;6365.968170159213;6373.235211774524;6430.260520040255;6468;6640.800775976729;6700.564463309869;6809.182898052166;6198.876022479525;6342.080398342215;6382.393672601095;6389.520785941073;6401.199850018747;5994.7602095916545;5995.61028533144;6000.249968753906;6016.608920420159;6021.157038014681;6717.966410167918;6814.420774234246;6815.318468153145;6973.651317434006;7024.227334993249;6765.729370825309;6778.2205046420295;6780.25417204117;6809.625470598967;6810.795676129724;6860.0738900247825;6981.79054628362;6985.406240469617;6990.09128813251;7005.404540614105;6390.9041364378645;6622.701978410887;6634.568753031038;6673.432758215412;6715.3620406061345;6564.671766411756;6665.633390163587;6711.4966377520905;6755.3582409671;6813.250542440229;6496.220453545605;6499.1876015498065;6507.869842603216;6521;6548.476121910331;6300.054991751274;6348.587341822767;6354.26925903527;6417.7753778617525;6444.355564443518;6377.649229292433;6409.551331406757;6417.39034791694;6419.550631455753;6420.903686444613;6365.395287447685;6377.075324078076;6431.228252609717;6479.514036447191;6574;6281.340459251713;6370.235571731237;6389.840253993501;6400.647964362005;6401.903971440339 04:39:14 INFO - PID 1646 | |1;ext.html;762.0931092000519;813.134012276925;826.0830478169231;1022.739201503617;1265.55072949103;39233.80383098084;39839;39901.6034438795;39915;40194;40070.799646001804;40350.19299614006;40511.79744101283;40605;40669.49288113903;7722.884156737639;7868.724594639189;8250.339972802192;8445.451045682043;8460.534670593115;8320.251177394033;8356.83286334273;8410.705625303115;8420.115887831782;8427.957860210721;8371.70238613013;8443.662253509852;8447.577621118919;8469.534175620369;8474.11021842707;8318.251357377865;8378.245957863792;8400.033996090468;8451;8468.576571171418;8126.065502467174;8143.79640508992;8168.264856162974;8176.550289734062;8196.098429172785;37533.436998445046;37631;37670.05824854373;37709.43435848464;37911;3518.0149558123767;3556.5376501054957;3560.1633616100194;3563.4120370138894;3565.286942611462;30586.541201881988;30829.229269268224;30970.38059238825;30988.0703578893;31044.379112417624;2986.3131479759777;3071.01727447217;3084.4139613473367;3087.9073627791204;3110.7355874750674;251289;254638;280368;309997;311262;48260.27609585859;48691.756541217066;48719.78200544944;48761;48775.26837097447;39630.21664025126;50908.49091509038;51047.48952510502;54450;54500;11458.598949036743;13465.932670336782;13502.054856159975;13584.592462226148;13622.455101796015;10866;10934.56261749521;10938.617148399928;10956.726081847859;10969.945150274358;12385.885270325714;12880.871191288155;13994.30028498571;13996.230207338695;14079.85920140806;5529.811090615537;5824.912626310609;5982.341942386334;6189.690515474208;6203.410675985864;980.7793246519476;986.6201512417771;1024.7335692719798;1048.7483004079118;1050.3435352904435;22089.779102208773;22504.662430063563;22560.435989100402;23730.881345593505;24882.875585621958;1044.2011860926398;1053.873535175784;1113.0817075912405;1133.4106264742288;1149.672343382132;11702.063834893188;12514.68713282157;12625;12643.557475488315;12818.743625127632;324277.37861310545;325650;326403.36798315856;328920;329446.7055329464;56619.15071273934;56627.86744265092;56867.14699279431;57006.859862802514;57039;1488.1175313228775;1514.3412615512289;1524.382625036884;1525.260248779341;1533.4402942925751;22490.66264006074;23096.538069238857;23986.880065599562;24103.51792964096;24225;39173.41239881347;39329.606703933736;39336.213275734895;39365;39369.60630393774;259498;259655;259885;260020;260193.3980660169;34522.48216276808;35309.823450882584;36503.63496365002;36695.81652091722;36844.078898027765;44813.32780008237;46088.308675370565;46217.53782462132;46267;46386.30420543762;25465.653700434912;29691.437027555967;31937;31962;32014.51978220282;24900.252992410256;28030.579541306488;31726.841365793025;31869.8406507966;32469.18827029343;38782.6121738779;38973.220535589695;39019.6098039016;39034.609653904234;39042;30006.399872002876;31767.04698859038;32368.35263294768;32374.410047195695;32525.861594844067;22851.542969140857;24100.87949560241;24240.515189695754;24451.38871528226;24611.753882460947 04:39:14 INFO - PID 1646 | |2;jquery.html;372.4729507746539;406.9115117061859;423.47700589771625;431.1613910943217;469.92716129;15936.920315398436;20643.277485288;21088;21506.462338441546;21841.781582184172;18849.528761780963;22205.77794222057;22643.886780566114;22683;22973.96617152228;5777.82666520004;5792.304923409176;5985.4912332451795;6014.548908831834;6035.366286539916;5873.70631468427;5903.9668058089665;5971.10433434986;5982.8803423931495;5992.940070599282;5775.393583673716;6020.247469066367;6036.396360363972;6061.696915154247;6095.786647467317;5982.910256346158;5994.880102397971;6000.729967151488;6028.5478589106;6040.184575082351;5359.5712343012465;5385.434529374398;5404.054290499186;5410.729463526808;5419.512243898068;26283.73716262832;26413.33966650849;26500.46999060027;26550.336241593923;26638.33404164892;2074.7925207479284;2187.278198194592;2188.1575593396497;2189.39791557323;2192.4190089626263;17534.561635959075;17611.823881761145;17841.643167136714;17928.820711792847;18312.450626481226;1986.3345779163997;1995.6806910894188;1997.3209108903043;2011.4367976966473;2011.8994050297426;166201;169310;170160.2983970157;171022;175270;11950.641480755592;12409;12433.626991190278;12462.81305780414;12582.685432864251;16103.355865765237;16135.112568808594;16154;16155.676886462204;16159.192040397933;7151.249118842551;7174.605396703127;7354.595497247703;8346.248837604524;8619.61211745473;6720;7783.338416234684;8072.192780721998;8075.394345424117;8238.052623948202;7151.928480715126;7692.499987500794;7807.531548107132;7808.141104478503;7852.097008844056;1819.6087841114224;2121.1621409543145;2143.3784202581387;2144.6139694854764;2152.817010554089;853.4879072556416;917.5228880981986;926.7080869526212;953.9189168920581;961.6393852305386;16507.33970641161;19980.3006894758;20001.099950502263;20109.39671809848;20171.596568068555;923.4920793563513;977.8510250455686;984.2175470500969;997.1125698128672;1006.1347241372414;10027.348222365523;10282.537285822156;10393.896061039444;10431.94784026075;10575.576976920991;506116;517707;520521;520979;523535;120974.39512802556;123568;123769.38115309546;126380.73619263874;129382.35308823585;1097.3470784882982;1125.7467069909205;1157.5311998640564;1160.1646814293695;1160.8913487619134;16823.7476437856;17250.223739931484;17480.737788932922;17518.211680474666;17523.912380438018;55949.44050559442;55965;56381;56426;56539.434605655064;72027.27972720415;76651.61674191593;77204.22795772195;77374.22625773895;77775.61112194402;8575.31397488187;8661.480311181349;8788.736337909873;8837.204651581389;8866.73399798007;13358.198508089547;13410.195388276734;13544;13942.651433714238;14002.439902404198;10031.498425078571;11460.484278207334;11599.942000289944;11656.18406711522;11875.109366797698;10864.185186110915;11081.944590276997;11097.667069987914;11104.222704410937;11235.831462528233;11469.655910322703;11496.712582185177;11921.642350729491;11968.700782480508;12097.75804483923;7961.920380796118;8467.788305292417;8657.740267791976;8750.037495875576;8807.515586642796;11665.475053622777;12051.75896482048;12151.878481215428;12153.81769273444;12167.84405481478 04:39:14 INFO - PID 1646 | |3;mootools.html;1373.5604606525912;1441.0201063276968;1441.7549016667165;1445.1834713386936;1445.8120444342235;11648.30110193388;11921.523139074448;12140.575079872206;12143.817842732355;12329;12033.578824741136;12109.455074521646;12130.332831694275;12291.078169137305;12308.384580770971;2535.6069809179608;2557.309526427863;2581.8579978101197;2584.8190626656046;2588.8446693198466;2508.749125087495;2549.992752862618;2552.591585346345;2559.654446649697;2568.3707491664477;2532.5947848344267;2536.378587246118;2555.8083143764197;2567.17850287907;2578.9742102579016;2480.119557557068;2498.2130628851855;2501.6247562865624;2504.749525047499;2511.5604769165325;2048.3035767839006;2502.199296225209;2511.54792137417;2516.9806228477496;2579.458313754114;11464.885351146466;11650.6504804856;11653.475593598223;11667.299962002307;11706.239094458919;6453.354664533556;6681.064650948871;6702.128723265993;6702.195736511601;6728.293529179464;4032.495938007749;4210.74735515867;4272;4282.892927676802;4292.849750258757;1229.07205060179;1251.837261156044;1254.4480428611382;1254.956076537326;1280.8527019392795;16299.674006519803;16332.75500867488;16540.007599544067;16594.419195328104;16734.414295499722;5170.6122040847085;5947.434993675594;5989.341172471025;6150.538709596798;6151.231096112986;13706.246156461291;13830.23933683637;13834.515791947233;13856.93071534636;13904.887608991256;6585;6716.093327400836;6716.529842911047;6719.0593316935665;6756.0879281297375;6070.3929607038945;6114.755409783559;6161.661108639068;6174.104754810527;6217.937820621736;4607.861764147081;4721.716696998123;4724.503927087675;4732.290156476556;4735.550122738403;1323.735252949414;1432.1120905038943;1474.4765608209145;1476.497990683173;1484.5249520153664;793.2741541489588;809.3282575462352;826.7726375246855;832.6128350317132;862.8058686795421;6217.7823776168525;6260.749570017239;6393.264774550893;6418.229812422539;6475.708593113227;514.7143335448861;606.6360183889719;608.5952841360446;617.8918689229367;622.4087117238641;3655.5796083450746;4016.6585840203325;4259.850905218303;4338.82644694215;4469.262571675702;14299.356528956221;14658.340374683165;14928.62678433048;15049.924750376396;15144;11826;14741.852581474262;14957.40170393172;14981.101133932;15065.17141557225;7237;8404.621792019374;8592.656293748305;8616.009158946676;8639.6976105836;5769.624974376652;7153.60655163971;7183.8563228736175;7269.236730143471;7339.926600733925;13347.66630834237;13525.797113043509;13546.254955977516;13636.590902272948;13787;13865.306734663025;13982.720345593234;14026.719465610835;14168.220747858966;14204.715905682035;7883.093444253755;9229.077092290718;9510.619575216913;9538.28462865274;9548.52257387114;10653.467326633483;10797.352158870493;10810.45947702596;10811.972862578043;10826.458677066274;7682.8079298018;8017.719379821679;8097.8380432392205;8126.106128325998;8189.795255118432;8903.510306933182;8982.820343593223;9013.864792028256;9031.232345250728;9063.592138353923;8123.79690507742;8131.878021829559;8222.506649601042;8223.09545949957;8229.670813167406;6080.391960803884;6112.969435152796;6168.814935551941;6197.25632924052;6277.654728989949;5513.2005859151;6113.908291375544;6247.750089996349;6315.494760419064;6324.715387807468 04:39:14 INFO - PID 1646 | |4;prototype.html;376.8643288416171;402.24378169042194;404.5246834968911;404.82997141200696;409.52699631925117;6389.840253993647;6414.230292364923;6416.9358306416925;6458.547901646886;6511.772087976922;5876.676782776945;6029.2119565217445;6116.042866282792;6227.968860155693;6254.687265636723;4430.645548356139;4729.2433210686295;4731.57415832575;4756.785944632483;4765.094632019923;4308.892277693051;4349.217140914634;4675;4753.524647535253;4763.285507173917;4014.337634290353;4311.922019495126;4682.508336624639;4704.835330763424;4715.363425937506;3849.538055433366;4619.722816631013;4642.466116396624;4718.693284936468;4728.314394412808;3795.2599243147597;4032.858849940238;4129.215449064668;4178.749275043507;4191.497020357577;4729;5986.880262394728;6016.729247183887;6089.695515224221;6129;4731.976340118312;5349.786008559653;5551.584345991746;5590.5527557795685;5595.63628364155;6132.969335153341;6519.4132528072705;6685.465162786965;6746.494012949049;6761.560498567626;4721.787519561628;4733.408323959505;4753.691010084333;4761.928571071437;4763.190257656172;5528;7085.185203701589;7088.822779430556;7096.6451677416435;7107.1471423429;3733.962660373416;4396.27461468854;4456.752109409371;4460.888477788081;4469.5306992765945;4524.122489166959;4529.524399937959;4532;4533.9319910201375;4552.180607490617;4097.44684467599;5133.640645154878;5265.236540701653;5354.678719276856;5386.569074474032;6698.665066746643;6704.631245281459;6721.327867213337;6722.831929201809;6834.179898412123;1534.9539513814605;1544.6215677159169;1550.1474189195897;1553.927789825017;1557.268084000518;1004.7789486313145;1012.8987101289812;1018.7911478146962;1021.596469394585;1033.7725700345911;5350.38470575881;5618.690971996498;6397.744090236339;6430.7106180222;6461.8061458156335;6153.138560601519;6764.139861538018;6967.372936435745;6979.8604027919155;7004.719811207597;5345.037893179187;6315.589486683442;6325.399087086719;6384.457321127757;6384.904226436607;2798.1532188755323;2911.980806717632;3303.6035675718585;3535.744810592254;3632.3824949758605;6049.576529642883;6209.85117753208;6791.762288319885;6954;7151.570905745568;7158;7176.7488137914925;7191.604461754549;7193.2447093055525;7203.171635262012;5602.187682785973;6444.742210311629;6860.862782744317;6966.860662786716;7183.676734546958;6959.373656370951;6963.4777391694815;6988.510804243654;6997.090378250795;7013.544119632207;6612.669366531558;6764.594124352555;6813.911653008156;6824.965875170592;6912.758053468306;5742.908847318996;6923.723051078102;7022.964885175541;7210.819729506804;7259.491835571671;3452;3887.3391523441646;3903.863364782219;3962.3660214365564;4467.910641787211;5506.063969125338;6960.860782784417;6998.790036298919;7015.579065256101;7049.436045116276;5897.498712609477;6325.679018515316;6639.601623902581;6737.528373014038;6778;6630.900536492053;6666.300038495888;6666.6000239914565;6675.499337549801;6785.864282714417;6106.389361063858;6228.56400051992;6600.372964568358;6616.272210056986;6632.436242919406;5900.911486327623;5906.911396328973;6673.332666733288;6694.866102677822;6799.89800153008;6279.560430769802;6555.951047832324;6576.76981305652;6603.20761508622;6608.074869518176 04:39:14 INFO - PID 1646 | |5;yui.html;829.7469271872084;840.4873027453257;842.6039761312184;865.1521508921257;869.0049892872661;16210.351585936563;16914.23885925135;17009.829901700978;19041;19642.214311427524;17818.732719009193;18438;18579.163937622805;18598.81401185991;18609.348672796423;971.553085580632;1024.405844610128;1026.702256345659;1027.0551092994426;1028.388109075099;1012.7063151686002;1025.3386565665141;1030.9072183503483;1039.7660526381578;1044.1438020822927;1059.3855563772993;1073.7047311989227;1074.8763892152424;1075.6557901471533;1076.3757020927883;1067.6103222323873;1067.6316670748618;1068.6740544134036;1069.198101423932;1070.6252811515985;777.6422845491081;796.4544287163254;797.8643630582815;799.460364254132;800.335721351277;17156.571085722957;17520.824791752046;17692.91153544224;17695.38066167678;17940;15099;17307.480775576754;17536.29854805806;17885;17932.772101021812;14630.268486575735;14662.926685366505;14767.556973290819;14795.778063329059;14803.889708271923;3571.3035957987945;3666.3400587894166;3723.3670276052867;3725.720570957189;3726.031231879731;136710;138814;139014;140109.2994535031;140576.99999999898;13708.794368084487;13713.245771482465;13791.172529648253;13841.861581384259;13919.721605568035;21731.782682173292;22612.0955161795;23905.04379824822;25639.71801409922;26852.86573567159;6952.200496942916;7819.257170568787;7991.4006449515355;8009.3192078673965;8025.518468891885;3988.561258261589;4038.676905847525;4043.555208927016;4088.34586466164;4099.262132816122;4268.210381079511;4304.139172165579;4363.040131171138;4382.649388048949;4406.096750166208;731.8838770874353;876.0407353947418;877.3332267476763;881.4623079921242;884;638.5466318913624;666.8432918264205;675.152683382352;677.1704661789367;685.4002747595854;11140.944295278472;12072.456739446747;12115.273083615011;12268.386580670931;12470.127091103535;647.397919934456;650.5738741124571;654.8428377189535;666.9133012708317;680.1600023970393;5534.861628459321;5537.58468114893;5649.265595472562;5678.176664383724;5771.913421298684;124116;137207.94188087186;138162.61837381698;141789.29105354613;142699.2865035689;90681.54659226662;105588;106895.46552267189;109002;109967.45016274868;84375.578122109;94602.52698736462;94922;95949.52025239968;97046.51476742713;11296.136137537544;11818.054555635701;11864.76270474603;12959.613324364203;12990.610281691565;32707.855225067007;33575.16062098467;43746.562534375524;44358.89102772457;45233.32150017686;59687.701561491915;64244.67877660582;75536.24463755293;77334.613326933;78477;16860.831391686417;16868.662626747642;16932.15339233058;17381.739273910647;17553.473395798148;18768.7184692227;18815.254423563314;19103.23587056558;19130.61738765189;19135.138918748413;17050.573735656708;17191.140442978052;17631.294748209926;18057.097145142954;18206.544836379195;17315.30738770435;17330;17334.393296235077;17492.125393730515;17602.559936001704;16512.33950641961;16720;17311.220995055002;17326.39357622477;17333.913330433268;10382.480875956324;11852.940735296268;11982.58060967862;11997.280163190237;12634.810477842655;13414.798778018532;13656.180629162282;13676.93161534186;13711.314434278047;13737 04:39:14 INFO - PID 1646 | __end_tp_report 04:39:14 INFO - PID 1646 | __start_cc_report 04:39:14 INFO - PID 1646 | _x_x_mozilla_cycle_collect,400 04:39:14 INFO - PID 1646 | __end_cc_report 04:39:14 INFO - PID 1646 | __startTimestamp1495625954215__endTimestamp 04:39:14 INFO - PID 1646 | 04:39:14 INFO - PID 1646 | ------- Summary: start ------- 04:39:14 INFO - PID 1646 | Number of tests: 6 04:39:14 INFO - PID 1646 | 04:39:14 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6142.63 Median:6384.97 stddev:748.88 (11.7%) stddev-sans-first:713.99 04:39:14 INFO - PID 1646 | Values: 3171.7 3414.7 3586.8 3598.1 3612.5 4973.5 5105.9 6024.3 6109.6 6250.9 5535.3 5784.8 6150.2 6192.1 6292.5 5385.2 5401.1 5488.9 5488.9 5503.5 4480.9 5351.2 5480.5 5524.0 5531.8 5474.9 5531.5 5545.9 5550.2 5572.7 5334.9 5388.1 5424.6 5441.7 5446.3 5335.4 5360.1 5394.5 5428.9 5450.3 6103.2 6259.3 6264.7 6276.2 6278.5 6368.2 6485.0 6508.9 6613.5 6618.1 6767.9 6791.4 6796.1 6826.9 6832.2 6345.1 6346.2 6351.7 6370.0 6374.4 6701.1 6818.0 6915.5 6938.2 6956.1 6385.0 6405.2 6508.2 6534.7 6554.7 6412.5 6498.9 6626.9 6743.3 6755.1 6646.6 6654.9 6656.5 6662.1 6670.4 6390.6 6691.0 6704.1 6704.8 6719.0 4526.9 5263.6 5306.0 5336.5 5365.1 4789.9 4806.9 4815.4 4837.6 4905.0 6194.6 6336.0 6355.3 6366.0 6373.2 6430.3 6468.0 6640.8 6700.6 6809.2 6198.9 6342.1 6382.4 6389.5 6401.2 5994.8 5995.6 6000.2 6016.6 6021.2 6718.0 6814.4 6815.3 6973.7 7024.2 6765.7 6778.2 6780.3 6809.6 6810.8 6860.1 6981.8 6985.4 6990.1 7005.4 6390.9 6622.7 6634.6 6673.4 6715.4 6564.7 6665.6 6711.5 6755.4 6813.3 6496.2 6499.2 6507.9 6521.0 6548.5 6300.1 6348.6 6354.3 6417.8 6444.4 6377.6 6409.6 6417.4 6419.6 6420.9 6365.4 6377.1 6431.2 6479.5 6574.0 6281.3 6370.2 6389.8 6400.6 6401.9 04:39:14 INFO - PID 1646 | 04:39:14 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:44561.30 Median:19020.25 stddev:76331.31 (401.3%) stddev-sans-first:76474.60 04:39:14 INFO - PID 1646 | Values: 762.1 813.1 826.1 1022.7 1265.6 39233.8 39839.0 39901.6 39915.0 40194.0 40070.8 40350.2 40511.8 40605.0 40669.5 7722.9 7868.7 8250.3 8445.5 8460.5 8320.3 8356.8 8410.7 8420.1 8428.0 8371.7 8443.7 8447.6 8469.5 8474.1 8318.3 8378.2 8400.0 8451.0 8468.6 8126.1 8143.8 8168.3 8176.6 8196.1 37533.4 37631.0 37670.1 37709.4 37911.0 3518.0 3556.5 3560.2 3563.4 3565.3 30586.5 30829.2 30970.4 30988.1 31044.4 2986.3 3071.0 3084.4 3087.9 3110.7 251289.0 254638.0 280368.0 309997.0 311262.0 48260.3 48691.8 48719.8 48761.0 48775.3 39630.2 50908.5 51047.5 54450.0 54500.0 11458.6 13465.9 13502.1 13584.6 13622.5 10866.0 10934.6 10938.6 10956.7 10969.9 12385.9 12880.9 13994.3 13996.2 14079.9 5529.8 5824.9 5982.3 6189.7 6203.4 980.8 986.6 1024.7 1048.7 1050.3 22089.8 22504.7 22560.4 23730.9 24882.9 1044.2 1053.9 1113.1 1133.4 1149.7 11702.1 12514.7 12625.0 12643.6 12818.7 324277.4 325650.0 326403.4 328920.0 329446.7 56619.2 56627.9 56867.1 57006.9 57039.0 1488.1 1514.3 1524.4 1525.3 1533.4 22490.7 23096.5 23986.9 24103.5 24225.0 39173.4 39329.6 39336.2 39365.0 39369.6 259498.0 259655.0 259885.0 260020.0 260193.4 34522.5 35309.8 36503.6 36695.8 36844.1 44813.3 46088.3 46217.5 46267.0 46386.3 25465.7 29691.4 31937.0 31962.0 32014.5 24900.3 28030.6 31726.8 31869.8 32469.2 38782.6 38973.2 39019.6 39034.6 39042.0 30006.4 31767.0 32368.4 32374.4 32525.9 22851.5 24100.9 24240.5 24451.4 24611.8 04:39:14 INFO - PID 1646 | 04:39:14 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:34629.47 Median:2190.91 stddev:88895.83 (4057.5%) stddev-sans-first:89107.99 04:39:14 INFO - PID 1646 | Values: 372.5 406.9 423.5 431.2 469.9 15936.9 20643.3 21088.0 21506.5 21841.8 18849.5 22205.8 22643.9 22683.0 22974.0 5777.8 5792.3 5985.5 6014.5 6035.4 5873.7 5904.0 5971.1 5982.9 5992.9 5775.4 6020.2 6036.4 6061.7 6095.8 5982.9 5994.9 6000.7 6028.5 6040.2 5359.6 5385.4 5404.1 5410.7 5419.5 26283.7 26413.3 26500.5 26550.3 26638.3 2074.8 2187.3 2188.2 2189.4 2192.4 17534.6 17611.8 17841.6 17928.8 18312.5 1986.3 1995.7 1997.3 2011.4 2011.9 166201.0 169310.0 170160.3 171022.0 175270.0 11950.6 12409.0 12433.6 12462.8 12582.7 16103.4 16135.1 16154.0 16155.7 16159.2 7151.2 7174.6 7354.6 8346.2 8619.6 6720.0 7783.3 8072.2 8075.4 8238.1 7151.9 7692.5 7807.5 7808.1 7852.1 1819.6 2121.2 2143.4 2144.6 2152.8 853.5 917.5 926.7 953.9 961.6 16507.3 19980.3 20001.1 20109.4 20171.6 923.5 977.9 984.2 997.1 1006.1 10027.3 10282.5 10393.9 10431.9 10575.6 506116.0 517707.0 520521.0 520979.0 523535.0 120974.4 123568.0 123769.4 126380.7 129382.4 1097.3 1125.7 1157.5 1160.2 1160.9 16823.7 17250.2 17480.7 17518.2 17523.9 55949.4 55965.0 56381.0 56426.0 56539.4 72027.3 76651.6 77204.2 77374.2 77775.6 8575.3 8661.5 8788.7 8837.2 8866.7 13358.2 13410.2 13544.0 13942.7 14002.4 10031.5 11460.5 11599.9 11656.2 11875.1 10864.2 11081.9 11097.7 11104.2 11235.8 11469.7 11496.7 11921.6 11968.7 12097.8 7961.9 8467.8 8657.7 8750.0 8807.5 11665.5 12051.8 12151.9 12153.8 12167.8 04:39:14 INFO - PID 1646 | 04:39:14 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:7180.71 Median:3836.12 stddev:4567.01 (119.1%) stddev-sans-first:4558.98 04:39:14 INFO - PID 1646 | Values: 1373.6 1441.0 1441.8 1445.2 1445.8 11648.3 11921.5 12140.6 12143.8 12329.0 12033.6 12109.5 12130.3 12291.1 12308.4 2535.6 2557.3 2581.9 2584.8 2588.8 2508.7 2550.0 2552.6 2559.7 2568.4 2532.6 2536.4 2555.8 2567.2 2579.0 2480.1 2498.2 2501.6 2504.7 2511.6 2048.3 2502.2 2511.5 2517.0 2579.5 11464.9 11650.7 11653.5 11667.3 11706.2 6453.4 6681.1 6702.1 6702.2 6728.3 4032.5 4210.7 4272.0 4282.9 4292.8 1229.1 1251.8 1254.4 1255.0 1280.9 16299.7 16332.8 16540.0 16594.4 16734.4 5170.6 5947.4 5989.3 6150.5 6151.2 13706.2 13830.2 13834.5 13856.9 13904.9 6585.0 6716.1 6716.5 6719.1 6756.1 6070.4 6114.8 6161.7 6174.1 6217.9 4607.9 4721.7 4724.5 4732.3 4735.6 1323.7 1432.1 1474.5 1476.5 1484.5 793.3 809.3 826.8 832.6 862.8 6217.8 6260.7 6393.3 6418.2 6475.7 514.7 606.6 608.6 617.9 622.4 3655.6 4016.7 4259.9 4338.8 4469.3 14299.4 14658.3 14928.6 15049.9 15144.0 11826.0 14741.9 14957.4 14981.1 15065.2 7237.0 8404.6 8592.7 8616.0 8639.7 5769.6 7153.6 7183.9 7269.2 7339.9 13347.7 13525.8 13546.3 13636.6 13787.0 13865.3 13982.7 14026.7 14168.2 14204.7 7883.1 9229.1 9510.6 9538.3 9548.5 10653.5 10797.4 10810.5 10812.0 10826.5 7682.8 8017.7 8097.8 8126.1 8189.8 8903.5 8982.8 9013.9 9031.2 9063.6 8123.8 8131.9 8222.5 8223.1 8229.7 6080.4 6113.0 6168.8 6197.3 6277.7 5513.2 6113.9 6247.8 6315.5 6324.7 04:39:14 INFO - PID 1646 | 04:39:14 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5391.53 Median:6069.64 stddev:1727.19 (28.5%) stddev-sans-first:1690.53 04:39:14 INFO - PID 1646 | Values: 376.9 402.2 404.5 404.8 409.5 6389.8 6414.2 6416.9 6458.5 6511.8 5876.7 6029.2 6116.0 6228.0 6254.7 4430.6 4729.2 4731.6 4756.8 4765.1 4308.9 4349.2 4675.0 4753.5 4763.3 4014.3 4311.9 4682.5 4704.8 4715.4 3849.5 4619.7 4642.5 4718.7 4728.3 3795.3 4032.9 4129.2 4178.7 4191.5 4729.0 5986.9 6016.7 6089.7 6129.0 4732.0 5349.8 5551.6 5590.6 5595.6 6133.0 6519.4 6685.5 6746.5 6761.6 4721.8 4733.4 4753.7 4761.9 4763.2 5528.0 7085.2 7088.8 7096.6 7107.1 3734.0 4396.3 4456.8 4460.9 4469.5 4524.1 4529.5 4532.0 4533.9 4552.2 4097.4 5133.6 5265.2 5354.7 5386.6 6698.7 6704.6 6721.3 6722.8 6834.2 1535.0 1544.6 1550.1 1553.9 1557.3 1004.8 1012.9 1018.8 1021.6 1033.8 5350.4 5618.7 6397.7 6430.7 6461.8 6153.1 6764.1 6967.4 6979.9 7004.7 5345.0 6315.6 6325.4 6384.5 6384.9 2798.2 2912.0 3303.6 3535.7 3632.4 6049.6 6209.9 6791.8 6954.0 7151.6 7158.0 7176.7 7191.6 7193.2 7203.2 5602.2 6444.7 6860.9 6966.9 7183.7 6959.4 6963.5 6988.5 6997.1 7013.5 6612.7 6764.6 6813.9 6825.0 6912.8 5742.9 6923.7 7023.0 7210.8 7259.5 3452.0 3887.3 3903.9 3962.4 4467.9 5506.1 6960.9 6998.8 7015.6 7049.4 5897.5 6325.7 6639.6 6737.5 6778.0 6630.9 6666.3 6666.6 6675.5 6785.9 6106.4 6228.6 6600.4 6616.3 6632.4 5900.9 5906.9 6673.3 6694.9 6799.9 6279.6 6556.0 6576.8 6603.2 6608.1 04:39:14 INFO - PID 1646 | 04:39:14 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:24430.87 Median:17757.06 stddev:36469.03 (205.4%) stddev-sans-first:36528.28 04:39:14 INFO - PID 1646 | Values: 829.7 840.5 842.6 865.2 869.0 16210.4 16914.2 17009.8 19041.0 19642.2 17818.7 18438.0 18579.2 18598.8 18609.3 971.6 1024.4 1026.7 1027.1 1028.4 1012.7 1025.3 1030.9 1039.8 1044.1 1059.4 1073.7 1074.9 1075.7 1076.4 1067.6 1067.6 1068.7 1069.2 1070.6 777.6 796.5 797.9 799.5 800.3 17156.6 17520.8 17692.9 17695.4 17940.0 15099.0 17307.5 17536.3 17885.0 17932.8 14630.3 14662.9 14767.6 14795.8 14803.9 3571.3 3666.3 3723.4 3725.7 3726.0 136710.0 138814.0 139014.0 140109.3 140577.0 13708.8 13713.2 13791.2 13841.9 13919.7 21731.8 22612.1 23905.0 25639.7 26852.9 6952.2 7819.3 7991.4 8009.3 8025.5 3988.6 4038.7 4043.6 4088.3 4099.3 4268.2 4304.1 4363.0 4382.6 4406.1 731.9 876.0 877.3 881.5 884.0 638.5 666.8 675.2 677.2 685.4 11140.9 12072.5 12115.3 12268.4 12470.1 647.4 650.6 654.8 666.9 680.2 5534.9 5537.6 5649.3 5678.2 5771.9 124116.0 137207.9 138162.6 141789.3 142699.3 90681.5 105588.0 106895.5 109002.0 109967.5 84375.6 94602.5 94922.0 95949.5 97046.5 11296.1 11818.1 11864.8 12959.6 12990.6 32707.9 33575.2 43746.6 44358.9 45233.3 59687.7 64244.7 75536.2 77334.6 78477.0 16860.8 16868.7 16932.2 17381.7 17553.5 18768.7 18815.3 19103.2 19130.6 19135.1 17050.6 17191.1 17631.3 18057.1 18206.5 17315.3 17330.0 17334.4 17492.1 17602.6 16512.3 16720.0 17311.2 17326.4 17333.9 10382.5 11852.9 11982.6 11997.3 12634.8 13414.8 13656.2 13676.9 13711.3 13737.0 04:39:14 INFO - PID 1646 | -------- Summary: end -------- 04:39:14 INFO - PID 1646 | 04:39:14 INFO - TEST-INFO | 1646: exit 0 04:39:14 INFO - TEST-OK | dromaeo_css | took 1142700ms 04:39:14 INFO - TEST-START | kraken 04:39:14 INFO - operating with platform_type : mac_ 04:39:14 INFO - Initialising browser for kraken test... 04:39:14 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/tmp1jkyLY/profile 04:39:14 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 04:39:48 INFO - TEST-INFO | 1656: killed by SIGTERM 04:39:48 INFO - Browser initialized. 04:39:48 INFO - Running cycle 1/1 for kraken test... 04:39:48 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/T/tmp1jkyLY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:39:50 INFO - PID 1660 | 2017-05-24 04:39:50.165 plugin-container[1661:10732] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 04:39:50 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:39:50 INFO - PID 1660 | 2017-05-24 04:39:50.165 plugin-container[1661:10732] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 04:39:50 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:39:50 INFO - PID 1660 | 2017-05-24 04:39:50.165 plugin-container[1661:10732] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:39:50 INFO - PID 1660 | 2017-05-24 04:39:50.712 plugin-container[1662:10777] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 04:39:50 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:39:50 INFO - PID 1660 | 2017-05-24 04:39:50.713 plugin-container[1662:10777] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9717, name = 'com.apple.CFPasteboardClient' 04:39:50 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:39:50 INFO - PID 1660 | 2017-05-24 04:39:50.713 plugin-container[1662:10777] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:40:00 INFO - PID 1660 | RSS: Main: 246390784 04:40:00 INFO - PID 1660 | 04:40:02 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 04:40:03 INFO - PID 1660 | RSS: Main: 260587520 04:40:03 INFO - PID 1660 | 04:40:06 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 04:40:06 INFO - PID 1660 | RSS: Main: 269217792 04:40:06 INFO - PID 1660 | 04:40:10 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 04:40:10 INFO - PID 1660 | RSS: Main: 272240640 04:40:10 INFO - PID 1660 | 04:40:12 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 04:40:13 INFO - PID 1660 | RSS: Main: 273440768 04:40:13 INFO - PID 1660 | 04:40:15 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:40:16 INFO - PID 1660 | RSS: Main: 273764352 04:40:16 INFO - PID 1660 | 04:40:18 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 04:40:19 INFO - PID 1660 | RSS: Main: 278732800 04:40:19 INFO - PID 1660 | 04:40:22 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 04:40:23 INFO - PID 1660 | RSS: Main: 282976256 04:40:23 INFO - PID 1660 | 04:40:26 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 04:40:26 INFO - PID 1660 | RSS: Main: 282025984 04:40:26 INFO - PID 1660 | 04:40:28 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:40:28 INFO - PID 1660 | RSS: Main: 281792512 04:40:28 INFO - PID 1660 | 04:40:30 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:40:31 INFO - PID 1660 | RSS: Main: 281870336 04:40:31 INFO - PID 1660 | 04:40:33 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:40:34 INFO - PID 1660 | RSS: Main: 281653248 04:40:34 INFO - PID 1660 | 04:40:37 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:40:37 INFO - PID 1660 | RSS: Main: 281235456 04:40:37 INFO - PID 1660 | 04:40:40 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:40:41 INFO - PID 1660 | RSS: Main: 281972736 04:40:41 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 04:40:43 INFO - PID 1660 | RSS: Main: 283086848 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | __start_tp_report 04:40:43 INFO - PID 1660 | _x_x_mozilla_page_load 04:40:43 INFO - PID 1660 | _x_x_mozilla_page_load_details 04:40:43 INFO - PID 1660 | |i|pagename|runs| 04:40:43 INFO - PID 1660 | |0;ai-astar;103;117;116;115;115;115;114;115;115;114 04:40:43 INFO - PID 1660 | |1;audio-beat-detection;132;110;112;161;138;145;142;111;139;112 04:40:43 INFO - PID 1660 | |2;audio-dft;200;201;217;237;215;225;220;196;196;197 04:40:43 INFO - PID 1660 | |3;audio-fft;73;61;58;81;59;58;60;61;74;65 04:40:43 INFO - PID 1660 | |4;audio-oscillator;92;104;124;72;69;70;92;71;72;88 04:40:43 INFO - PID 1660 | |5;imaging-gaussian-blur;92;89;88;87;90;93;89;99;88;88 04:40:43 INFO - PID 1660 | |6;imaging-darkroom;96;97;123;100;101;154;143;96;108;109 04:40:43 INFO - PID 1660 | |7;imaging-desaturate;94;93;91;92;96;92;92;93;131;100 04:40:43 INFO - PID 1660 | |8;json-parse-financial;49;49;50;50;50;51;50;49;52;51 04:40:43 INFO - PID 1660 | |9;json-stringify-tinderbox;55;66;55;56;55;64;56;57;59;57 04:40:43 INFO - PID 1660 | |10;stanford-crypto-aes;82;82;107;112;89;87;90;90;123;82 04:40:43 INFO - PID 1660 | |11;stanford-crypto-ccm;142;141;139;135;137;138;139;135;144;158 04:40:43 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;187;188;188;185;183;187;188;178;186;189 04:40:43 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;68;69;73;89;92;100;72;71;67;68 04:40:43 INFO - PID 1660 | __end_tp_report 04:40:43 INFO - PID 1660 | __start_cc_report 04:40:43 INFO - PID 1660 | _x_x_mozilla_cycle_collect,1054 04:40:43 INFO - PID 1660 | __end_cc_report 04:40:43 INFO - PID 1660 | __startTimestamp1495626043380__endTimestamp 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | ------- Summary: start ------- 04:40:43 INFO - PID 1660 | Number of tests: 14 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.93 (3.4%) stddev-sans-first:0.93 04:40:43 INFO - PID 1660 | Values: 103.0 117.0 116.0 115.0 115.0 115.0 114.0 115.0 115.0 114.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:138.50 stddev:17.91 (12.9%) stddev-sans-first:18.99 04:40:43 INFO - PID 1660 | Values: 132.0 110.0 112.0 161.0 138.0 145.0 142.0 111.0 139.0 112.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:210.40 Median:216.00 stddev:14.41 (6.7%) stddev-sans-first:14.78 04:40:43 INFO - PID 1660 | Values: 200.0 201.0 217.0 237.0 215.0 225.0 220.0 196.0 196.0 197.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:65.00 Median:63.00 stddev:8.11 (12.9%) stddev-sans-first:8.07 04:40:43 INFO - PID 1660 | Values: 73.0 61.0 58.0 81.0 59.0 58.0 60.0 61.0 74.0 65.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:72.00 stddev:18.26 (25.4%) stddev-sans-first:19.22 04:40:43 INFO - PID 1660 | Values: 92.0 104.0 124.0 72.0 69.0 70.0 92.0 71.0 72.0 88.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:89.50 stddev:3.59 (4.0%) stddev-sans-first:3.76 04:40:43 INFO - PID 1660 | Values: 92.0 89.0 88.0 87.0 90.0 93.0 89.0 99.0 88.0 88.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:112.70 Median:148.50 stddev:20.73 (14.0%) stddev-sans-first:21.09 04:40:43 INFO - PID 1660 | Values: 96.0 97.0 123.0 100.0 101.0 154.0 143.0 96.0 108.0 109.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:97.40 Median:92.50 stddev:12.09 (13.1%) stddev-sans-first:12.76 04:40:43 INFO - PID 1660 | Values: 94.0 93.0 91.0 92.0 96.0 92.0 92.0 93.0 131.0 100.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.99 (2.0%) stddev-sans-first:0.97 04:40:43 INFO - PID 1660 | Values: 49.0 49.0 50.0 50.0 50.0 51.0 50.0 49.0 52.0 51.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:58.00 Median:57.00 stddev:3.92 (6.9%) stddev-sans-first:4.00 04:40:43 INFO - PID 1660 | Values: 55.0 66.0 55.0 56.0 55.0 64.0 56.0 57.0 59.0 57.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:84.50 stddev:14.42 (17.1%) stddev-sans-first:14.58 04:40:43 INFO - PID 1660 | Values: 82.0 82.0 107.0 112.0 89.0 87.0 90.0 90.0 123.0 82.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:140.80 Median:140.00 stddev:6.70 (4.8%) stddev-sans-first:7.09 04:40:43 INFO - PID 1660 | Values: 142.0 141.0 139.0 135.0 137.0 138.0 139.0 135.0 144.0 158.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.90 Median:187.50 stddev:3.28 (1.8%) stddev-sans-first:3.46 04:40:43 INFO - PID 1660 | Values: 187.0 188.0 188.0 185.0 183.0 187.0 188.0 178.0 186.0 189.0 04:40:43 INFO - PID 1660 | 04:40:43 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.90 Median:71.50 stddev:12.02 (16.8%) stddev-sans-first:12.31 04:40:43 INFO - PID 1660 | Values: 68.0 69.0 73.0 89.0 92.0 100.0 72.0 71.0 67.0 68.0 04:40:43 INFO - PID 1660 | -------- Summary: end -------- 04:40:43 INFO - PID 1660 | 04:40:44 INFO - TEST-INFO | 1660: exit 0 04:40:44 INFO - TEST-OK | kraken | took 89349ms 04:40:44 INFO - SUITE-END | took 1232s 04:40:44 INFO - Completed test suite (00:20:32) 04:40:44 INFO - javascript benchmark 04:40:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7923.593364320447, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3171.6669749676284, 3414.7268218542517, 3586.7847929124273, 3598.118460977061, 3612.530371051763, 4973.5026497350245, 5105.9022310203345, 6024.337322894478, 6109.633421994683, 6250.906236406452, 5535.335759708831, 5784.797532086378, 6150.1697270868535, 6192.102145188945, 6292.465140463055, 5385.192221166817, 5401.135818269068, 5488.862778430531, 5488.91766623501, 5503.477169668875, 4480.865574032784, 5351.2240725095035, 5480.506754392104, 5524.0, 5531.806386776464, 5474.91787623186, 5531.502164805167, 5545.916811247835, 5550.222968784374, 5572.721363931808, 5334.893302133935, 5388.084025715638, 5424.647397919122, 5441.673499589998, 5446.319210098738, 5335.439778823206, 5360.115580929142, 5394.460553944614, 5428.891422171574, 5450.264214331054, 6103.23709536308, 6259.280182778994, 6264.655443950581, 6276.215473065867, 6278.4663303619245, 6368.172137622126, 6485.0, 6508.9023664645065, 6613.470922326251, 6618.106555615027, 6767.864642707118, 6791.3548212919695, 6796.116504854337, 6826.93173068268, 6832.180138383376, 6345.111684364193, 6346.2067241594805, 6351.745930162834, 6369.968150159312, 6374.394432528902, 6701.1288532491435, 6818.011388348662, 6915.51591388598, 6938.202106757686, 6956.130483689539, 6384.968075159594, 6405.19935008124, 6508.21901371829, 6534.705938232791, 6554.70503827329, 6412.454941330039, 6498.870022599522, 6626.86746265072, 6743.325667433218, 6755.121834161528, 6646.567973081735, 6654.86690266192, 6656.534042617067, 6662.100616416722, 6670.366315200048, 6390.648514331663, 6690.966545167243, 6704.061431399608, 6704.765333213411, 6719.0, 4526.88682782933, 5263.552523056113, 5305.973470132624, 5336.46635336471, 5365.08793505105, 4789.87437952081, 4806.927896081562, 4815.398075240595, 4837.637177211724, 4904.975475122602, 6194.628322300677, 6336.0, 6355.3009168991375, 6365.968170159213, 6373.235211774524, 6430.260520040255, 6468.0, 6640.800775976729, 6700.564463309869, 6809.182898052166, 6198.876022479525, 6342.080398342215, 6382.393672601095, 6389.520785941073, 6401.199850018747, 5994.7602095916545, 5995.61028533144, 6000.249968753906, 6016.608920420159, 6021.157038014681, 6717.966410167918, 6814.420774234246, 6815.318468153145, 6973.651317434006, 7024.227334993249, 6765.729370825309, 6778.2205046420295, 6780.25417204117, 6809.625470598967, 6810.795676129724, 6860.0738900247825, 6981.79054628362, 6985.406240469617, 6990.09128813251, 7005.404540614105, 6390.9041364378645, 6622.701978410887, 6634.568753031038, 6673.432758215412, 6715.3620406061345, 6564.671766411756, 6665.633390163587, 6711.4966377520905, 6755.3582409671, 6813.250542440229, 6496.220453545605, 6499.1876015498065, 6507.869842603216, 6521.0, 6548.476121910331, 6300.054991751274, 6348.587341822767, 6354.26925903527, 6417.7753778617525, 6444.355564443518, 6377.649229292433, 6409.551331406757, 6417.39034791694, 6419.550631455753, 6420.903686444613, 6365.395287447685, 6377.075324078076, 6431.228252609717, 6479.514036447191, 6574.0, 6281.340459251713, 6370.235571731237, 6389.840253993501, 6400.647964362005, 6401.903971440339], "value": 6090.927537681652, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.0931092000519, 813.134012276925, 826.0830478169231, 1022.739201503617, 1265.55072949103, 39233.80383098084, 39839.0, 39901.6034438795, 39915.0, 40194.0, 40070.799646001804, 40350.19299614006, 40511.79744101283, 40605.0, 40669.49288113903, 7722.884156737639, 7868.724594639189, 8250.339972802192, 8445.451045682043, 8460.534670593115, 8320.251177394033, 8356.83286334273, 8410.705625303115, 8420.115887831782, 8427.957860210721, 8371.70238613013, 8443.662253509852, 8447.577621118919, 8469.534175620369, 8474.11021842707, 8318.251357377865, 8378.245957863792, 8400.033996090468, 8451.0, 8468.576571171418, 8126.065502467174, 8143.79640508992, 8168.264856162974, 8176.550289734062, 8196.098429172785, 37533.436998445046, 37631.0, 37670.05824854373, 37709.43435848464, 37911.0, 3518.0149558123767, 3556.5376501054957, 3560.1633616100194, 3563.4120370138894, 3565.286942611462, 30586.541201881988, 30829.229269268224, 30970.38059238825, 30988.0703578893, 31044.379112417624, 2986.3131479759777, 3071.01727447217, 3084.4139613473367, 3087.9073627791204, 3110.7355874750674, 251289.0, 254638.0, 280368.0, 309997.0, 311262.0, 48260.27609585859, 48691.756541217066, 48719.78200544944, 48761.0, 48775.26837097447, 39630.21664025126, 50908.49091509038, 51047.48952510502, 54450.0, 54500.0, 11458.598949036743, 13465.932670336782, 13502.054856159975, 13584.592462226148, 13622.455101796015, 10866.0, 10934.56261749521, 10938.617148399928, 10956.726081847859, 10969.945150274358, 12385.885270325714, 12880.871191288155, 13994.30028498571, 13996.230207338695, 14079.85920140806, 5529.811090615537, 5824.912626310609, 5982.341942386334, 6189.690515474208, 6203.410675985864, 980.7793246519476, 986.6201512417771, 1024.7335692719798, 1048.7483004079118, 1050.3435352904435, 22089.779102208773, 22504.662430063563, 22560.435989100402, 23730.881345593505, 24882.875585621958, 1044.2011860926398, 1053.873535175784, 1113.0817075912405, 1133.4106264742288, 1149.672343382132, 11702.063834893188, 12514.68713282157, 12625.0, 12643.557475488315, 12818.743625127632, 324277.37861310545, 325650.0, 326403.36798315856, 328920.0, 329446.7055329464, 56619.15071273934, 56627.86744265092, 56867.14699279431, 57006.859862802514, 57039.0, 1488.1175313228775, 1514.3412615512289, 1524.382625036884, 1525.260248779341, 1533.4402942925751, 22490.66264006074, 23096.538069238857, 23986.880065599562, 24103.51792964096, 24225.0, 39173.41239881347, 39329.606703933736, 39336.213275734895, 39365.0, 39369.60630393774, 259498.0, 259655.0, 259885.0, 260020.0, 260193.3980660169, 34522.48216276808, 35309.823450882584, 36503.63496365002, 36695.81652091722, 36844.078898027765, 44813.32780008237, 46088.308675370565, 46217.53782462132, 46267.0, 46386.30420543762, 25465.653700434912, 29691.437027555967, 31937.0, 31962.0, 32014.51978220282, 24900.252992410256, 28030.579541306488, 31726.841365793025, 31869.8406507966, 32469.18827029343, 38782.6121738779, 38973.220535589695, 39019.6098039016, 39034.609653904234, 39042.0, 30006.399872002876, 31767.04698859038, 32368.35263294768, 32374.410047195695, 32525.861594844067, 22851.542969140857, 24100.87949560241, 24240.515189695754, 24451.38871528226, 24611.753882460947], "value": 17519.00539943748, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.4729507746539, 406.9115117061859, 423.47700589771625, 431.1613910943217, 469.92716129, 15936.920315398436, 20643.277485288, 21088.0, 21506.462338441546, 21841.781582184172, 18849.528761780963, 22205.77794222057, 22643.886780566114, 22683.0, 22973.96617152228, 5777.82666520004, 5792.304923409176, 5985.4912332451795, 6014.548908831834, 6035.366286539916, 5873.70631468427, 5903.9668058089665, 5971.10433434986, 5982.8803423931495, 5992.940070599282, 5775.393583673716, 6020.247469066367, 6036.396360363972, 6061.696915154247, 6095.786647467317, 5982.910256346158, 5994.880102397971, 6000.729967151488, 6028.5478589106, 6040.184575082351, 5359.5712343012465, 5385.434529374398, 5404.054290499186, 5410.729463526808, 5419.512243898068, 26283.73716262832, 26413.33966650849, 26500.46999060027, 26550.336241593923, 26638.33404164892, 2074.7925207479284, 2187.278198194592, 2188.1575593396497, 2189.39791557323, 2192.4190089626263, 17534.561635959075, 17611.823881761145, 17841.643167136714, 17928.820711792847, 18312.450626481226, 1986.3345779163997, 1995.6806910894188, 1997.3209108903043, 2011.4367976966473, 2011.8994050297426, 166201.0, 169310.0, 170160.2983970157, 171022.0, 175270.0, 11950.641480755592, 12409.0, 12433.626991190278, 12462.81305780414, 12582.685432864251, 16103.355865765237, 16135.112568808594, 16154.0, 16155.676886462204, 16159.192040397933, 7151.249118842551, 7174.605396703127, 7354.595497247703, 8346.248837604524, 8619.61211745473, 6720.0, 7783.338416234684, 8072.192780721998, 8075.394345424117, 8238.052623948202, 7151.928480715126, 7692.499987500794, 7807.531548107132, 7808.141104478503, 7852.097008844056, 1819.6087841114224, 2121.1621409543145, 2143.3784202581387, 2144.6139694854764, 2152.817010554089, 853.4879072556416, 917.5228880981986, 926.7080869526212, 953.9189168920581, 961.6393852305386, 16507.33970641161, 19980.3006894758, 20001.099950502263, 20109.39671809848, 20171.596568068555, 923.4920793563513, 977.8510250455686, 984.2175470500969, 997.1125698128672, 1006.1347241372414, 10027.348222365523, 10282.537285822156, 10393.896061039444, 10431.94784026075, 10575.576976920991, 506116.0, 517707.0, 520521.0, 520979.0, 523535.0, 120974.39512802556, 123568.0, 123769.38115309546, 126380.73619263874, 129382.35308823585, 1097.3470784882982, 1125.7467069909205, 1157.5311998640564, 1160.1646814293695, 1160.8913487619134, 16823.7476437856, 17250.223739931484, 17480.737788932922, 17518.211680474666, 17523.912380438018, 55949.44050559442, 55965.0, 56381.0, 56426.0, 56539.434605655064, 72027.27972720415, 76651.61674191593, 77204.22795772195, 77374.22625773895, 77775.61112194402, 8575.31397488187, 8661.480311181349, 8788.736337909873, 8837.204651581389, 8866.73399798007, 13358.198508089547, 13410.195388276734, 13544.0, 13942.651433714238, 14002.439902404198, 10031.498425078571, 11460.484278207334, 11599.942000289944, 11656.18406711522, 11875.109366797698, 10864.185186110915, 11081.944590276997, 11097.667069987914, 11104.222704410937, 11235.831462528233, 11469.655910322703, 11496.712582185177, 11921.642350729491, 11968.700782480508, 12097.75804483923, 7961.920380796118, 8467.788305292417, 8657.740267791976, 8750.037495875576, 8807.515586642796, 11665.475053622777, 12051.75896482048, 12151.878481215428, 12153.81769273444, 12167.84405481478], "value": 10118.455302533388, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1373.5604606525912, 1441.0201063276968, 1441.7549016667165, 1445.1834713386936, 1445.8120444342235, 11648.30110193388, 11921.523139074448, 12140.575079872206, 12143.817842732355, 12329.0, 12033.578824741136, 12109.455074521646, 12130.332831694275, 12291.078169137305, 12308.384580770971, 2535.6069809179608, 2557.309526427863, 2581.8579978101197, 2584.8190626656046, 2588.8446693198466, 2508.749125087495, 2549.992752862618, 2552.591585346345, 2559.654446649697, 2568.3707491664477, 2532.5947848344267, 2536.378587246118, 2555.8083143764197, 2567.17850287907, 2578.9742102579016, 2480.119557557068, 2498.2130628851855, 2501.6247562865624, 2504.749525047499, 2511.5604769165325, 2048.3035767839006, 2502.199296225209, 2511.54792137417, 2516.9806228477496, 2579.458313754114, 11464.885351146466, 11650.6504804856, 11653.475593598223, 11667.299962002307, 11706.239094458919, 6453.354664533556, 6681.064650948871, 6702.128723265993, 6702.195736511601, 6728.293529179464, 4032.495938007749, 4210.74735515867, 4272.0, 4282.892927676802, 4292.849750258757, 1229.07205060179, 1251.837261156044, 1254.4480428611382, 1254.956076537326, 1280.8527019392795, 16299.674006519803, 16332.75500867488, 16540.007599544067, 16594.419195328104, 16734.414295499722, 5170.6122040847085, 5947.434993675594, 5989.341172471025, 6150.538709596798, 6151.231096112986, 13706.246156461291, 13830.23933683637, 13834.515791947233, 13856.93071534636, 13904.887608991256, 6585.0, 6716.093327400836, 6716.529842911047, 6719.0593316935665, 6756.0879281297375, 6070.3929607038945, 6114.755409783559, 6161.661108639068, 6174.104754810527, 6217.937820621736, 4607.861764147081, 4721.716696998123, 4724.503927087675, 4732.290156476556, 4735.550122738403, 1323.735252949414, 1432.1120905038943, 1474.4765608209145, 1476.497990683173, 1484.5249520153664, 793.2741541489588, 809.3282575462352, 826.7726375246855, 832.6128350317132, 862.8058686795421, 6217.7823776168525, 6260.749570017239, 6393.264774550893, 6418.229812422539, 6475.708593113227, 514.7143335448861, 606.6360183889719, 608.5952841360446, 617.8918689229367, 622.4087117238641, 3655.5796083450746, 4016.6585840203325, 4259.850905218303, 4338.82644694215, 4469.262571675702, 14299.356528956221, 14658.340374683165, 14928.62678433048, 15049.924750376396, 15144.0, 11826.0, 14741.852581474262, 14957.40170393172, 14981.101133932, 15065.17141557225, 7237.0, 8404.621792019374, 8592.656293748305, 8616.009158946676, 8639.6976105836, 5769.624974376652, 7153.60655163971, 7183.8563228736175, 7269.236730143471, 7339.926600733925, 13347.66630834237, 13525.797113043509, 13546.254955977516, 13636.590902272948, 13787.0, 13865.306734663025, 13982.720345593234, 14026.719465610835, 14168.220747858966, 14204.715905682035, 7883.093444253755, 9229.077092290718, 9510.619575216913, 9538.28462865274, 9548.52257387114, 10653.467326633483, 10797.352158870493, 10810.45947702596, 10811.972862578043, 10826.458677066274, 7682.8079298018, 8017.719379821679, 8097.8380432392205, 8126.106128325998, 8189.795255118432, 8903.510306933182, 8982.820343593223, 9013.864792028256, 9031.232345250728, 9063.592138353923, 8123.79690507742, 8131.878021829559, 8222.506649601042, 8223.09545949957, 8229.670813167406, 6080.391960803884, 6112.969435152796, 6168.814935551941, 6197.25632924052, 6277.654728989949, 5513.2005859151, 6113.908291375544, 6247.750089996349, 6315.494760419064, 6324.715387807468], "value": 5388.838904629694, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.8643288416171, 402.24378169042194, 404.5246834968911, 404.82997141200696, 409.52699631925117, 6389.840253993647, 6414.230292364923, 6416.9358306416925, 6458.547901646886, 6511.772087976922, 5876.676782776945, 6029.2119565217445, 6116.042866282792, 6227.968860155693, 6254.687265636723, 4430.645548356139, 4729.2433210686295, 4731.57415832575, 4756.785944632483, 4765.094632019923, 4308.892277693051, 4349.217140914634, 4675.0, 4753.524647535253, 4763.285507173917, 4014.337634290353, 4311.922019495126, 4682.508336624639, 4704.835330763424, 4715.363425937506, 3849.538055433366, 4619.722816631013, 4642.466116396624, 4718.693284936468, 4728.314394412808, 3795.2599243147597, 4032.858849940238, 4129.215449064668, 4178.749275043507, 4191.497020357577, 4729.0, 5986.880262394728, 6016.729247183887, 6089.695515224221, 6129.0, 4731.976340118312, 5349.786008559653, 5551.584345991746, 5590.5527557795685, 5595.63628364155, 6132.969335153341, 6519.4132528072705, 6685.465162786965, 6746.494012949049, 6761.560498567626, 4721.787519561628, 4733.408323959505, 4753.691010084333, 4761.928571071437, 4763.190257656172, 5528.0, 7085.185203701589, 7088.822779430556, 7096.6451677416435, 7107.1471423429, 3733.962660373416, 4396.27461468854, 4456.752109409371, 4460.888477788081, 4469.5306992765945, 4524.122489166959, 4529.524399937959, 4532.0, 4533.9319910201375, 4552.180607490617, 4097.44684467599, 5133.640645154878, 5265.236540701653, 5354.678719276856, 5386.569074474032, 6698.665066746643, 6704.631245281459, 6721.327867213337, 6722.831929201809, 6834.179898412123, 1534.9539513814605, 1544.6215677159169, 1550.1474189195897, 1553.927789825017, 1557.268084000518, 1004.7789486313145, 1012.8987101289812, 1018.7911478146962, 1021.596469394585, 1033.7725700345911, 5350.38470575881, 5618.690971996498, 6397.744090236339, 6430.7106180222, 6461.8061458156335, 6153.138560601519, 6764.139861538018, 6967.372936435745, 6979.8604027919155, 7004.719811207597, 5345.037893179187, 6315.589486683442, 6325.399087086719, 6384.457321127757, 6384.904226436607, 2798.1532188755323, 2911.980806717632, 3303.6035675718585, 3535.744810592254, 3632.3824949758605, 6049.576529642883, 6209.85117753208, 6791.762288319885, 6954.0, 7151.570905745568, 7158.0, 7176.7488137914925, 7191.604461754549, 7193.2447093055525, 7203.171635262012, 5602.187682785973, 6444.742210311629, 6860.862782744317, 6966.860662786716, 7183.676734546958, 6959.373656370951, 6963.4777391694815, 6988.510804243654, 6997.090378250795, 7013.544119632207, 6612.669366531558, 6764.594124352555, 6813.911653008156, 6824.965875170592, 6912.758053468306, 5742.908847318996, 6923.723051078102, 7022.964885175541, 7210.819729506804, 7259.491835571671, 3452.0, 3887.3391523441646, 3903.863364782219, 3962.3660214365564, 4467.910641787211, 5506.063969125338, 6960.860782784417, 6998.790036298919, 7015.579065256101, 7049.436045116276, 5897.498712609477, 6325.679018515316, 6639.601623902581, 6737.528373014038, 6778.0, 6630.900536492053, 6666.300038495888, 6666.6000239914565, 6675.499337549801, 6785.864282714417, 6106.389361063858, 6228.56400051992, 6600.372964568358, 6616.272210056986, 6632.436242919406, 5900.911486327623, 5906.911396328973, 6673.332666733288, 6694.866102677822, 6799.89800153008, 6279.560430769802, 6555.951047832324, 6576.76981305652, 6603.20761508622, 6608.074869518176], "value": 4850.025317888696, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [829.7469271872084, 840.4873027453257, 842.6039761312184, 865.1521508921257, 869.0049892872661, 16210.351585936563, 16914.23885925135, 17009.829901700978, 19041.0, 19642.214311427524, 17818.732719009193, 18438.0, 18579.163937622805, 18598.81401185991, 18609.348672796423, 971.553085580632, 1024.405844610128, 1026.702256345659, 1027.0551092994426, 1028.388109075099, 1012.7063151686002, 1025.3386565665141, 1030.9072183503483, 1039.7660526381578, 1044.1438020822927, 1059.3855563772993, 1073.7047311989227, 1074.8763892152424, 1075.6557901471533, 1076.3757020927883, 1067.6103222323873, 1067.6316670748618, 1068.6740544134036, 1069.198101423932, 1070.6252811515985, 777.6422845491081, 796.4544287163254, 797.8643630582815, 799.460364254132, 800.335721351277, 17156.571085722957, 17520.824791752046, 17692.91153544224, 17695.38066167678, 17940.0, 15099.0, 17307.480775576754, 17536.29854805806, 17885.0, 17932.772101021812, 14630.268486575735, 14662.926685366505, 14767.556973290819, 14795.778063329059, 14803.889708271923, 3571.3035957987945, 3666.3400587894166, 3723.3670276052867, 3725.720570957189, 3726.031231879731, 136710.0, 138814.0, 139014.0, 140109.2994535031, 140576.99999999898, 13708.794368084487, 13713.245771482465, 13791.172529648253, 13841.861581384259, 13919.721605568035, 21731.782682173292, 22612.0955161795, 23905.04379824822, 25639.71801409922, 26852.86573567159, 6952.200496942916, 7819.257170568787, 7991.4006449515355, 8009.3192078673965, 8025.518468891885, 3988.561258261589, 4038.676905847525, 4043.555208927016, 4088.34586466164, 4099.262132816122, 4268.210381079511, 4304.139172165579, 4363.040131171138, 4382.649388048949, 4406.096750166208, 731.8838770874353, 876.0407353947418, 877.3332267476763, 881.4623079921242, 884.0, 638.5466318913624, 666.8432918264205, 675.152683382352, 677.1704661789367, 685.4002747595854, 11140.944295278472, 12072.456739446747, 12115.273083615011, 12268.386580670931, 12470.127091103535, 647.397919934456, 650.5738741124571, 654.8428377189535, 666.9133012708317, 680.1600023970393, 5534.861628459321, 5537.58468114893, 5649.265595472562, 5678.176664383724, 5771.913421298684, 124116.0, 137207.94188087186, 138162.61837381698, 141789.29105354613, 142699.2865035689, 90681.54659226662, 105588.0, 106895.46552267189, 109002.0, 109967.45016274868, 84375.578122109, 94602.52698736462, 94922.0, 95949.52025239968, 97046.51476742713, 11296.136137537544, 11818.054555635701, 11864.76270474603, 12959.613324364203, 12990.610281691565, 32707.855225067007, 33575.16062098467, 43746.562534375524, 44358.89102772457, 45233.32150017686, 59687.701561491915, 64244.67877660582, 75536.24463755293, 77334.613326933, 78477.0, 16860.831391686417, 16868.662626747642, 16932.15339233058, 17381.739273910647, 17553.473395798148, 18768.7184692227, 18815.254423563314, 19103.23587056558, 19130.61738765189, 19135.138918748413, 17050.573735656708, 17191.140442978052, 17631.294748209926, 18057.097145142954, 18206.544836379195, 17315.30738770435, 17330.0, 17334.393296235077, 17492.125393730515, 17602.559936001704, 16512.33950641961, 16720.0, 17311.220995055002, 17326.39357622477, 17333.913330433268, 10382.480875956324, 11852.940735296268, 11982.58060967862, 11997.280163190237, 12634.810477842655, 13414.798778018532, 13656.180629162282, 13676.93161534186, 13711.314434278047, 13737.0], "value": 8769.139301818219, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1511.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 116.0, 115.0, 115.0, 115.0, 114.0, 115.0, 115.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 110.0, 112.0, 161.0, 138.0, 145.0, 142.0, 111.0, 139.0, 112.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 201.0, 217.0, 237.0, 215.0, 225.0, 220.0, 196.0, 196.0, 197.0], "value": 210.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 61.0, 58.0, 81.0, 59.0, 58.0, 60.0, 61.0, 74.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 104.0, 124.0, 72.0, 69.0, 70.0, 92.0, 71.0, 72.0, 88.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 88.0, 87.0, 90.0, 93.0, 89.0, 99.0, 88.0, 88.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 123.0, 100.0, 101.0, 154.0, 143.0, 96.0, 108.0, 109.0], "value": 112.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 91.0, 92.0, 96.0, 92.0, 92.0, 93.0, 131.0, 100.0], "value": 97.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 50.0, 50.0, 50.0, 51.0, 50.0, 49.0, 52.0, 51.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 66.0, 55.0, 56.0, 55.0, 64.0, 56.0, 57.0, 59.0, 57.0], "value": 58.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 107.0, 112.0, 89.0, 87.0, 90.0, 90.0, 123.0, 82.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 141.0, 139.0, 135.0, 137.0, 138.0, 139.0, 135.0, 144.0, 158.0], "value": 140.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 188.0, 185.0, 183.0, 187.0, 188.0, 178.0, 186.0, 189.0], "value": 185.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 73.0, 89.0, 92.0, 100.0, 72.0, 71.0, 67.0, 68.0], "value": 76.9, "unit": "score"}]}]} 04:40:44 INFO - Return code: 0 04:40:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:40:44 INFO - # TBPL SUCCESS # 04:40:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:40:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:40:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:40:44 INFO - 2017-05-24 04:40:44.689 screenresolution[1667:11155] starting screenresolution argv=screenresolution get 04:40:44 INFO - 2017-05-24 04:40:44.692 screenresolution[1667:11155] Display 0: 1600x1200x32@60 04:40:44 INFO - 2017-05-24 04:40:44.698 screenresolution[1668:11159] starting screenresolution argv=screenresolution list 04:40:44 INFO - Available Modes on Display 0 04:40:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:40:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:40:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:40:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:40:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:40:44 INFO - Intel Iris: 04:40:44 INFO - Chipset Model: Intel Iris 04:40:44 INFO - Type: GPU 04:40:44 INFO - Bus: Built-In 04:40:44 INFO - VRAM (Dynamic, Max): 1536 MB 04:40:44 INFO - Vendor: Intel (0x8086) 04:40:44 INFO - Device ID: 0x0a2e 04:40:44 INFO - Revision ID: 0x0009 04:40:44 INFO - Displays: 04:40:44 INFO - Display: 04:40:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:40:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:40:44 INFO - Display Serial Number: 1600x1200 60 04:40:44 INFO - Main Display: Yes 04:40:44 INFO - Mirror: Off 04:40:44 INFO - Online: Yes 04:40:44 INFO - Rotation: Supported 04:40:44 INFO - Return code: 0 04:40:44 INFO - Running post-action listener: _resource_record_post_action 04:40:44 INFO - [mozharness: 2017-05-24 11:40:44.936575Z] Finished run-tests step (success) 04:40:44 INFO - Running post-run listener: _resource_record_post_run 04:40:44 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 39181312; Write bytes: 565841920; Read time: 403; Write time: 1815 04:40:44 INFO - TinderboxPrint: CPU usage
53.4% 04:40:44 INFO - TinderboxPrint: I/O read bytes / time
39,181,312 / 403 04:40:44 INFO - TinderboxPrint: I/O write bytes / time
565,841,920 / 1,815 04:40:44 INFO - TinderboxPrint: CPU idle
2,339.8 (46.6%) 04:40:44 INFO - TinderboxPrint: CPU system
1,019.0 (20.3%) 04:40:44 INFO - TinderboxPrint: CPU user
1,658.9 (33.1%) 04:40:44 INFO - TinderboxPrint: Swap in / out
284,721,152 / 0 04:40:45 INFO - install - Wall time: 22s; CPU: 55.0%; Read bytes: 235401728; Write bytes: 230983680; Read time: 16891; Write time: 482 04:40:45 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 33031168; Write bytes: 316413952; Read time: 323; Write time: 1264 04:40:45 INFO - Running post-run listener: _upload_blobber_files 04:40:45 INFO - Blob upload gear active. 04:40:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:40:45 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 04:40:45 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'] 04:40:45 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 04:40:45 INFO - (blobuploader) - INFO - Open directory for files ... 04:40:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:40:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:40:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:47 INFO - (blobuploader) - INFO - Done attempting. 04:40:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:40:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:40:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:48 INFO - (blobuploader) - INFO - Done attempting. 04:40:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:40:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:40:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:49 INFO - (blobuploader) - INFO - Done attempting. 04:40:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:40:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:40:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:40:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:40:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:40:51 INFO - (blobuploader) - INFO - Done attempting. 04:40:51 INFO - (blobuploader) - INFO - Iteration through files over. 04:40:51 INFO - Return code: 0 04:40:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:40:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:40:51 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2169bad4648f074206826ccbdbf309d238dad4b575ddf6657a9c30a35041acfbf29f02042069cacd7e3f5442d9e03c24e22068e72375239b0203c0cd5723da7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/507f65c5ddbbc7e1ce4d1f4d3a98e538f3d7c7428861b853b08f0414899521aca40202482d1ab1a5bd866fbb9a74243d2fa66dba3df45de5e6ebab2c40db5b37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8acc568c59e4652033b17180bfd3135134c54126cae66ee1e95494f9d7d908d59e88fbac7d41ab38267d55078fa07750c4fdba3a55bda04275753bafea2b7e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef33d13773213e332957b755aa7538e8b9975b9b3785630d85b9ab0731eb0579aa6595a7f2436459ab4260f3db6c1029d0fec77796f8e16cd42da3ea14480e56"} 04:40:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:40:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:40:51 INFO - Contents: 04:40:51 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f2169bad4648f074206826ccbdbf309d238dad4b575ddf6657a9c30a35041acfbf29f02042069cacd7e3f5442d9e03c24e22068e72375239b0203c0cd5723da7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/507f65c5ddbbc7e1ce4d1f4d3a98e538f3d7c7428861b853b08f0414899521aca40202482d1ab1a5bd866fbb9a74243d2fa66dba3df45de5e6ebab2c40db5b37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8acc568c59e4652033b17180bfd3135134c54126cae66ee1e95494f9d7d908d59e88fbac7d41ab38267d55078fa07750c4fdba3a55bda04275753bafea2b7e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef33d13773213e332957b755aa7538e8b9975b9b3785630d85b9ab0731eb0579aa6595a7f2436459ab4260f3db6c1029d0fec77796f8e16cd42da3ea14480e56"} 04:40:51 INFO - Running post-run listener: copy_logs_to_upload_dir 04:40:51 INFO - Copying logs to upload dir... 04:40:51 INFO - mkdir: /builds/slave/test/build/upload/logs 04:40:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1343.266874 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 23 secs) (at 2017-05-24 04:40:51.862462) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 04:40:51.866707) ========= 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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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/f2169bad4648f074206826ccbdbf309d238dad4b575ddf6657a9c30a35041acfbf29f02042069cacd7e3f5442d9e03c24e22068e72375239b0203c0cd5723da7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/507f65c5ddbbc7e1ce4d1f4d3a98e538f3d7c7428861b853b08f0414899521aca40202482d1ab1a5bd866fbb9a74243d2fa66dba3df45de5e6ebab2c40db5b37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8acc568c59e4652033b17180bfd3135134c54126cae66ee1e95494f9d7d908d59e88fbac7d41ab38267d55078fa07750c4fdba3a55bda04275753bafea2b7e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef33d13773213e332957b755aa7538e8b9975b9b3785630d85b9ab0731eb0579aa6595a7f2436459ab4260f3db6c1029d0fec77796f8e16cd42da3ea14480e56"} build_url:https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011184 build_url: 'https://queue.taskcluster.net/v1/task/aXa5sCSFRNuZqMgmgOCx1g/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/f2169bad4648f074206826ccbdbf309d238dad4b575ddf6657a9c30a35041acfbf29f02042069cacd7e3f5442d9e03c24e22068e72375239b0203c0cd5723da7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/507f65c5ddbbc7e1ce4d1f4d3a98e538f3d7c7428861b853b08f0414899521aca40202482d1ab1a5bd866fbb9a74243d2fa66dba3df45de5e6ebab2c40db5b37", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8acc568c59e4652033b17180bfd3135134c54126cae66ee1e95494f9d7d908d59e88fbac7d41ab38267d55078fa07750c4fdba3a55bda04275753bafea2b7e0b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef33d13773213e332957b755aa7538e8b9975b9b3785630d85b9ab0731eb0579aa6595a7f2436459ab4260f3db6c1029d0fec77796f8e16cd42da3ea14480e56"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 04:40:51.902345) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:40:51.902841) ========= 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.3RZe9CKqqQ/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.UNln8dEGlF/Listeners TMPDIR=/var/folders/sz/c6ws_wbn20bfgbb7cxcht_j000000w/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.005172 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 04:40:51.953519) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 04:40:51.954032) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 04:40:51.954793) ========= ========= Total master_lag: 0.19 =========