builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0028 starttime: 1497998508.7 results: success (0) buildid: 20170620141157 builduid: 870529573d8244e3adb32310b920d7de revision: cd547b10e59e7db0b381a21598c65e183fc4dc2b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:48.697176) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:48.697632) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:48.697908) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:48.723063) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:48.723466) ========= 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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-20 15:41:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.1M=0.001s 2017-06-20 15:41:48 (14.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.138237 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:48.883935) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:48.884247) ========= 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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.028310 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:48.933886) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-20 15:41:48.934234) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cd547b10e59e7db0b381a21598c65e183fc4dc2b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev cd547b10e59e7db0b381a21598c65e183fc4dc2b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-20 15:41:49,031 truncating revision to first 12 chars 2017-06-20 15:41:49,031 Setting DEBUG logging. 2017-06-20 15:41:49,031 attempt 1/10 2017-06-20 15:41:49,032 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/cd547b10e59e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-20 15:41:50,084 unpacking tar archive at: mozilla-beta-cd547b10e59e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.337910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-20 15:41:50.293733) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:50.294055) ========= script_repo_revision: cd547b10e59e7db0b381a21598c65e183fc4dc2b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:50.294449) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:50.294716) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 15:41:50.312298) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-20 15:41:50.312686) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:41:50 INFO - MultiFileLogger online at 20170620 15:41:50 in /builds/slave/test 15:41:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:41:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:41:50 INFO - {'append_to_log': False, 15:41:50 INFO - 'base_work_dir': '/builds/slave/test', 15:41:50 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:41:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:41:50 INFO - 'branch': 'Mozilla-Beta', 15:41:50 INFO - 'buildbot_json_path': 'buildprops.json', 15:41:50 INFO - 'config_files': ('talos/mac_config.py',), 15:41:50 INFO - 'default_actions': ('clobber', 15:41:50 INFO - 'read-buildbot-config', 15:41:50 INFO - 'download-and-extract', 15:41:50 INFO - 'populate-webroot', 15:41:50 INFO - 'create-virtualenv', 15:41:50 INFO - 'install', 15:41:50 INFO - 'setup-mitmproxy', 15:41:50 INFO - 'run-tests'), 15:41:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:41:50 INFO - 'download_minidump_stackwalk': True, 15:41:50 INFO - 'download_symbols': 'ondemand', 15:41:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:41:50 INFO - 'tooltool.py': '/tools/tooltool.py', 15:41:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:41:50 INFO - '/tools/misc-python/virtualenv.py')}, 15:41:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:41:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:41:50 INFO - 'gecko_profile': False, 15:41:50 INFO - 'gecko_profile_interval': 0, 15:41:50 INFO - 'installer_path': 'installer.exe', 15:41:50 INFO - 'log_level': 'info', 15:41:50 INFO - 'log_name': 'talos', 15:41:50 INFO - 'log_to_console': True, 15:41:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:41:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:41:50 INFO - 'opt_config_files': (), 15:41:50 INFO - 'pip_index': False, 15:41:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:41:50 INFO - 'cmd': ('bash', 15:41:50 INFO - '-c', 15:41:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:41:50 INFO - 'enabled': True, 15:41:50 INFO - 'halt_on_failure': False, 15:41:50 INFO - 'name': 'check_screen_resolution'},), 15:41:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:41:50 INFO - 'cmd': ('bash', 15:41:50 INFO - '-c', 15:41:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:41:50 INFO - 'enabled': True, 15:41:50 INFO - 'halt_on_failure': False, 15:41:50 INFO - 'name': 'check_screen_resolution'},), 15:41:50 INFO - 'run_cmd_checks_enabled': True, 15:41:50 INFO - 'suite': 'dromaeojs-e10s', 15:41:50 INFO - 'system_bits': '32', 15:41:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:41:50 INFO - 'title': 't-yosemite-r7-0028', 15:41:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:41:50 INFO - 'use_talos_json': True, 15:41:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:41:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:41:50 INFO - 'work_dir': 'build'} 15:41:50 INFO - [mozharness: 2017-06-20 22:41:50.517961Z] Running clobber step. 15:41:50 INFO - Running pre-action listener: _resource_record_pre_action 15:41:50 INFO - Running main action method: clobber 15:41:50 INFO - rmtree: /builds/slave/test/build 15:41:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:41:53 INFO - Running post-action listener: _resource_record_post_action 15:41:53 INFO - [mozharness: 2017-06-20 22:41:53.017804Z] Finished clobber step (success) 15:41:53 INFO - [mozharness: 2017-06-20 22:41:53.017958Z] Running read-buildbot-config step. 15:41:53 INFO - Running pre-action listener: _resource_record_pre_action 15:41:53 INFO - Running main action method: read_buildbot_config 15:41:53 INFO - Using buildbot properties: 15:41:53 INFO - { 15:41:53 INFO - "project": "", 15:41:53 INFO - "product": "firefox", 15:41:53 INFO - "script_repo_revision": "production", 15:41:53 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 15:41:53 INFO - "repository": "", 15:41:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 15:41:53 INFO - "buildid": "20170620141157", 15:41:53 INFO - "pgo_build": "False", 15:41:53 INFO - "basedir": "/builds/slave/test", 15:41:53 INFO - "buildnumber": 3, 15:41:53 INFO - "slavename": "t-yosemite-r7-0028", 15:41:53 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:41:53 INFO - "platform": "yosemite_r7_devedition", 15:41:53 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 15:41:53 INFO - "branch": "mozilla-beta", 15:41:53 INFO - "repo_path": "releases/mozilla-beta", 15:41:53 INFO - "revision": "cd547b10e59e7db0b381a21598c65e183fc4dc2b", 15:41:53 INFO - "stage_platform": "macosx64-devedition", 15:41:53 INFO - "builduid": "870529573d8244e3adb32310b920d7de", 15:41:53 INFO - "slavebuilddir": "test" 15:41:53 INFO - } 15:41:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 15:41:53 INFO - Running post-action listener: _resource_record_post_action 15:41:53 INFO - [mozharness: 2017-06-20 22:41:53.021229Z] Finished read-buildbot-config step (success) 15:41:53 INFO - [mozharness: 2017-06-20 22:41:53.021334Z] Running download-and-extract step. 15:41:53 INFO - Running pre-action listener: _resource_record_pre_action 15:41:53 INFO - Running main action method: download_and_extract 15:41:53 INFO - mkdir: /builds/slave/test/build/tests 15:41:53 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')]} 15:41:53 INFO - trying https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 15:41:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 15:41:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 15:41:54 INFO - Downloaded 1290 bytes. 15:41:54 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 15:41:54 INFO - Using the following test package requirements: 15:41:54 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:41:54 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 15:41:54 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:41:54 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:41:54 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 15:41:54 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:41:54 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 15:41:54 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:41:54 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:41:54 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 15:41:54 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:41:54 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:41:54 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 15:41:54 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:41:54 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 15:41:54 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:41:54 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 15:41:54 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:41:54 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 15:41:54 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 15:41:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:41:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:41:54 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:41:56 INFO - Content-Length response header: 59545122 15:41:56 INFO - Bytes received: 59545122 15:42:14 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:42:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:42:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:42:14 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:42:16 INFO - Content-Length response header: 59545122 15:42:16 INFO - Bytes received: 59545122 15:42:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 15:42:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 15:42:33 INFO - Fetch https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 15:42:35 INFO - Content-Length response header: 14454470 15:42:35 INFO - Bytes received: 14454470 15:42: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')]} 15:42:35 INFO - trying https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:42:35 INFO - Downloading https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 15:42:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 15:42:37 INFO - Downloaded 66840470 bytes. 15:42:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:42:37 INFO - mkdir: /builds/slave/test/properties 15:42:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:42:37 INFO - Writing to file /builds/slave/test/properties/build_url 15:42:37 INFO - Contents: 15:42:37 INFO - build_url:https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:42:37 INFO - Running post-action listener: _resource_record_post_action 15:42:37 INFO - Running post-action listener: set_extra_try_arguments 15:42:37 INFO - [mozharness: 2017-06-20 22:42:37.990683Z] Finished download-and-extract step (success) 15:42:37 INFO - [mozharness: 2017-06-20 22:42:37.990795Z] Running populate-webroot step. 15:42:37 INFO - Running pre-action listener: _resource_record_pre_action 15:42:37 INFO - Running main action method: populate_webroot 15:42:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:42:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:42:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:42:38 INFO - '--tppagecycles', 15:42:38 INFO - '7'], 15:42:38 INFO - 'tests': ['tsvgm']}}, 15:42:38 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:42:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:42:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:42:38 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:42:38 INFO - 'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:38 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:42:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:42:38 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:42:38 INFO - 'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['damp', 'tps']}, 15:42:38 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:42:38 INFO - 'tests': ['damp', 'tps']}, 15:42:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['dromaeo_dom']}, 15:42:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:42:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:42:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:42:38 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:42:38 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:42:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['a11yr', 15:42:38 INFO - 'ts_paint', 15:42:38 INFO - 'tpaint', 15:42:38 INFO - 'sessionrestore', 15:42:38 INFO - 'sessionrestore_no_auto_restore', 15:42:38 INFO - 'tabpaint']}, 15:42:38 INFO - 'other-e10s': {'tests': ['a11yr', 15:42:38 INFO - 'ts_paint', 15:42:38 INFO - 'tpaint', 15:42:38 INFO - 'sessionrestore', 15:42:38 INFO - 'sessionrestore_no_auto_restore', 15:42:38 INFO - 'tabpaint']}, 15:42:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:42:38 INFO - 'ts_paint', 15:42:38 INFO - 'tpaint', 15:42:38 INFO - 'sessionrestore', 15:42:38 INFO - 'sessionrestore_no_auto_restore', 15:42:38 INFO - 'tabpaint']}, 15:42:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:42:38 INFO - 'ts_paint', 15:42:38 INFO - 'tpaint', 15:42:38 INFO - 'sessionrestore', 15:42:38 INFO - 'sessionrestore_no_auto_restore', 15:42:38 INFO - 'tabpaint']}, 15:42:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['a11yr', 15:42:38 INFO - 'ts_paint', 15:42:38 INFO - 'tpaint', 15:42:38 INFO - 'sessionrestore', 15:42:38 INFO - 'sessionrestore_no_auto_restore', 15:42:38 INFO - 'tabpaint']}, 15:42:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['a11yr', 15:42:38 INFO - 'ts_paint', 15:42:38 INFO - 'tpaint', 15:42:38 INFO - 'sessionrestore', 15:42:38 INFO - 'sessionrestore_no_auto_restore', 15:42:38 INFO - 'tabpaint']}, 15:42:38 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:42:38 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:42:38 INFO - 'bloom_basic_ref']}, 15:42:38 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:42:38 INFO - 'talos_options': ['--mitmproxy', 15:42:38 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:42:38 INFO - '--firstNonBlankPaint'], 15:42:38 INFO - 'tests': ['quantum_pageload_google', 15:42:38 INFO - 'quantum_pageload_youtube', 15:42:38 INFO - 'quantum_pageload_amazon', 15:42:38 INFO - 'quantum_pageload_facebook']}, 15:42:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['tsvgx', 15:42:38 INFO - 'tsvgr_opacity', 15:42:38 INFO - 'tart', 15:42:38 INFO - 'tscrollx', 15:42:38 INFO - 'cart', 15:42:38 INFO - 'tsvg_static']}, 15:42:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:42:38 INFO - 'tsvgr_opacity', 15:42:38 INFO - 'tart', 15:42:38 INFO - 'tscrollx', 15:42:38 INFO - 'cart', 15:42:38 INFO - 'tsvg_static']}, 15:42:38 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:42:38 INFO - 'talos_options': ['--disable-e10s'], 15:42:38 INFO - 'tests': ['tp5o']}, 15:42:38 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:42:38 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:42:38 INFO - 'talos_options': ['--disable-e10s', 15:42:38 INFO - '--xperf_path', 15:42:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:38 INFO - 'tests': ['tp5n']}, 15:42:38 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:42:38 INFO - 'talos_options': ['--xperf_path', 15:42:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:42:38 INFO - 'tests': ['tp5n']}}, 15:42:38 INFO - 'talos.zip': {'path': '', 15:42:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:42:38 INFO - Running post-action listener: _resource_record_post_action 15:42:38 INFO - [mozharness: 2017-06-20 22:42:38.012151Z] Finished populate-webroot step (success) 15:42:38 INFO - [mozharness: 2017-06-20 22:42:38.012283Z] Running create-virtualenv step. 15:42:38 INFO - Running pre-action listener: _resource_record_pre_action 15:42:38 INFO - Running main action method: create_virtualenv 15:42:38 INFO - Creating virtualenv /builds/slave/test/build/venv 15:42:38 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 15:42:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:42:38 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:42:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:42:38 INFO - Using real prefix '/tools/python27' 15:42:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:42:38 INFO - Installing distribute.............................................................................................................................................................................................done. 15:42:41 INFO - Installing pip.................done. 15:42:41 INFO - Return code: 0 15:42:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:42:41 INFO - Reading from file tmpfile_stdout 15:42:42 INFO - Output received: 15:42:42 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) 15:42:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:42:42 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')]} 15:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:42 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')]} 15:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:42 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 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:42:42 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 15:42:42 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 15:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:42 INFO - 'HOME': '/Users/cltbld', 15:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:42 INFO - 'LOGNAME': 'cltbld', 15:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:42 INFO - 'MOZ_NO_REMOTE': '1', 15:42:42 INFO - 'NO_EM_RESTART': '1', 15:42:42 INFO - 'PAGER': '/bin/cat', 15:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:42 INFO - 'PWD': '/builds/slave/test', 15:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:42 INFO - 'SHELL': '/bin/bash', 15:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:42:42 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:42 INFO - 'USER': 'cltbld', 15:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:42 INFO - 'XPC_FLAGS': '0x0', 15:42:42 INFO - 'XPC_SERVICE_NAME': '0', 15:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:42 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 15:42:42 INFO - Cleaning up... 15:42:42 INFO - Return code: 0 15:42:42 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:42:42 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')]} 15:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:42 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')]} 15:42:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:42 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 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:42:42 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 15:42:42 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 15:42:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:42:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:42 INFO - 'HOME': '/Users/cltbld', 15:42:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:42 INFO - 'LOGNAME': 'cltbld', 15:42:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:42 INFO - 'MOZ_NO_REMOTE': '1', 15:42:42 INFO - 'NO_EM_RESTART': '1', 15:42:42 INFO - 'PAGER': '/bin/cat', 15:42:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:42 INFO - 'PWD': '/builds/slave/test', 15:42:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:42 INFO - 'SHELL': '/bin/bash', 15:42:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:42:42 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:42:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:42 INFO - 'USER': 'cltbld', 15:42:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:42 INFO - 'XPC_FLAGS': '0x0', 15:42:42 INFO - 'XPC_SERVICE_NAME': '0', 15:42:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:42 INFO - Downloading/unpacking psutil>=3.1.1 15:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:42:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:44 INFO - Installing collected packages: psutil 15:42:44 INFO - Running setup.py install for psutil 15:42:44 INFO - building 'psutil._psutil_osx' extension 15:42:44 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 15:42:45 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 15:42:45 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 15:42:45 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 15:42:45 INFO - building 'psutil._psutil_posix' extension 15:42:45 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 15:42:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:42:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:42:45 INFO - ^ 15:42:45 INFO - 1 warning generated. 15:42:45 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 15:42:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:42:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:42:45 INFO - Successfully installed psutil 15:42:45 INFO - Cleaning up... 15:42:45 INFO - Return code: 0 15:42:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:42:45 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')]} 15:42:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:46 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 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:42:46 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 15:42:46 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 15:42:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:42:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:46 INFO - 'HOME': '/Users/cltbld', 15:42:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:46 INFO - 'LOGNAME': 'cltbld', 15:42:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:46 INFO - 'MOZ_NO_REMOTE': '1', 15:42:46 INFO - 'NO_EM_RESTART': '1', 15:42:46 INFO - 'PAGER': '/bin/cat', 15:42:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:46 INFO - 'PWD': '/builds/slave/test', 15:42:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:46 INFO - 'SHELL': '/bin/bash', 15:42:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:42:46 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:42:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:46 INFO - 'USER': 'cltbld', 15:42:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:46 INFO - 'XPC_FLAGS': '0x0', 15:42:46 INFO - 'XPC_SERVICE_NAME': '0', 15:42:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:42:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:42:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:42:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:42:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:42:48 INFO - Installing collected packages: mozsystemmonitor 15:42:48 INFO - Running setup.py install for mozsystemmonitor 15:42:48 INFO - Successfully installed mozsystemmonitor 15:42:48 INFO - Cleaning up... 15:42:48 INFO - Return code: 0 15:42:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:42:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42: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')]} 15:42:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:42: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==2.5.1'] in /builds/slave/test/build 15:42: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==2.5.1 15:42:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:42:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:49 INFO - 'HOME': '/Users/cltbld', 15:42:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:49 INFO - 'LOGNAME': 'cltbld', 15:42:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:49 INFO - 'MOZ_NO_REMOTE': '1', 15:42:49 INFO - 'NO_EM_RESTART': '1', 15:42:49 INFO - 'PAGER': '/bin/cat', 15:42:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:49 INFO - 'PWD': '/builds/slave/test', 15:42:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:49 INFO - 'SHELL': '/bin/bash', 15:42:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:42:49 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:42:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:49 INFO - 'USER': 'cltbld', 15:42:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:49 INFO - 'XPC_FLAGS': '0x0', 15:42:49 INFO - 'XPC_SERVICE_NAME': '0', 15:42:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:49 INFO - Downloading/unpacking jsonschema==2.5.1 15:42:49 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 15:42:49 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 15:42:49 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 15:42:49 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 15:42:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:42:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:42:50 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 15:42:50 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 15:42:50 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 15:42:50 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 15:42:51 INFO - Downloading functools32-3.2.3-2.tar.gz 15:42:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:42:51 INFO - warning: no files found matching '*.txt' 15:42:51 INFO - no previously-included directories found matching 'build' 15:42:51 INFO - no previously-included directories found matching 'dist' 15:42:51 INFO - no previously-included directories found matching '.git*' 15:42:51 INFO - Installing collected packages: jsonschema, functools32 15:42:51 INFO - Running setup.py install for functools32 15:42:51 INFO - warning: no files found matching '*.txt' 15:42:51 INFO - no previously-included directories found matching 'build' 15:42:51 INFO - no previously-included directories found matching 'dist' 15:42:51 INFO - no previously-included directories found matching '.git*' 15:42:51 INFO - Successfully installed jsonschema functools32 15:42:51 INFO - Cleaning up... 15:42:51 INFO - Return code: 0 15:42:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:51 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 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:42:51 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 15:42:51 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 15:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:51 INFO - 'HOME': '/Users/cltbld', 15:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:51 INFO - 'LOGNAME': 'cltbld', 15:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:51 INFO - 'MOZ_NO_REMOTE': '1', 15:42:51 INFO - 'NO_EM_RESTART': '1', 15:42:51 INFO - 'PAGER': '/bin/cat', 15:42:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:51 INFO - 'PWD': '/builds/slave/test', 15:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:51 INFO - 'SHELL': '/bin/bash', 15:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:42:51 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:51 INFO - 'USER': 'cltbld', 15:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:51 INFO - 'XPC_FLAGS': '0x0', 15:42:51 INFO - 'XPC_SERVICE_NAME': '0', 15:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:42:51 INFO - Cleaning up... 15:42:51 INFO - Return code: 0 15:42:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:51 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 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:42:51 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 15:42:51 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 15:42:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:42:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:51 INFO - 'HOME': '/Users/cltbld', 15:42:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:51 INFO - 'LOGNAME': 'cltbld', 15:42:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:51 INFO - 'MOZ_NO_REMOTE': '1', 15:42:51 INFO - 'NO_EM_RESTART': '1', 15:42:51 INFO - 'PAGER': '/bin/cat', 15:42:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:51 INFO - 'PWD': '/builds/slave/test', 15:42:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:51 INFO - 'SHELL': '/bin/bash', 15:42:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:42:51 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:42:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:51 INFO - 'USER': 'cltbld', 15:42:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:51 INFO - 'XPC_FLAGS': '0x0', 15:42:51 INFO - 'XPC_SERVICE_NAME': '0', 15:42:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:51 INFO - Downloading/unpacking blobuploader==1.2.4 15:42:51 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 15:42:51 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 15:42:51 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 15:42:51 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 15:42:53 INFO - Downloading blobuploader-1.2.4.tar.gz 15:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:42:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:42:53 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 15:42:53 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 15:42:53 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 15:42:53 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 15:42:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:42:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:42:53 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 15:42:53 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 15:42:53 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 15:42:53 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 15:42:54 INFO - Downloading docopt-0.6.1.tar.gz 15:42:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:42:54 INFO - Installing collected packages: blobuploader, requests, docopt 15:42:54 INFO - Running setup.py install for blobuploader 15:42:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:42:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:42:54 INFO - Running setup.py install for requests 15:42:54 INFO - Running setup.py install for docopt 15:42:55 INFO - Successfully installed blobuploader requests docopt 15:42:55 INFO - Cleaning up... 15:42:55 INFO - Return code: 0 15:42:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:55 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')]} 15:42:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:55 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')]} 15:42:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:55 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 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:42:55 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 15:42:55 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 15:42:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:42:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:55 INFO - 'HOME': '/Users/cltbld', 15:42:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:55 INFO - 'LOGNAME': 'cltbld', 15:42:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:55 INFO - 'MOZ_NO_REMOTE': '1', 15:42:55 INFO - 'NO_EM_RESTART': '1', 15:42:55 INFO - 'PAGER': '/bin/cat', 15:42:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:55 INFO - 'PWD': '/builds/slave/test', 15:42:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:55 INFO - 'SHELL': '/bin/bash', 15:42:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:42:55 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:42:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:55 INFO - 'USER': 'cltbld', 15:42:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:55 INFO - 'XPC_FLAGS': '0x0', 15:42:55 INFO - 'XPC_SERVICE_NAME': '0', 15:42:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:42:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-XfJcrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:42:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-cys16S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:42:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-zdtc3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:42:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-D19hoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:42:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-6IEK63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:42:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-SSQQsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:42:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:42:55 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-R56qwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-1RJ_wr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-0cgPSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-w4cEXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Eo21Bk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-s9BG7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-uRmQc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-fAdAJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-74BEgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:42:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:42:56 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-3SFPcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:42:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:42:57 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-8LAWeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:42:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:42:57 INFO - Running setup.py install for manifestparser 15:42:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:42:57 INFO - Running setup.py install for mozcrash 15:42:57 INFO - Running setup.py install for mozdebug 15:42:57 INFO - Running setup.py install for mozdevice 15:42:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:42:57 INFO - Running setup.py install for mozfile 15:42:57 INFO - Running setup.py install for mozhttpd 15:42:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:42:58 INFO - Running setup.py install for mozinfo 15:42:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:42:58 INFO - Running setup.py install for mozInstall 15:42:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:42:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:42:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:42:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:42:58 INFO - Running setup.py install for mozleak 15:42:58 INFO - Running setup.py install for mozlog 15:42:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:42:58 INFO - Running setup.py install for moznetwork 15:42:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:42:58 INFO - Running setup.py install for mozprocess 15:42:58 INFO - Running setup.py install for mozprofile 15:42:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:42:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:42:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:42:59 INFO - Running setup.py install for mozrunner 15:42:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:42:59 INFO - Running setup.py install for mozscreenshot 15:42:59 INFO - Running setup.py install for moztest 15:42:59 INFO - Running setup.py install for mozversion 15:42:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:42:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:42:59 INFO - Cleaning up... 15:42:59 INFO - Return code: 0 15:42:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:42:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:42:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:42:59 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 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:42:59 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 15:42:59 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 15:42:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:42:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:42:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:42:59 INFO - 'HOME': '/Users/cltbld', 15:42:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:42:59 INFO - 'LOGNAME': 'cltbld', 15:42:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:42:59 INFO - 'MOZ_NO_REMOTE': '1', 15:42:59 INFO - 'NO_EM_RESTART': '1', 15:42:59 INFO - 'PAGER': '/bin/cat', 15:42:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:42:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:42:59 INFO - 'PWD': '/builds/slave/test', 15:42:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:42:59 INFO - 'SHELL': '/bin/bash', 15:42:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:42:59 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:42:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:42:59 INFO - 'USER': 'cltbld', 15:42:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:42:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:42:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:42:59 INFO - 'XPC_FLAGS': '0x0', 15:42:59 INFO - 'XPC_SERVICE_NAME': '0', 15:42:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:42:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:42:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-XHP7xX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:43:00 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)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-AajGgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:43:00 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)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-r2K_wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:43:00 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)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-qWXZ8G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:43:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-tlkBBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:43:00 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)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-VZ8na8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:43:00 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)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-lrY29X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:43:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-KaHZgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:43:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-T8lgby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:43:00 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)) 15:43:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:43:00 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-AcH3UM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:43:01 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)) 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:43:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-6N3qfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:43:01 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)) 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:43:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-x5OHNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:43:01 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)) 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:43:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-qrH6EL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:43:01 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)) 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:43:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Zxe9gc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:43:01 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)) 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-RCk_V1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:43:01 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)) 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:43:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-XVAU6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:43:01 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)) 15:43:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:43:01 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-YQhi6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:43:01 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)) 15:43:01 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)) 15:43:01 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)) 15:43:01 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)) 15:43:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:43:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:43:01 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:43:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:43:01 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 15:43:01 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 15:43:01 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 15:43:01 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 15:43:03 INFO - Downloading blessings-1.6.tar.gz 15:43:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:43:03 INFO - Installing collected packages: blessings 15:43:03 INFO - Running setup.py install for blessings 15:43:03 INFO - Successfully installed blessings 15:43:03 INFO - Cleaning up... 15:43:03 INFO - Return code: 0 15:43:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:43:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:43:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:43:03 INFO - Reading from file tmpfile_stdout 15:43:03 INFO - Current package versions: 15:43:03 INFO - blessings == 1.6 15:43:03 INFO - blobuploader == 1.2.4 15:43:03 INFO - docopt == 0.6.1 15:43:03 INFO - functools32 == 3.2.3-2 15:43:03 INFO - jsonschema == 2.5.1 15:43:03 INFO - manifestparser == 1.1 15:43:03 INFO - mozInstall == 1.13 15:43:03 INFO - mozcrash == 1.0 15:43:03 INFO - mozdebug == 0.1 15:43:03 INFO - mozdevice == 0.51 15:43:03 INFO - mozfile == 1.2 15:43:03 INFO - mozhttpd == 0.7 15:43:03 INFO - mozinfo == 0.10 15:43:03 INFO - mozleak == 0.1 15:43:03 INFO - mozlog == 3.4 15:43:03 INFO - moznetwork == 0.27 15:43:03 INFO - mozprocess == 0.25 15:43:03 INFO - mozprofile == 0.28 15:43:03 INFO - mozrunner == 6.13 15:43:03 INFO - mozscreenshot == 0.1 15:43:03 INFO - mozsystemmonitor == 0.3 15:43:03 INFO - moztest == 0.8 15:43:03 INFO - mozversion == 1.4 15:43:03 INFO - psutil == 3.1.1 15:43:03 INFO - requests == 1.2.3 15:43:03 INFO - wsgiref == 0.1.2 15:43:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:43:03 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')]} 15:43:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:03 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')]} 15:43:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:03 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 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:43:03 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 15:43:03 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 15:43:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:43:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:03 INFO - 'HOME': '/Users/cltbld', 15:43:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:03 INFO - 'LOGNAME': 'cltbld', 15:43:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:03 INFO - 'MOZ_NO_REMOTE': '1', 15:43:03 INFO - 'NO_EM_RESTART': '1', 15:43:03 INFO - 'PAGER': '/bin/cat', 15:43:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:03 INFO - 'PWD': '/builds/slave/test', 15:43:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:03 INFO - 'SHELL': '/bin/bash', 15:43:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:43:03 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:43:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:03 INFO - 'USER': 'cltbld', 15:43:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:03 INFO - 'XPC_FLAGS': '0x0', 15:43:03 INFO - 'XPC_SERVICE_NAME': '0', 15:43:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 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)) 15:43:04 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:43:04 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 15:43:04 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 15:43:04 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 15:43:04 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 15:43:05 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:43:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:43:05 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)) 15:43:05 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)) 15:43:05 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)) 15:43:05 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)) 15:43:05 INFO - Installing collected packages: marionette-driver 15:43:05 INFO - Running setup.py install for marionette-driver 15:43:05 INFO - Successfully installed marionette-driver 15:43:05 INFO - Cleaning up... 15:43:05 INFO - Return code: 0 15:43:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:43:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:43:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:43:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109021c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109025768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9449c9bb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, '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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 15:43:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:43:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:43:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:43:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:05 INFO - 'HOME': '/Users/cltbld', 15:43:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:05 INFO - 'LOGNAME': 'cltbld', 15:43:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:05 INFO - 'MOZ_NO_REMOTE': '1', 15:43:05 INFO - 'NO_EM_RESTART': '1', 15:43:05 INFO - 'PAGER': '/bin/cat', 15:43:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:05 INFO - 'PWD': '/builds/slave/test', 15:43:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:05 INFO - 'SHELL': '/bin/bash', 15:43:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:43:05 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:43:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:05 INFO - 'USER': 'cltbld', 15:43:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:05 INFO - 'XPC_FLAGS': '0x0', 15:43:05 INFO - 'XPC_SERVICE_NAME': '0', 15:43:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:43:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:43:06 INFO - Cleaning up... 15:43:06 INFO - Return code: 0 15:43:06 INFO - Running post-action listener: _resource_record_post_action 15:43:06 INFO - Running post-action listener: _start_resource_monitoring 15:43:06 INFO - Starting resource monitoring. 15:43:06 INFO - [mozharness: 2017-06-20 22:43:06.076366Z] Finished create-virtualenv step (success) 15:43:06 INFO - [mozharness: 2017-06-20 22:43:06.077371Z] Running install step. 15:43:06 INFO - Running pre-action listener: _resource_record_pre_action 15:43:06 INFO - Running main action method: install 15:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:43:06 INFO - Reading from file tmpfile_stdout 15:43:06 INFO - Detecting whether we're running mozinstall >=1.0... 15:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:43:06 INFO - Reading from file tmpfile_stdout 15:43:06 INFO - Output received: 15:43:06 INFO - Usage: mozinstall [options] installer 15:43:06 INFO - Options: 15:43:06 INFO - -h, --help show this help message and exit 15:43:06 INFO - -d DEST, --destination=DEST 15:43:06 INFO - Directory to install application into. [default: 15:43:06 INFO - "/builds/slave/test"] 15:43:06 INFO - --app=APP Application being installed. [default: firefox] 15:43:06 INFO - mkdir: /builds/slave/test/build/application 15:43:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:43:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:43:27 INFO - Reading from file tmpfile_stdout 15:43:27 INFO - Output received: 15:43:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:43:27 INFO - Running post-action listener: _resource_record_post_action 15:43:27 INFO - [mozharness: 2017-06-20 22:43:27.975099Z] Finished install step (success) 15:43:27 INFO - [mozharness: 2017-06-20 22:43:27.975278Z] Running setup-mitmproxy step. 15:43:27 INFO - Running pre-action listener: _resource_record_pre_action 15:43:27 INFO - Running main action method: setup_mitmproxy 15:43:27 INFO - Skipping: mitmproxy is not required 15:43:27 INFO - Running post-action listener: _resource_record_post_action 15:43:27 INFO - [mozharness: 2017-06-20 22:43:27.975850Z] Finished setup-mitmproxy step (success) 15:43:27 INFO - [mozharness: 2017-06-20 22:43:27.975996Z] Running run-tests step. 15:43:27 INFO - Running pre-action listener: _resource_record_pre_action 15:43:27 INFO - Running main action method: run_tests 15:43:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:43:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:43:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:43:27 INFO - 2017-06-20 15:43:27.990 screenresolution[1623:7405] starting screenresolution argv=screenresolution get 15:43:27 INFO - 2017-06-20 15:43:27.993 screenresolution[1623:7405] Display 0: 1600x1200x32@60 15:43:28 INFO - 2017-06-20 15:43:27.999 screenresolution[1624:7407] starting screenresolution argv=screenresolution list 15:43:28 INFO - Available Modes on Display 0 15:43:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:43:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:43:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:43:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:43:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:43:28 INFO - Intel Iris: 15:43:28 INFO - Chipset Model: Intel Iris 15:43:28 INFO - Type: GPU 15:43:28 INFO - Bus: Built-In 15:43:28 INFO - VRAM (Dynamic, Max): 1536 MB 15:43:28 INFO - Vendor: Intel (0x8086) 15:43:28 INFO - Device ID: 0x0a2e 15:43:28 INFO - Revision ID: 0x0009 15:43:28 INFO - Displays: 15:43:28 INFO - Display: 15:43:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:43:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:43:28 INFO - Display Serial Number: 1600x1200 60 15:43:28 INFO - Main Display: Yes 15:43:28 INFO - Mirror: Off 15:43:28 INFO - Online: Yes 15:43:28 INFO - Rotation: Supported 15:43:28 INFO - Return code: 0 15:43:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:43:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:43:28 INFO - Python 2.7.3 15:43:28 INFO - Return code: 0 15:43:28 INFO - grabbing minidump binary from tooltool 15:43:28 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')]} 15:43:28 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 0x108d77d30>, '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 0x108d41cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108d68be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:43:28 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 15:43:28 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 15:43:28 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 15:43:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:43:28 INFO - Return code: 0 15:43:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:43:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:43:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:43:28 INFO - ENV: RUST_BACKTRACE is now 1 15:43:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:43:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:43:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 15:43:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0028 --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 15:43:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgo58dRZxJ/Render', 15:43:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:43:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:43:28 INFO - 'HOME': '/Users/cltbld', 15:43:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:43:28 INFO - 'LOGNAME': 'cltbld', 15:43:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:43:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:43:28 INFO - 'MOZ_NO_REMOTE': '1', 15:43:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:43:28 INFO - 'NO_EM_RESTART': '1', 15:43:28 INFO - 'PAGER': '/bin/cat', 15:43:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:43:28 INFO - 'PWD': '/builds/slave/test', 15:43:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:43:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:43:28 INFO - 'RUST_BACKTRACE': '1', 15:43:28 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:43:28 INFO - 'SHELL': '/bin/bash', 15:43:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.A21LZ5Nzrr/Listeners', 15:43:28 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 15:43:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:43:28 INFO - 'USER': 'cltbld', 15:43:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:43:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:43:28 INFO - 'XPC_FLAGS': '0x0', 15:43:28 INFO - 'XPC_SERVICE_NAME': '0', 15:43:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:43:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 15:43:28 INFO - mozversion application_buildid: 20170620141157 15:43:28 INFO - mozversion application_changeset: cd547b10e59e7db0b381a21598c65e183fc4dc2b 15:43:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:43:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:43:28 INFO - mozversion application_name: Firefox 15:43:28 INFO - mozversion application_remotingname: firefox-dev 15:43:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:43:28 INFO - mozversion application_vendor: Mozilla 15:43:28 INFO - mozversion application_version: 55.0 15:43:28 INFO - mozversion platform_buildid: 20170620141157 15:43:28 INFO - mozversion platform_changeset: cd547b10e59e7db0b381a21598c65e183fc4dc2b 15:43:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:43:28 INFO - mozversion platform_version: 55.0 15:43:28 INFO - using testdate: 1497998608 15:43:28 INFO - actual date: 1497998608 15:43:28 INFO - starting webserver on 'localhost:49222' 15:43:28 INFO - SUITE-START | Running 2 tests 15:43:28 INFO - TEST-START | dromaeo_css 15:43:28 INFO - operating with platform_type : mac_ 15:43:28 INFO - Initialising browser for dromaeo_css test... 15:43:28 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmph1DTwE/profile 15:43:28 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 15:44:02 INFO - TEST-INFO | 1634: killed by SIGTERM 15:44:02 INFO - Browser initialized. 15:44:02 INFO - Running cycle 1/1 for dromaeo_css test... 15:44:02 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmph1DTwE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:44:04 INFO - PID 1643 | 2017-06-20 15:44:04.010 plugin-container[1644:7972] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 15:44:04 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:44:04 INFO - PID 1643 | 2017-06-20 15:44:04.010 plugin-container[1644:7972] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451f, name = 'com.apple.CFPasteboardClient' 15:44:04 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:44:04 INFO - PID 1643 | 2017-06-20 15:44:04.010 plugin-container[1644:7972] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:44:04 INFO - PID 1643 | 2017-06-20 15:44:04.761 plugin-container[1645:8014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9343, name = 'com.apple.tsm.portname' 15:44:04 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:44:04 INFO - PID 1643 | 2017-06-20 15:44:04.762 plugin-container[1645:8014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9547, name = 'com.apple.CFPasteboardClient' 15:44:04 INFO - PID 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:44:04 INFO - PID 1643 | 2017-06-20 15:44:04.762 plugin-container[1645:8014] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:44:14 INFO - PID 1643 | RSS: Main: 214851584 15:44:14 INFO - PID 1643 | 15:47:19 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 15:47:21 INFO - PID 1643 | RSS: Main: 239001600 15:47:21 INFO - PID 1643 | 15:50:23 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 15:50:23 INFO - PID 1643 | RSS: Main: 247357440 15:50:23 INFO - PID 1643 | 15:53:25 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 15:53:26 INFO - PID 1643 | RSS: Main: 249221120 15:53:26 INFO - PID 1643 | 15:56:28 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 15:56:28 INFO - PID 1643 | RSS: Main: 253243392 15:56:28 INFO - PID 1643 | 15:59:31 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 15:59:32 INFO - PID 1643 | RSS: Main: 253825024 15:59:32 INFO - PID 1643 | 16:02:33 INFO - PID 1643 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 16:02:34 INFO - PID 1643 | RSS: Main: 251760640 16:02:34 INFO - PID 1643 | 16:02:34 INFO - PID 1643 | __start_tp_report 16:02:34 INFO - PID 1643 | _x_x_mozilla_page_load 16:02:34 INFO - PID 1643 | _x_x_mozilla_page_load_details 16:02:34 INFO - PID 1643 | |i|pagename|runs| 16:02:34 INFO - PID 1643 | |0;dojo.html;3489.5986961499434;3601.549806274214;3613.6928361089313;3632.400653892108;3667.7065834733226;6257.624542527452;6266.248050233972;6266.780662676807;6290.559660823738;6313.463355614778;6332;6355.841103972403;6361.236651601815;6378.170837791093;6383.968080159604;4020.3165461871404;5541.196526503664;5549.972250138743;5601.60788744788;5645.379008309083;5594.9369619772115;5596.0206963781375;5668.631538949955;5698.8290351289525;5709.229254050712;4616;5477.945220547823;5499.532539734128;5545.722713864331;5601.691906945116;5429.837104886859;5508.504234618864;5533.335999680024;5580.497755202051;5630.8029218977545;5460.563154947595;5488.259085023511;5517.889642207174;5524.502794748451;5562.3047119110115;5098.852758129428;6069.15031895535;6308.684565771693;6311.08489269058;6324.08300796382;6815.625140617264;6829.248782633956;6842.897356539655;6843.418309443654;6874.759383421556;6674.69963851623;6800.4219641330055;6821.488388370843;6874.415674667659;6977.546459480168;6227.221597300338;6358.968205158944;6401.743930242738;6452.741890324428;6477.546571739933;6912.239653638096;6996.720131194696;7112.644367781693;7147.606881621561;7153.606551639606;6606.471482281406;6607.9669601651685;6611.768588099393;6668.0331351954665;6761.29006454325;6985.3713165815325;6992.475564332593;6995.650217489105;7002.7899163025195;7014.754483593152;6875.656217189121;6886;6894.172699276019;6905.274946130685;6912.377885990184;6632;6736.494762892705;6750.3924646782025;6781.11845460087;6859.039734437183;5470.863228419322;5483.951231649664;5524.530414914697;5545.584081193846;5546.334439867161;4802.231642937113;4870.220764677634;4894.412670479494;4904.705717656952;4958.107540642719;6509.446697030806;6531.216254049545;6544.280129185786;6547.803565893031;6556.8197724410065;6938.479614028968;6991.440684745208;6993;6995.545289556264;7021.824454388681;6157.96921015392;6382.425581697669;6391.456726178327;6409.487241020707;6547.377999090078;6068.969655151696;6142.170806941095;6143.201383820075;6146.924288249538;6154.076888466676;7128.215896251512;7134.607596582237;7139.535930164523;7142.607156606437;7153.105861767279;6938.653067346511;6946.305369463013;6994.755183568551;7023.683934223074;7026.929730702627;6890.931090689029;7126.322999315057;7143.964280178566;7168.534045287248;7170.89243661356;6632.767853125118;6637.834054148685;6770;6833.726650933907;6845;6888.758893438706;6896.793096207121;6977.162740471176;7000.509964302653;7014.5440546364325;6749.291324411062;6765.729370825309;6768.796936091925;6776.6950487227205;6788.355106264897;6345.0482427636225;6400.39196276353;6749.392554670103;6758.256591774999;6780.966095169492;5275.393329767126;5392.622516423813;6417.326180750954;6427.839304017437;6432.678366081584;6535.771248006297;6557.147570815763;6667.66661666905;6668.933310667026;6748.190217173774;6498.610083395011;6565.474762018932;6567.408933196035;6578.5395022347975;6656.7670131545165 16:02:34 INFO - PID 1643 | |1;ext.html;776.6427443376047;805.9643358284605;836.4646626159258;856.1566856646205;863.9568021598918;35999.82000089999;36995.44506832396;37135.4429683555;37165.32756025982;37183.62816371836;36749.44875826865;37107.628923710756;37144.071398215056;37170;37211.813940930326;6243.7502499900065;7480.0649918760155;7496.250374962515;7508.436867234952;7519.774406767779;6381.93618063818;7414.59219742914;7426.183119856812;7522.13495448025;7538.283863033003;7459.850802983964;7484.363829074535;7484.962575187116;7495.625218739068;7517.548947063166;7957.562334071624;8131;8222.17778222179;8222.62998165078;8226;8054.23484768946;8090.514569125871;8136;8144.104148543655;8156.225158609982;34682.82658586716;34931;34964.65035349665;35015.4747678785;35130.47304290438;3132.4518209313505;3580.373434648926;3619.402798538238;3621.1309285771226;3629.274145170976;28747.712522874714;29117.56323655147;29188.854055729797;29214;29223.123306300633;2965.273507990556;3059.7858149929516;3074.1546074829603;3076.107928700674;3108.347247078094;202408;253264;255673;256625.7168714163;257636;37200.627993720256;46960.06079878383;46960.295595566095;47019.52980470152;47287.527124729;40887.97780055522;49397.29520285993;49642.75178624156;49774.75112624414;49906;15259.465918692793;15304.540863774104;15311.770323445156;15419.383224670888;15441;11795.764084718257;11843.111766617538;12787.936060319638;12789.36053197355;12798;13148.671283217996;13287.003474739433;13318.267495287851;13332.6666833328;13464.259465729283;4967.90064198714;5850.502707269845;5891.293044834647;5915.881682366328;5930.614510056833;931.9394239374419;960.0606774315843;996.3473924579412;1016.5730393234711;1036.3468772576728;19025;22831.657525137136;23117.537649246922;23167.652485212737;23706.407339816644;915.6383228624655;1027.2501074215863;1084.6800193942806;1097.9725506862392;1153.3656488931053;11609.767804644029;11861.881381186251;11960.40197990097;12041.39793010346;12092.516299348103;283140;334633;335089;335816;336335;52210.73894630503;52330.21504677433;52485.737571311896;52658.999999999236;52737;1518.8860835437386;1526.29027502212;1530.066659337803;1531.4563330017907;1532.3181184372847;22250.332490025325;22669.88665056664;23212.419689507897;23225.651615225448;24094.9199860409;30631.693683063775;37285.4407183887;37452.62547374491;37587.248255034196;37631;297404;297539.5123024371;297696.511517441;298573;298939;28028.71971280261;28477.857610711813;31770.205744856565;33203.667963320055;33647.831760841036;39696.60303396929;41297.79351103225;42112;42236.78881605572;42373.78813105915;27268.06306194133;28028.29929251785;28665.28336791597;29821.254468638457;30114.096577102722;28478.82668461208;29193.97821076252;29967.400651987275;30256;30626.693733062384;32691.019269421955;33027.18350490746;34379.65620343765;34512.48231276583;35153.121171969884;23547.529049418572;28357.858210708815;28707.900765218925;30013;30312.69687303187;23498.765012349657;23743.287701368987;23807.690577017736;23906.163284284965;23932.641010385207 16:02:34 INFO - PID 1643 | |2;jquery.html;479.53006054067015;502.6506577928341;505.50208045075595;513.2327170879535;532.1485623003193;20695.06872190751;21554.245601403953;21648.675269870946;22328.995195216212;22404.215852445166;19828.40514784555;22539;23381.83090845455;23702.288931332045;23767.524649507;5037.848864534069;6212.9068063979075;6380.585261957981;6401.071844582532;6472.870542589146;6224.937750622504;6301.023341382071;6333.683315834213;6341.746330146789;6344.397282258201;5829.387914269004;6076.544259180557;6137.570370074097;6159.661218632973;6310.463610593105;4852.9757351213375;6019.668918209497;6073.028315469526;6090.482309003718;6273.1531243282025;5526.171074338841;5526.530244929146;5533.474319939599;5534.944650553483;5542.418046105182;27619;28672.283192920135;29017.70982290171;29021.839126434916;29104.9813256535;2177.7277840269967;2252.8986195621237;2271.46620544172;2275.6586512023164;2275.8634481931135;18332.54168645781;18623.90688046551;18757.249710011583;18776.906115469334;18884.244630214776;2155.8275337972923;2184.388371256051;2199.714037175173;2201.7467991180897;2212.712347394828;141449.2927535366;169341;183360.08319958314;183959;212837;11841.88158118425;13614.047016708933;13845.16928984264;14559.41762329495;14875.776863347059;19604.21583136687;19854.01459854003;19856.20575176977;19883.701744473845;19900.402987910387;9884.654037108667;9936.552855121538;10212.540435680412;10239.078482936422;10240.180785537139;9574.808503829885;9640.276979226586;9646.855297170549;9678.612855485842;9682.419054856731;8787.516686582172;8797.38418310725;8845.584706447065;8880.777980550409;8942.50816205115;2658.8803503842373;2661.7604415602686;2664.307280107147;2675.1038402135114;2771.7540317555513;934.5841100710119;941.6892425499568;943.580106852444;977.0864241933815;993.3642468819961;20361.694574581394;20494.38516844497;20583.07376168046;21339.039743211593;21493.05728363124;965.3146266151111;988.5749127874939;1010.4139599032543;1016.1058268723624;1027.2603725317758;10481.213908956706;10484.947575262075;10554.683359499228;10719.303245289031;11202.831957520644;574700.7996565221;589758;601113;603576;603987;154897;158837;159041.2047939776;159946.20026900023;160289;979.24108815667;1028.4446398944635;1031.4275577054657;1031.489412740698;1118.3625333559792;18597.34909278169;20810.47973800667;21334.03996820116;21763.9118044094;22633;68163;69424;70290.64854675694;71881;72346;97613;100202;100822;105226;105823;11399.829002564802;11582.826257606312;11724.413779311171;11779.411029448665;11919;15230.543083707507;16014.359425622844;16101.516954491384;18383.26466941361;18574.628507430047;12486.562970295996;13185.670358240737;13387.665308366979;13518.188908665119;13569.592912212649;11997.64007079789;12050.698732531757;12222.266664000188;12434.067444941846;12568.87431125677;13870.237136957161;13887.305634718427;13946.372413241226;13963.441462341392;14065.226412547408;8833.33749968742;10749.462526873469;10777.676669699922;10908.836367454653;10952.561897524329;12970.610881673565;13097.541586044825;13101;13110.672233194246;13189.538366157138 16:02:34 INFO - PID 1643 | |3;mootools.html;1399.1884706870017;1445.024608389337;1452.4843680493418;1456.9854301456983;1467.3543640798052;11616.767664646713;13413.12814667046;13834.824039956604;14011.579652610439;14029.36867840947;13496.190228586267;13919;13919.860801391984;13920.234387108705;13969.30153492324;2273.9658905116394;2844.8008639395252;2862.5419932810755;2864.4843928092937;2864.770818334528;2703.9324016899536;2811;2813.7186281371905;2819.0697069966864;2827.46278207141;2311.537692461506;2406.3863714752715;2410.7950824179966;2764.543850264714;2860.585215143803;2831.8584070796583;2846.658400991884;2856.8428736419487;2859.356644754934;2859.6997315281906;2817.647794025747;2831.1648063821017;2838.7019362967003;2838.815476993989;2840.1053668094482;13376.197428154343;13701.177929324273;13703.931480342535;13709.931450342685;13787.862121378757;7629.80925476862;7902.80242993924;7935.246151615645;7940.205979402013;7961.681532738741;4709.152352576535;4858.1983972644475;4892.926606100911;4898.485659005789;4965.851024469272;1372.540199033325;1376.7521846067705;1384.6399936016608;1405.3838086200951;1406.521782593923;21120.894395528077;21229.5754084919;21233.68149477759;21293.680594790934;21339.35981920545;6691.5315927884585;6726.159230096238;6838.794836154923;6939.895901561481;6943.375096241362;14233.217173055582;14382.208978506282;14469;14491.275436228147;14532.927335363256;7521.9247807522315;7611.20082391352;7665.425093118039;7669.654865531064;7672.42456815741;6071.392860713893;6770.424513916274;7017.3684368407085;7037;7098.183708873443;4789.712617242976;4889.997550502138;5068.771905264272;5198.428172900978;5348.171033489815;1326.0253713520556;1543.5060780550116;1555.8366371530826;1559.438602103242;1578.4159860851557;710.989335159973;778.8208711996159;844.7043534762784;857.2070834478083;870.5647176411794;6486.416222539994;7040.577565345993;7253.782386528413;7275.454340924452;7283.380912622486;963.816874793787;981.9558119884622;1013.2805707947292;1015.6648306058983;1038.444432228754;4177.885783718105;4394.143142087262;4395.824167033283;4577.816887324537;4592.288195329729;18273.3604323848;18570.907145464185;18634.06829658539;18672.533186670444;18710.438686839418;17389;18556;18634;18636.34772782946;18653.533661658566;9249.815003699889;9257.028012058772;9525.714228573153;9542.61829526825;9600.615975360908;7903.328217101611;8133.186681331819;8175.427720059539;8339.45793523419;8374.581270936551;16348.264328105026;16450.75323870119;16531.504054878373;17031.14844257807;17052.97682139026;15627.296771645531;16613.41853035138;17108.48674539766;17113.14434278306;17147.657046858745;12345.321007344683;12570.49718011306;12572.874271257537;12617.93691031539;12619.11666183395;12468.937655311665;12486;12508.249505029728;12544.121911466109;12558.246505209716;9311.906880931376;9815.85276220843;9883;9940.701778946643;10311.535980880728;8389.412741108308;9781.899364588757;9819.263555233529;9989.900100998897;10287.279890407599;8890.06654301289;8941.284697224337;9044.914610246813;9054.818903622023;9180.724578262663;6624.735010599522;6950.93049069523;7024.1571011478945;7191.496595238283;7225.819354516179;6927.653617319214;7024.121984751906;7194.856102877809;7245.456590755609;7267.745628902963 16:02:34 INFO - PID 1643 | |4;prototype.html;435.8888483436727;447.7022779851399;462.38964566771864;471.55202557570294;473.91000698393697;5409.647588102975;5719.857003574913;6010.248718910137;6483.222013358391;6588.967055164718;5542.473465020817;6372.585781924172;6384.5211609129265;6392.7442902283965;6393.232812062559;4824.179889418799;4835.419749630049;4850.781714822824;4857.198562237244;4891.657583969123;4125.443065186207;4818.879528011793;4822.807087716487;4836.782344794493;4838.201696720037;4621.306803979413;4688.367070445497;4821.493743156971;4822.565969062784;4826.565609095181;4786.56920877121;4802.711837289757;4811.7112973221365;4818.831340903087;4844.2733589961435;3495.860165593373;4189.62293393593;4196.223698615766;4236.152769446123;4240.851570195028;6179.079431037222;6203.8138855834395;6227.159333489966;6232.736100219296;6243.1883855099;4671;4702.506236845116;5677.318721753375;5693.658380497183;5707;6493.220813502363;6860.5197636165485;6879.552829066145;6904.930950690529;6991.552956465247;3981.467135152987;4795.592374648159;4804.6876952997945;4809.1583972804965;4814.146553576863;6674.132362792854;7293.343599076109;7340.669669864816;7351;7356.668949897267;4357.869263922087;4662.0209755950955;4890.679516530517;4904.092742842586;4912.38595175603;4912.673578133956;4988.875278118004;4999.450060493343;4999.850004499871;5000.574951129122;4716.410448693913;5670.809130082646;5682.54539636828;5690.174924635905;5733.3406658234835;6611.404973552307;6921.961705744178;6925.58446493212;6940.236573976858;6965.825854353681;1409.689868228988;1473.7052589482146;1602.543275166572;1696.2197389201106;1697.117498900565;1052.9947350263353;1083.7399024234128;1086.3970496374588;1087.255230167338;1107.595727559435;5756.194132821408;5879.176915231786;6399.424051835264;6432.847904526749;6576.473882089422;5913.994620914455;6251.687415629201;7170.856582868313;7199.568025918462;7201.963990180016;5691.345495268097;6309.905351419732;6528.673566321665;6537.934620653828;6603.537752357289;3113.517404802259;3209.8716051357683;3680.7301480989017;3683.5395575553057;3721.646443587909;6347.873042539123;6654.966725166343;7319.5242309249725;7347.595882226529;7354.6690398931105;5798.5071268941765;5849.3858144894175;7292.635368231568;7314.561126332437;7319.487635865438;5947.048472244506;6191.721372538246;6201.844953876189;7194.7696214173775;7352.264773522605;5582.916256256241;5835.9708201458725;6305.6216627000695;6662.300458451987;7119.679614417467;6931.653417329013;6951.30486951321;6986.54587451814;7004.3696067354185;7026.473014524034;6805.65971701403;6806.93193068063;7288.599127048064;7297.781066568012;7301.780946571611;4829.468758436639;4841.4674385816825;4857.975710121426;4888.584470320063;4916.164252077227;5862.794802181903;6259.874802504016;7100.928990710234;7134.714611415485;7153.2131465537705;6787.864242715217;6852.965735171292;6861.725530978904;6892.758753443605;6897.7585784497305;6216.875662486635;6508.674566271762;6711.798646040626;6865.18072710722;6874.9656251718425;5290.81482148123;6539;6659.866802663822;6680.732770689118;6697.698603562754;5375.623706340675;6795.864082718417;6843.281455447305;6844.965775171092;6871.278515755974;6626.90059649096;6645.767398141042;6647.567908085959;6666.566673166229;6689.46484281266 16:02:34 INFO - PID 1643 | |5;yui.html;814.8411059843326;828.0891019878135;835.2858306148244;851.9403641745076;860.2300940658112;17133.742993855103;17561.473155805314;17581.472555823315;17600.855944363622;17611.82388176118;17329.306827726876;18368.72446913294;18501.81498185021;18606.16272267748;18630.72053919189;1002.3634991780217;1013.0882206014609;1026.8049070676584;1032.473438546343;1038.1539045677787;1012.3116280928965;1022.4121130350042;1024.3444195714712;1024.5133561558237;1027.1064174168484;902.3728508686465;944.489975413276;977.3451787302524;1052.41590917041;1073.0396295315688;1025.0927928783044;1044.6657069737687;1049.396596956753;1052.952617132231;1053.5680371047833;748.4498893313411;763.9503432276884;770.2143813310448;771.6103367799282;772.5326177662546;17279.568010799703;17362.65274694499;17404.825951740448;17617.911910440493;17651.02919339436;17141;17190;17323.393681221096;17411.5647108822;17460.476185714448;14991.650751432424;15038.924805376013;15109.47116850905;15117.39530418782;15124.773128403083;3553.129483276588;3567.233044895334;3585.928281434357;3599.6760291573883;3601.243738814852;113808.43095784468;125340;127793;158309.4169058306;158528;13906.026578139434;14218.431262749375;14227.004109712429;14380.856191437952;14422;27460.588091178248;27484.725152748615;27786.305342366602;27788.30529236785;27915;7835.608219588998;8163.346932245406;8227.177282271845;8312.958435207905;8331.750047498585;4089.284375234322;4118.5263694674895;4147.19129769692;4155.459790227251;4158.293090174678;4355.651547876162;4357.520672725997;4651.62786977041;4666.626669866402;4726;838.1450920061503;869.4174902815129;869.6217145541709;1006.2000709436016;1008.9041541053588;644.458654730029;658.9538732288789;682.331315310998;697.6825544377297;707.5754547271699;11265.15511336653;11269.380184089954;11691.590794322157;11894.583689570823;12399.442025108892;580.7241560258843;620.2340109964191;642.1202951955757;669.210331808461;671.1744554198364;5109.412417571953;5824.592278540546;6037.09443583466;6555.672216389162;6668.761565587809;136535.31732341473;142116;143668;144111;149826.50173498126;106443;113443.86556134498;113447;113874;114709;101255;103040;103604;106297;108177;10940.45297735126;11897.988670962719;12421.441035153259;12475.563355282522;12892.935535322264;37420.81289593535;38846.44614215513;46763.06473870572;46992.530074698814;49758.502414976836;56212.718936406694;60237.350285975226;61630;64937.35062649313;67278.32721672721;16408.917955410623;16690.66618667644;17391.913040434716;17518.73721894147;17564.946103233848;18788.530286742392;18797.90601046986;19020.524486887938;19042.952637605064;19184.712229316294;11189.992900638981;14930.552083437515;15191.316390762664;17049.48851534456;17323.653526929644;16604.50186494407;16730.4144354947;16884;17299.7405038922;17600.29598816033;16299.674006520041;16366.509004729876;16689.49931502057;16820.747688784435;16927.915360423118;11288.49201785938;11421.37182454973;12001.519939202333;12199.14605977573;12209.328486932949;13058.934705326412;13143.868561314264;13153.868461315264;13175.538856139989;13484.797728033891 16:02:34 INFO - PID 1643 | __end_tp_report 16:02:34 INFO - PID 1643 | __start_cc_report 16:02:34 INFO - PID 1643 | _x_x_mozilla_cycle_collect,409 16:02:34 INFO - PID 1643 | __end_cc_report 16:02:34 INFO - PID 1643 | __startTimestamp1497999754031__endTimestamp 16:02:34 INFO - PID 1643 | 16:02:34 INFO - PID 1643 | ------- Summary: start ------- 16:02:34 INFO - PID 1643 | Number of tests: 6 16:02:34 INFO - PID 1643 | 16:02:34 INFO - PID 1643 | [#0] dojo.html Cycles:165 Average:6301.06 Median:6547.80 stddev:794.19 (12.1%) stddev-sans-first:765.38 16:02:34 INFO - PID 1643 | Values: 3489.6 3601.5 3613.7 3632.4 3667.7 6257.6 6266.2 6266.8 6290.6 6313.5 6332.0 6355.8 6361.2 6378.2 6384.0 4020.3 5541.2 5550.0 5601.6 5645.4 5594.9 5596.0 5668.6 5698.8 5709.2 4616.0 5477.9 5499.5 5545.7 5601.7 5429.8 5508.5 5533.3 5580.5 5630.8 5460.6 5488.3 5517.9 5524.5 5562.3 5098.9 6069.2 6308.7 6311.1 6324.1 6815.6 6829.2 6842.9 6843.4 6874.8 6674.7 6800.4 6821.5 6874.4 6977.5 6227.2 6359.0 6401.7 6452.7 6477.5 6912.2 6996.7 7112.6 7147.6 7153.6 6606.5 6608.0 6611.8 6668.0 6761.3 6985.4 6992.5 6995.7 7002.8 7014.8 6875.7 6886.0 6894.2 6905.3 6912.4 6632.0 6736.5 6750.4 6781.1 6859.0 5470.9 5484.0 5524.5 5545.6 5546.3 4802.2 4870.2 4894.4 4904.7 4958.1 6509.4 6531.2 6544.3 6547.8 6556.8 6938.5 6991.4 6993.0 6995.5 7021.8 6158.0 6382.4 6391.5 6409.5 6547.4 6069.0 6142.2 6143.2 6146.9 6154.1 7128.2 7134.6 7139.5 7142.6 7153.1 6938.7 6946.3 6994.8 7023.7 7026.9 6890.9 7126.3 7144.0 7168.5 7170.9 6632.8 6637.8 6770.0 6833.7 6845.0 6888.8 6896.8 6977.2 7000.5 7014.5 6749.3 6765.7 6768.8 6776.7 6788.4 6345.0 6400.4 6749.4 6758.3 6781.0 5275.4 5392.6 6417.3 6427.8 6432.7 6535.8 6557.1 6667.7 6668.9 6748.2 6498.6 6565.5 6567.4 6578.5 6656.8 16:02:34 INFO - PID 1643 | 16:02:34 INFO - PID 1643 | [#1] ext.html Cycles:180 Average:43281.43 Median:334861.00 stddev:76634.20 (22.9%) stddev-sans-first:76782.73 16:02:34 INFO - PID 1643 | Values: 776.6 806.0 836.5 856.2 864.0 35999.8 36995.4 37135.4 37165.3 37183.6 36749.4 37107.6 37144.1 37170.0 37211.8 6243.8 7480.1 7496.3 7508.4 7519.8 6381.9 7414.6 7426.2 7522.1 7538.3 7459.9 7484.4 7485.0 7495.6 7517.5 7957.6 8131.0 8222.2 8222.6 8226.0 8054.2 8090.5 8136.0 8144.1 8156.2 34682.8 34931.0 34964.7 35015.5 35130.5 3132.5 3580.4 3619.4 3621.1 3629.3 28747.7 29117.6 29188.9 29214.0 29223.1 2965.3 3059.8 3074.2 3076.1 3108.3 202408.0 253264.0 255673.0 256625.7 257636.0 37200.6 46960.1 46960.3 47019.5 47287.5 40888.0 49397.3 49642.8 49774.8 49906.0 15259.5 15304.5 15311.8 15419.4 15441.0 11795.8 11843.1 12787.9 12789.4 12798.0 13148.7 13287.0 13318.3 13332.7 13464.3 4967.9 5850.5 5891.3 5915.9 5930.6 931.9 960.1 996.3 1016.6 1036.3 19025.0 22831.7 23117.5 23167.7 23706.4 915.6 1027.3 1084.7 1098.0 1153.4 11609.8 11861.9 11960.4 12041.4 12092.5 283140.0 334633.0 335089.0 335816.0 336335.0 52210.7 52330.2 52485.7 52659.0 52737.0 1518.9 1526.3 1530.1 1531.5 1532.3 22250.3 22669.9 23212.4 23225.7 24094.9 30631.7 37285.4 37452.6 37587.2 37631.0 297404.0 297539.5 297696.5 298573.0 298939.0 28028.7 28477.9 31770.2 33203.7 33647.8 39696.6 41297.8 42112.0 42236.8 42373.8 27268.1 28028.3 28665.3 29821.3 30114.1 28478.8 29194.0 29967.4 30256.0 30626.7 32691.0 33027.2 34379.7 34512.5 35153.1 23547.5 28357.9 28707.9 30013.0 30312.7 23498.8 23743.3 23807.7 23906.2 23932.6 16:02:34 INFO - PID 1643 | 16:02:34 INFO - PID 1643 | [#2] jquery.html Cycles:180 Average:40063.74 Median:21706.29 stddev:102232.98 (471.0%) stddev-sans-first:102476.57 16:02:34 INFO - PID 1643 | Values: 479.5 502.7 505.5 513.2 532.1 20695.1 21554.2 21648.7 22329.0 22404.2 19828.4 22539.0 23381.8 23702.3 23767.5 5037.8 6212.9 6380.6 6401.1 6472.9 6224.9 6301.0 6333.7 6341.7 6344.4 5829.4 6076.5 6137.6 6159.7 6310.5 4853.0 6019.7 6073.0 6090.5 6273.2 5526.2 5526.5 5533.5 5534.9 5542.4 27619.0 28672.3 29017.7 29021.8 29105.0 2177.7 2252.9 2271.5 2275.7 2275.9 18332.5 18623.9 18757.2 18776.9 18884.2 2155.8 2184.4 2199.7 2201.7 2212.7 141449.3 169341.0 183360.1 183959.0 212837.0 11841.9 13614.0 13845.2 14559.4 14875.8 19604.2 19854.0 19856.2 19883.7 19900.4 9884.7 9936.6 10212.5 10239.1 10240.2 9574.8 9640.3 9646.9 9678.6 9682.4 8787.5 8797.4 8845.6 8880.8 8942.5 2658.9 2661.8 2664.3 2675.1 2771.8 934.6 941.7 943.6 977.1 993.4 20361.7 20494.4 20583.1 21339.0 21493.1 965.3 988.6 1010.4 1016.1 1027.3 10481.2 10484.9 10554.7 10719.3 11202.8 574700.8 589758.0 601113.0 603576.0 603987.0 154897.0 158837.0 159041.2 159946.2 160289.0 979.2 1028.4 1031.4 1031.5 1118.4 18597.3 20810.5 21334.0 21763.9 22633.0 68163.0 69424.0 70290.6 71881.0 72346.0 97613.0 100202.0 100822.0 105226.0 105823.0 11399.8 11582.8 11724.4 11779.4 11919.0 15230.5 16014.4 16101.5 18383.3 18574.6 12486.6 13185.7 13387.7 13518.2 13569.6 11997.6 12050.7 12222.3 12434.1 12568.9 13870.2 13887.3 13946.4 13963.4 14065.2 8833.3 10749.5 10777.7 10908.8 10952.6 12970.6 13097.5 13101.0 13110.7 13189.5 16:02:34 INFO - PID 1643 | 16:02:34 INFO - PID 1643 | [#3] mootools.html Cycles:180 Average:8408.95 Median:2842.45 stddev:5626.84 (198.0%) stddev-sans-first:5617.98 16:02:34 INFO - PID 1643 | Values: 1399.2 1445.0 1452.5 1457.0 1467.4 11616.8 13413.1 13834.8 14011.6 14029.4 13496.2 13919.0 13919.9 13920.2 13969.3 2274.0 2844.8 2862.5 2864.5 2864.8 2703.9 2811.0 2813.7 2819.1 2827.5 2311.5 2406.4 2410.8 2764.5 2860.6 2831.9 2846.7 2856.8 2859.4 2859.7 2817.6 2831.2 2838.7 2838.8 2840.1 13376.2 13701.2 13703.9 13709.9 13787.9 7629.8 7902.8 7935.2 7940.2 7961.7 4709.2 4858.2 4892.9 4898.5 4965.9 1372.5 1376.8 1384.6 1405.4 1406.5 21120.9 21229.6 21233.7 21293.7 21339.4 6691.5 6726.2 6838.8 6939.9 6943.4 14233.2 14382.2 14469.0 14491.3 14532.9 7521.9 7611.2 7665.4 7669.7 7672.4 6071.4 6770.4 7017.4 7037.0 7098.2 4789.7 4890.0 5068.8 5198.4 5348.2 1326.0 1543.5 1555.8 1559.4 1578.4 711.0 778.8 844.7 857.2 870.6 6486.4 7040.6 7253.8 7275.5 7283.4 963.8 982.0 1013.3 1015.7 1038.4 4177.9 4394.1 4395.8 4577.8 4592.3 18273.4 18570.9 18634.1 18672.5 18710.4 17389.0 18556.0 18634.0 18636.3 18653.5 9249.8 9257.0 9525.7 9542.6 9600.6 7903.3 8133.2 8175.4 8339.5 8374.6 16348.3 16450.8 16531.5 17031.1 17053.0 15627.3 16613.4 17108.5 17113.1 17147.7 12345.3 12570.5 12572.9 12617.9 12619.1 12468.9 12486.0 12508.2 12544.1 12558.2 9311.9 9815.9 9883.0 9940.7 10311.5 8389.4 9781.9 9819.3 9989.9 10287.3 8890.1 8941.3 9044.9 9054.8 9180.7 6624.7 6950.9 7024.2 7191.5 7225.8 6927.7 7024.1 7194.9 7245.5 7267.7 16:02:34 INFO - PID 1643 | 16:02:34 INFO - PID 1643 | [#4] prototype.html Cycles:180 Average:5525.63 Median:6094.66 stddev:1699.44 (27.9%) stddev-sans-first:1660.72 16:02:34 INFO - PID 1643 | Values: 435.9 447.7 462.4 471.6 473.9 5409.6 5719.9 6010.2 6483.2 6589.0 5542.5 6372.6 6384.5 6392.7 6393.2 4824.2 4835.4 4850.8 4857.2 4891.7 4125.4 4818.9 4822.8 4836.8 4838.2 4621.3 4688.4 4821.5 4822.6 4826.6 4786.6 4802.7 4811.7 4818.8 4844.3 3495.9 4189.6 4196.2 4236.2 4240.9 6179.1 6203.8 6227.2 6232.7 6243.2 4671.0 4702.5 5677.3 5693.7 5707.0 6493.2 6860.5 6879.6 6904.9 6991.6 3981.5 4795.6 4804.7 4809.2 4814.1 6674.1 7293.3 7340.7 7351.0 7356.7 4357.9 4662.0 4890.7 4904.1 4912.4 4912.7 4988.9 4999.5 4999.9 5000.6 4716.4 5670.8 5682.5 5690.2 5733.3 6611.4 6922.0 6925.6 6940.2 6965.8 1409.7 1473.7 1602.5 1696.2 1697.1 1053.0 1083.7 1086.4 1087.3 1107.6 5756.2 5879.2 6399.4 6432.8 6576.5 5914.0 6251.7 7170.9 7199.6 7202.0 5691.3 6309.9 6528.7 6537.9 6603.5 3113.5 3209.9 3680.7 3683.5 3721.6 6347.9 6655.0 7319.5 7347.6 7354.7 5798.5 5849.4 7292.6 7314.6 7319.5 5947.0 6191.7 6201.8 7194.8 7352.3 5582.9 5836.0 6305.6 6662.3 7119.7 6931.7 6951.3 6986.5 7004.4 7026.5 6805.7 6806.9 7288.6 7297.8 7301.8 4829.5 4841.5 4858.0 4888.6 4916.2 5862.8 6259.9 7100.9 7134.7 7153.2 6787.9 6853.0 6861.7 6892.8 6897.8 6216.9 6508.7 6711.8 6865.2 6875.0 5290.8 6539.0 6659.9 6680.7 6697.7 5375.6 6795.9 6843.3 6845.0 6871.3 6626.9 6645.8 6647.6 6666.6 6689.5 16:02:34 INFO - PID 1643 | 16:02:34 INFO - PID 1643 | [#5] yui.html Cycles:180 Average:24959.60 Median:17345.98 stddev:37812.55 (218.0%) stddev-sans-first:37875.17 16:02:34 INFO - PID 1643 | Values: 814.8 828.1 835.3 851.9 860.2 17133.7 17561.5 17581.5 17600.9 17611.8 17329.3 18368.7 18501.8 18606.2 18630.7 1002.4 1013.1 1026.8 1032.5 1038.2 1012.3 1022.4 1024.3 1024.5 1027.1 902.4 944.5 977.3 1052.4 1073.0 1025.1 1044.7 1049.4 1053.0 1053.6 748.4 764.0 770.2 771.6 772.5 17279.6 17362.7 17404.8 17617.9 17651.0 17141.0 17190.0 17323.4 17411.6 17460.5 14991.7 15038.9 15109.5 15117.4 15124.8 3553.1 3567.2 3585.9 3599.7 3601.2 113808.4 125340.0 127793.0 158309.4 158528.0 13906.0 14218.4 14227.0 14380.9 14422.0 27460.6 27484.7 27786.3 27788.3 27915.0 7835.6 8163.3 8227.2 8313.0 8331.8 4089.3 4118.5 4147.2 4155.5 4158.3 4355.7 4357.5 4651.6 4666.6 4726.0 838.1 869.4 869.6 1006.2 1008.9 644.5 659.0 682.3 697.7 707.6 11265.2 11269.4 11691.6 11894.6 12399.4 580.7 620.2 642.1 669.2 671.2 5109.4 5824.6 6037.1 6555.7 6668.8 136535.3 142116.0 143668.0 144111.0 149826.5 106443.0 113443.9 113447.0 113874.0 114709.0 101255.0 103040.0 103604.0 106297.0 108177.0 10940.5 11898.0 12421.4 12475.6 12892.9 37420.8 38846.4 46763.1 46992.5 49758.5 56212.7 60237.4 61630.0 64937.4 67278.3 16408.9 16690.7 17391.9 17518.7 17564.9 18788.5 18797.9 19020.5 19043.0 19184.7 11190.0 14930.6 15191.3 17049.5 17323.7 16604.5 16730.4 16884.0 17299.7 17600.3 16299.7 16366.5 16689.5 16820.7 16927.9 11288.5 11421.4 12001.5 12199.1 12209.3 13058.9 13143.9 13153.9 13175.5 13484.8 16:02:34 INFO - PID 1643 | -------- Summary: end -------- 16:02:34 INFO - PID 1643 | 16:02:34 INFO - TEST-INFO | 1643: exit 0 16:02:34 INFO - TEST-OK | dromaeo_css | took 1145908ms 16:02:34 INFO - TEST-START | kraken 16:02:34 INFO - operating with platform_type : mac_ 16:02:34 INFO - Initialising browser for kraken test... 16:02:34 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpELFHo9/profile 16:02:34 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 16:03:07 INFO - TEST-INFO | 1653: killed by SIGTERM 16:03:07 INFO - Browser initialized. 16:03:07 INFO - Running cycle 1/1 for kraken test... 16:03:07 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpELFHo9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:03:09 INFO - PID 1657 | 2017-06-20 16:03:09.465 plugin-container[1658:10566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 16:03:09 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:03:09 INFO - PID 1657 | 2017-06-20 16:03:09.465 plugin-container[1658:10566] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 16:03:09 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:03:09 INFO - PID 1657 | 2017-06-20 16:03:09.465 plugin-container[1658:10566] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:03:10 INFO - PID 1657 | 2017-06-20 16:03:10.252 plugin-container[1659:10609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 16:03:10 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:03:10 INFO - PID 1657 | 2017-06-20 16:03:10.252 plugin-container[1659:10609] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 16:03:10 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:03:10 INFO - PID 1657 | 2017-06-20 16:03:10.252 plugin-container[1659:10609] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:03:19 INFO - PID 1657 | RSS: Main: 214081536 16:03:19 INFO - PID 1657 | 16:03:22 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 16:03:23 INFO - PID 1657 | RSS: Main: 228302848 16:03:23 INFO - PID 1657 | 16:03:25 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 16:03:26 INFO - PID 1657 | RSS: Main: 237453312 16:03:26 INFO - PID 1657 | 16:03:29 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 16:03:30 INFO - PID 1657 | RSS: Main: 242102272 16:03:30 INFO - PID 1657 | 16:03:32 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 16:03:32 INFO - PID 1657 | RSS: Main: 243662848 16:03:32 INFO - PID 1657 | 16:03:34 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:03:35 INFO - PID 1657 | RSS: Main: 243658752 16:03:35 INFO - PID 1657 | 16:03:38 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 16:03:38 INFO - PID 1657 | RSS: Main: 247353344 16:03:38 INFO - PID 1657 | 16:03:42 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 16:03:42 INFO - PID 1657 | RSS: Main: 247492608 16:03:42 INFO - PID 1657 | 16:03:45 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 16:03:46 INFO - PID 1657 | RSS: Main: 247685120 16:03:46 INFO - PID 1657 | 16:03:47 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:03:48 INFO - PID 1657 | RSS: Main: 247590912 16:03:48 INFO - PID 1657 | 16:03:50 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:03:50 INFO - PID 1657 | RSS: Main: 247656448 16:03:50 INFO - PID 1657 | 16:03:52 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:03:53 INFO - PID 1657 | RSS: Main: 247615488 16:03:53 INFO - PID 1657 | 16:03:56 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:03:57 INFO - PID 1657 | RSS: Main: 247640064 16:03:57 INFO - PID 1657 | 16:04:00 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:04:00 INFO - PID 1657 | RSS: Main: 248430592 16:04:00 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 16:04:02 INFO - PID 1657 | RSS: Main: 249651200 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | __start_tp_report 16:04:02 INFO - PID 1657 | _x_x_mozilla_page_load 16:04:02 INFO - PID 1657 | _x_x_mozilla_page_load_details 16:04:02 INFO - PID 1657 | |i|pagename|runs| 16:04:02 INFO - PID 1657 | |0;ai-astar;103;113;124;123;119;115;113;102;114;113 16:04:02 INFO - PID 1657 | |1;audio-beat-detection;134;113;151;141;109;126;112;145;147;111 16:04:02 INFO - PID 1657 | |2;audio-dft;198;202;202;199;193;196;197;197;199;197 16:04:02 INFO - PID 1657 | |3;audio-fft;67;75;80;147;57;58;61;60;126;60 16:04:02 INFO - PID 1657 | |4;audio-oscillator;68;70;127;69;72;84;134;69;71;106 16:04:02 INFO - PID 1657 | |5;imaging-gaussian-blur;98;105;93;92;92;92;92;92;93;89 16:04:02 INFO - PID 1657 | |6;imaging-darkroom;96;125;95;138;153;98;138;153;96;95 16:04:02 INFO - PID 1657 | |7;imaging-desaturate;91;89;92;90;92;91;89;93;125;146 16:04:02 INFO - PID 1657 | |8;json-parse-financial;49;47;51;50;50;51;52;52;53;52 16:04:02 INFO - PID 1657 | |9;json-stringify-tinderbox;56;71;54;56;60;58;56;56;55;56 16:04:02 INFO - PID 1657 | |10;stanford-crypto-aes;82;82;83;78;79;83;79;80;84;80 16:04:02 INFO - PID 1657 | |11;stanford-crypto-ccm;147;136;135;142;135;137;140;146;148;171 16:04:02 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;188;233;222;184;232;223;182;183;181;180 16:04:02 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;68;63;66;66;64;68;67;69;77;94 16:04:02 INFO - PID 1657 | __end_tp_report 16:04:02 INFO - PID 1657 | __start_cc_report 16:04:02 INFO - PID 1657 | _x_x_mozilla_cycle_collect,1027 16:04:02 INFO - PID 1657 | __end_cc_report 16:04:02 INFO - PID 1657 | __startTimestamp1497999842807__endTimestamp 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | ------- Summary: start ------- 16:04:02 INFO - PID 1657 | Number of tests: 14 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.50 stddev:7.26 (6.3%) stddev-sans-first:6.55 16:04:02 INFO - PID 1657 | Values: 103.0 113.0 124.0 123.0 119.0 115.0 113.0 102.0 114.0 113.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:128.90 Median:137.50 stddev:16.70 (12.1%) stddev-sans-first:17.61 16:04:02 INFO - PID 1657 | Values: 134.0 113.0 151.0 141.0 109.0 126.0 112.0 145.0 147.0 111.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:198.00 Median:198.50 stddev:2.71 (1.4%) stddev-sans-first:2.87 16:04:02 INFO - PID 1657 | Values: 198.0 202.0 202.0 199.0 193.0 196.0 197.0 197.0 199.0 197.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:79.10 Median:60.50 stddev:31.56 (52.2%) stddev-sans-first:33.17 16:04:02 INFO - PID 1657 | Values: 67.0 75.0 80.0 147.0 57.0 58.0 61.0 60.0 126.0 60.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:87.00 Median:69.50 stddev:25.73 (37.0%) stddev-sans-first:26.36 16:04:02 INFO - PID 1657 | Values: 68.0 70.0 127.0 69.0 72.0 84.0 134.0 69.0 71.0 106.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:92.00 stddev:4.52 (4.9%) stddev-sans-first:4.53 16:04:02 INFO - PID 1657 | Values: 98.0 105.0 93.0 92.0 92.0 92.0 92.0 92.0 93.0 89.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:118.70 Median:95.00 stddev:25.21 (26.5%) stddev-sans-first:25.37 16:04:02 INFO - PID 1657 | Values: 96.0 125.0 95.0 138.0 153.0 98.0 138.0 153.0 96.0 95.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:99.80 Median:91.00 stddev:19.50 (21.4%) stddev-sans-first:20.42 16:04:02 INFO - PID 1657 | Values: 91.0 89.0 92.0 90.0 92.0 91.0 89.0 93.0 125.0 146.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.50 stddev:1.77 (3.4%) stddev-sans-first:1.76 16:04:02 INFO - PID 1657 | Values: 49.0 47.0 51.0 50.0 50.0 51.0 52.0 52.0 53.0 52.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:57.80 Median:56.00 stddev:4.92 (8.8%) stddev-sans-first:5.17 16:04:02 INFO - PID 1657 | Values: 56.0 71.0 54.0 56.0 60.0 58.0 56.0 56.0 55.0 56.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:82.00 stddev:2.05 (2.5%) stddev-sans-first:2.15 16:04:02 INFO - PID 1657 | Values: 82.0 82.0 83.0 78.0 79.0 83.0 79.0 80.0 84.0 80.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:143.70 Median:144.00 stddev:10.81 (7.5%) stddev-sans-first:11.40 16:04:02 INFO - PID 1657 | Values: 147.0 136.0 135.0 142.0 135.0 137.0 140.0 146.0 148.0 171.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:200.80 Median:205.00 stddev:23.32 (11.4%) stddev-sans-first:24.27 16:04:02 INFO - PID 1657 | Values: 188.0 233.0 222.0 184.0 232.0 223.0 182.0 183.0 181.0 180.0 16:04:02 INFO - PID 1657 | 16:04:02 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:68.00 stddev:9.19 (13.5%) stddev-sans-first:9.71 16:04:02 INFO - PID 1657 | Values: 68.0 63.0 66.0 66.0 64.0 68.0 67.0 69.0 77.0 94.0 16:04:02 INFO - PID 1657 | -------- Summary: end -------- 16:04:02 INFO - PID 1657 | 16:04:03 INFO - TEST-INFO | 1657: exit 0 16:04:03 INFO - TEST-OK | kraken | took 89072ms 16:04:03 INFO - SUITE-END | took 1234s 16:04:04 INFO - Completed test suite (00:20:35) 16:04:04 INFO - javascript benchmark 16:04:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8292.567143617358, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3489.5986961499434, 3601.549806274214, 3613.6928361089313, 3632.400653892108, 3667.7065834733226, 6257.624542527452, 6266.248050233972, 6266.780662676807, 6290.559660823738, 6313.463355614778, 6332.0, 6355.841103972403, 6361.236651601815, 6378.170837791093, 6383.968080159604, 4020.3165461871404, 5541.196526503664, 5549.972250138743, 5601.60788744788, 5645.379008309083, 5594.9369619772115, 5596.0206963781375, 5668.631538949955, 5698.8290351289525, 5709.229254050712, 4616.0, 5477.945220547823, 5499.532539734128, 5545.722713864331, 5601.691906945116, 5429.837104886859, 5508.504234618864, 5533.335999680024, 5580.497755202051, 5630.8029218977545, 5460.563154947595, 5488.259085023511, 5517.889642207174, 5524.502794748451, 5562.3047119110115, 5098.852758129428, 6069.15031895535, 6308.684565771693, 6311.08489269058, 6324.08300796382, 6815.625140617264, 6829.248782633956, 6842.897356539655, 6843.418309443654, 6874.759383421556, 6674.69963851623, 6800.4219641330055, 6821.488388370843, 6874.415674667659, 6977.546459480168, 6227.221597300338, 6358.968205158944, 6401.743930242738, 6452.741890324428, 6477.546571739933, 6912.239653638096, 6996.720131194696, 7112.644367781693, 7147.606881621561, 7153.606551639606, 6606.471482281406, 6607.9669601651685, 6611.768588099393, 6668.0331351954665, 6761.29006454325, 6985.3713165815325, 6992.475564332593, 6995.650217489105, 7002.7899163025195, 7014.754483593152, 6875.656217189121, 6886.0, 6894.172699276019, 6905.274946130685, 6912.377885990184, 6632.0, 6736.494762892705, 6750.3924646782025, 6781.11845460087, 6859.039734437183, 5470.863228419322, 5483.951231649664, 5524.530414914697, 5545.584081193846, 5546.334439867161, 4802.231642937113, 4870.220764677634, 4894.412670479494, 4904.705717656952, 4958.107540642719, 6509.446697030806, 6531.216254049545, 6544.280129185786, 6547.803565893031, 6556.8197724410065, 6938.479614028968, 6991.440684745208, 6993.0, 6995.545289556264, 7021.824454388681, 6157.96921015392, 6382.425581697669, 6391.456726178327, 6409.487241020707, 6547.377999090078, 6068.969655151696, 6142.170806941095, 6143.201383820075, 6146.924288249538, 6154.076888466676, 7128.215896251512, 7134.607596582237, 7139.535930164523, 7142.607156606437, 7153.105861767279, 6938.653067346511, 6946.305369463013, 6994.755183568551, 7023.683934223074, 7026.929730702627, 6890.931090689029, 7126.322999315057, 7143.964280178566, 7168.534045287248, 7170.89243661356, 6632.767853125118, 6637.834054148685, 6770.0, 6833.726650933907, 6845.0, 6888.758893438706, 6896.793096207121, 6977.162740471176, 7000.509964302653, 7014.5440546364325, 6749.291324411062, 6765.729370825309, 6768.796936091925, 6776.6950487227205, 6788.355106264897, 6345.0482427636225, 6400.39196276353, 6749.392554670103, 6758.256591774999, 6780.966095169492, 5275.393329767126, 5392.622516423813, 6417.326180750954, 6427.839304017437, 6432.678366081584, 6535.771248006297, 6557.147570815763, 6667.66661666905, 6668.933310667026, 6748.190217173774, 6498.610083395011, 6565.474762018932, 6567.408933196035, 6578.5395022347975, 6656.7670131545165], "value": 6245.877509197111, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.6427443376047, 805.9643358284605, 836.4646626159258, 856.1566856646205, 863.9568021598918, 35999.82000089999, 36995.44506832396, 37135.4429683555, 37165.32756025982, 37183.62816371836, 36749.44875826865, 37107.628923710756, 37144.071398215056, 37170.0, 37211.813940930326, 6243.7502499900065, 7480.0649918760155, 7496.250374962515, 7508.436867234952, 7519.774406767779, 6381.93618063818, 7414.59219742914, 7426.183119856812, 7522.13495448025, 7538.283863033003, 7459.850802983964, 7484.363829074535, 7484.962575187116, 7495.625218739068, 7517.548947063166, 7957.562334071624, 8131.0, 8222.17778222179, 8222.62998165078, 8226.0, 8054.23484768946, 8090.514569125871, 8136.0, 8144.104148543655, 8156.225158609982, 34682.82658586716, 34931.0, 34964.65035349665, 35015.4747678785, 35130.47304290438, 3132.4518209313505, 3580.373434648926, 3619.402798538238, 3621.1309285771226, 3629.274145170976, 28747.712522874714, 29117.56323655147, 29188.854055729797, 29214.0, 29223.123306300633, 2965.273507990556, 3059.7858149929516, 3074.1546074829603, 3076.107928700674, 3108.347247078094, 202408.0, 253264.0, 255673.0, 256625.7168714163, 257636.0, 37200.627993720256, 46960.06079878383, 46960.295595566095, 47019.52980470152, 47287.527124729, 40887.97780055522, 49397.29520285993, 49642.75178624156, 49774.75112624414, 49906.0, 15259.465918692793, 15304.540863774104, 15311.770323445156, 15419.383224670888, 15441.0, 11795.764084718257, 11843.111766617538, 12787.936060319638, 12789.36053197355, 12798.0, 13148.671283217996, 13287.003474739433, 13318.267495287851, 13332.6666833328, 13464.259465729283, 4967.90064198714, 5850.502707269845, 5891.293044834647, 5915.881682366328, 5930.614510056833, 931.9394239374419, 960.0606774315843, 996.3473924579412, 1016.5730393234711, 1036.3468772576728, 19025.0, 22831.657525137136, 23117.537649246922, 23167.652485212737, 23706.407339816644, 915.6383228624655, 1027.2501074215863, 1084.6800193942806, 1097.9725506862392, 1153.3656488931053, 11609.767804644029, 11861.881381186251, 11960.40197990097, 12041.39793010346, 12092.516299348103, 283140.0, 334633.0, 335089.0, 335816.0, 336335.0, 52210.73894630503, 52330.21504677433, 52485.737571311896, 52658.999999999236, 52737.0, 1518.8860835437386, 1526.29027502212, 1530.066659337803, 1531.4563330017907, 1532.3181184372847, 22250.332490025325, 22669.88665056664, 23212.419689507897, 23225.651615225448, 24094.9199860409, 30631.693683063775, 37285.4407183887, 37452.62547374491, 37587.248255034196, 37631.0, 297404.0, 297539.5123024371, 297696.511517441, 298573.0, 298939.0, 28028.71971280261, 28477.857610711813, 31770.205744856565, 33203.667963320055, 33647.831760841036, 39696.60303396929, 41297.79351103225, 42112.0, 42236.78881605572, 42373.78813105915, 27268.06306194133, 28028.29929251785, 28665.28336791597, 29821.254468638457, 30114.096577102722, 28478.82668461208, 29193.97821076252, 29967.400651987275, 30256.0, 30626.693733062384, 32691.019269421955, 33027.18350490746, 34379.65620343765, 34512.48231276583, 35153.121171969884, 23547.529049418572, 28357.858210708815, 28707.900765218925, 30013.0, 30312.69687303187, 23498.765012349657, 23743.287701368987, 23807.690577017736, 23906.163284284965, 23932.641010385207], "value": 16734.00795230516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.53006054067015, 502.6506577928341, 505.50208045075595, 513.2327170879535, 532.1485623003193, 20695.06872190751, 21554.245601403953, 21648.675269870946, 22328.995195216212, 22404.215852445166, 19828.40514784555, 22539.0, 23381.83090845455, 23702.288931332045, 23767.524649507, 5037.848864534069, 6212.9068063979075, 6380.585261957981, 6401.071844582532, 6472.870542589146, 6224.937750622504, 6301.023341382071, 6333.683315834213, 6341.746330146789, 6344.397282258201, 5829.387914269004, 6076.544259180557, 6137.570370074097, 6159.661218632973, 6310.463610593105, 4852.9757351213375, 6019.668918209497, 6073.028315469526, 6090.482309003718, 6273.1531243282025, 5526.171074338841, 5526.530244929146, 5533.474319939599, 5534.944650553483, 5542.418046105182, 27619.0, 28672.283192920135, 29017.70982290171, 29021.839126434916, 29104.9813256535, 2177.7277840269967, 2252.8986195621237, 2271.46620544172, 2275.6586512023164, 2275.8634481931135, 18332.54168645781, 18623.90688046551, 18757.249710011583, 18776.906115469334, 18884.244630214776, 2155.8275337972923, 2184.388371256051, 2199.714037175173, 2201.7467991180897, 2212.712347394828, 141449.2927535366, 169341.0, 183360.08319958314, 183959.0, 212837.0, 11841.88158118425, 13614.047016708933, 13845.16928984264, 14559.41762329495, 14875.776863347059, 19604.21583136687, 19854.01459854003, 19856.20575176977, 19883.701744473845, 19900.402987910387, 9884.654037108667, 9936.552855121538, 10212.540435680412, 10239.078482936422, 10240.180785537139, 9574.808503829885, 9640.276979226586, 9646.855297170549, 9678.612855485842, 9682.419054856731, 8787.516686582172, 8797.38418310725, 8845.584706447065, 8880.777980550409, 8942.50816205115, 2658.8803503842373, 2661.7604415602686, 2664.307280107147, 2675.1038402135114, 2771.7540317555513, 934.5841100710119, 941.6892425499568, 943.580106852444, 977.0864241933815, 993.3642468819961, 20361.694574581394, 20494.38516844497, 20583.07376168046, 21339.039743211593, 21493.05728363124, 965.3146266151111, 988.5749127874939, 1010.4139599032543, 1016.1058268723624, 1027.2603725317758, 10481.213908956706, 10484.947575262075, 10554.683359499228, 10719.303245289031, 11202.831957520644, 574700.7996565221, 589758.0, 601113.0, 603576.0, 603987.0, 154897.0, 158837.0, 159041.2047939776, 159946.20026900023, 160289.0, 979.24108815667, 1028.4446398944635, 1031.4275577054657, 1031.489412740698, 1118.3625333559792, 18597.34909278169, 20810.47973800667, 21334.03996820116, 21763.9118044094, 22633.0, 68163.0, 69424.0, 70290.64854675694, 71881.0, 72346.0, 97613.0, 100202.0, 100822.0, 105226.0, 105823.0, 11399.829002564802, 11582.826257606312, 11724.413779311171, 11779.411029448665, 11919.0, 15230.543083707507, 16014.359425622844, 16101.516954491384, 18383.26466941361, 18574.628507430047, 12486.562970295996, 13185.670358240737, 13387.665308366979, 13518.188908665119, 13569.592912212649, 11997.64007079789, 12050.698732531757, 12222.266664000188, 12434.067444941846, 12568.87431125677, 13870.237136957161, 13887.305634718427, 13946.372413241226, 13963.441462341392, 14065.226412547408, 8833.33749968742, 10749.462526873469, 10777.676669699922, 10908.836367454653, 10952.561897524329, 12970.610881673565, 13097.541586044825, 13101.0, 13110.672233194246, 13189.538366157138], "value": 11392.314110590181, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1399.1884706870017, 1445.024608389337, 1452.4843680493418, 1456.9854301456983, 1467.3543640798052, 11616.767664646713, 13413.12814667046, 13834.824039956604, 14011.579652610439, 14029.36867840947, 13496.190228586267, 13919.0, 13919.860801391984, 13920.234387108705, 13969.30153492324, 2273.9658905116394, 2844.8008639395252, 2862.5419932810755, 2864.4843928092937, 2864.770818334528, 2703.9324016899536, 2811.0, 2813.7186281371905, 2819.0697069966864, 2827.46278207141, 2311.537692461506, 2406.3863714752715, 2410.7950824179966, 2764.543850264714, 2860.585215143803, 2831.8584070796583, 2846.658400991884, 2856.8428736419487, 2859.356644754934, 2859.6997315281906, 2817.647794025747, 2831.1648063821017, 2838.7019362967003, 2838.815476993989, 2840.1053668094482, 13376.197428154343, 13701.177929324273, 13703.931480342535, 13709.931450342685, 13787.862121378757, 7629.80925476862, 7902.80242993924, 7935.246151615645, 7940.205979402013, 7961.681532738741, 4709.152352576535, 4858.1983972644475, 4892.926606100911, 4898.485659005789, 4965.851024469272, 1372.540199033325, 1376.7521846067705, 1384.6399936016608, 1405.3838086200951, 1406.521782593923, 21120.894395528077, 21229.5754084919, 21233.68149477759, 21293.680594790934, 21339.35981920545, 6691.5315927884585, 6726.159230096238, 6838.794836154923, 6939.895901561481, 6943.375096241362, 14233.217173055582, 14382.208978506282, 14469.0, 14491.275436228147, 14532.927335363256, 7521.9247807522315, 7611.20082391352, 7665.425093118039, 7669.654865531064, 7672.42456815741, 6071.392860713893, 6770.424513916274, 7017.3684368407085, 7037.0, 7098.183708873443, 4789.712617242976, 4889.997550502138, 5068.771905264272, 5198.428172900978, 5348.171033489815, 1326.0253713520556, 1543.5060780550116, 1555.8366371530826, 1559.438602103242, 1578.4159860851557, 710.989335159973, 778.8208711996159, 844.7043534762784, 857.2070834478083, 870.5647176411794, 6486.416222539994, 7040.577565345993, 7253.782386528413, 7275.454340924452, 7283.380912622486, 963.816874793787, 981.9558119884622, 1013.2805707947292, 1015.6648306058983, 1038.444432228754, 4177.885783718105, 4394.143142087262, 4395.824167033283, 4577.816887324537, 4592.288195329729, 18273.3604323848, 18570.907145464185, 18634.06829658539, 18672.533186670444, 18710.438686839418, 17389.0, 18556.0, 18634.0, 18636.34772782946, 18653.533661658566, 9249.815003699889, 9257.028012058772, 9525.714228573153, 9542.61829526825, 9600.615975360908, 7903.328217101611, 8133.186681331819, 8175.427720059539, 8339.45793523419, 8374.581270936551, 16348.264328105026, 16450.75323870119, 16531.504054878373, 17031.14844257807, 17052.97682139026, 15627.296771645531, 16613.41853035138, 17108.48674539766, 17113.14434278306, 17147.657046858745, 12345.321007344683, 12570.49718011306, 12572.874271257537, 12617.93691031539, 12619.11666183395, 12468.937655311665, 12486.0, 12508.249505029728, 12544.121911466109, 12558.246505209716, 9311.906880931376, 9815.85276220843, 9883.0, 9940.701778946643, 10311.535980880728, 8389.412741108308, 9781.899364588757, 9819.263555233529, 9989.900100998897, 10287.279890407599, 8890.06654301289, 8941.284697224337, 9044.914610246813, 9054.818903622023, 9180.724578262663, 6624.735010599522, 6950.93049069523, 7024.1571011478945, 7191.496595238283, 7225.819354516179, 6927.653617319214, 7024.121984751906, 7194.856102877809, 7245.456590755609, 7267.745628902963], "value": 6193.008940613888, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.8888483436727, 447.7022779851399, 462.38964566771864, 471.55202557570294, 473.91000698393697, 5409.647588102975, 5719.857003574913, 6010.248718910137, 6483.222013358391, 6588.967055164718, 5542.473465020817, 6372.585781924172, 6384.5211609129265, 6392.7442902283965, 6393.232812062559, 4824.179889418799, 4835.419749630049, 4850.781714822824, 4857.198562237244, 4891.657583969123, 4125.443065186207, 4818.879528011793, 4822.807087716487, 4836.782344794493, 4838.201696720037, 4621.306803979413, 4688.367070445497, 4821.493743156971, 4822.565969062784, 4826.565609095181, 4786.56920877121, 4802.711837289757, 4811.7112973221365, 4818.831340903087, 4844.2733589961435, 3495.860165593373, 4189.62293393593, 4196.223698615766, 4236.152769446123, 4240.851570195028, 6179.079431037222, 6203.8138855834395, 6227.159333489966, 6232.736100219296, 6243.1883855099, 4671.0, 4702.506236845116, 5677.318721753375, 5693.658380497183, 5707.0, 6493.220813502363, 6860.5197636165485, 6879.552829066145, 6904.930950690529, 6991.552956465247, 3981.467135152987, 4795.592374648159, 4804.6876952997945, 4809.1583972804965, 4814.146553576863, 6674.132362792854, 7293.343599076109, 7340.669669864816, 7351.0, 7356.668949897267, 4357.869263922087, 4662.0209755950955, 4890.679516530517, 4904.092742842586, 4912.38595175603, 4912.673578133956, 4988.875278118004, 4999.450060493343, 4999.850004499871, 5000.574951129122, 4716.410448693913, 5670.809130082646, 5682.54539636828, 5690.174924635905, 5733.3406658234835, 6611.404973552307, 6921.961705744178, 6925.58446493212, 6940.236573976858, 6965.825854353681, 1409.689868228988, 1473.7052589482146, 1602.543275166572, 1696.2197389201106, 1697.117498900565, 1052.9947350263353, 1083.7399024234128, 1086.3970496374588, 1087.255230167338, 1107.595727559435, 5756.194132821408, 5879.176915231786, 6399.424051835264, 6432.847904526749, 6576.473882089422, 5913.994620914455, 6251.687415629201, 7170.856582868313, 7199.568025918462, 7201.963990180016, 5691.345495268097, 6309.905351419732, 6528.673566321665, 6537.934620653828, 6603.537752357289, 3113.517404802259, 3209.8716051357683, 3680.7301480989017, 3683.5395575553057, 3721.646443587909, 6347.873042539123, 6654.966725166343, 7319.5242309249725, 7347.595882226529, 7354.6690398931105, 5798.5071268941765, 5849.3858144894175, 7292.635368231568, 7314.561126332437, 7319.487635865438, 5947.048472244506, 6191.721372538246, 6201.844953876189, 7194.7696214173775, 7352.264773522605, 5582.916256256241, 5835.9708201458725, 6305.6216627000695, 6662.300458451987, 7119.679614417467, 6931.653417329013, 6951.30486951321, 6986.54587451814, 7004.3696067354185, 7026.473014524034, 6805.65971701403, 6806.93193068063, 7288.599127048064, 7297.781066568012, 7301.780946571611, 4829.468758436639, 4841.4674385816825, 4857.975710121426, 4888.584470320063, 4916.164252077227, 5862.794802181903, 6259.874802504016, 7100.928990710234, 7134.714611415485, 7153.2131465537705, 6787.864242715217, 6852.965735171292, 6861.725530978904, 6892.758753443605, 6897.7585784497305, 6216.875662486635, 6508.674566271762, 6711.798646040626, 6865.18072710722, 6874.9656251718425, 5290.81482148123, 6539.0, 6659.866802663822, 6680.732770689118, 6697.698603562754, 5375.623706340675, 6795.864082718417, 6843.281455447305, 6844.965775171092, 6871.278515755974, 6626.90059649096, 6645.767398141042, 6647.567908085959, 6666.566673166229, 6689.46484281266], "value": 5007.97224629235, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.8411059843326, 828.0891019878135, 835.2858306148244, 851.9403641745076, 860.2300940658112, 17133.742993855103, 17561.473155805314, 17581.472555823315, 17600.855944363622, 17611.82388176118, 17329.306827726876, 18368.72446913294, 18501.81498185021, 18606.16272267748, 18630.72053919189, 1002.3634991780217, 1013.0882206014609, 1026.8049070676584, 1032.473438546343, 1038.1539045677787, 1012.3116280928965, 1022.4121130350042, 1024.3444195714712, 1024.5133561558237, 1027.1064174168484, 902.3728508686465, 944.489975413276, 977.3451787302524, 1052.41590917041, 1073.0396295315688, 1025.0927928783044, 1044.6657069737687, 1049.396596956753, 1052.952617132231, 1053.5680371047833, 748.4498893313411, 763.9503432276884, 770.2143813310448, 771.6103367799282, 772.5326177662546, 17279.568010799703, 17362.65274694499, 17404.825951740448, 17617.911910440493, 17651.02919339436, 17141.0, 17190.0, 17323.393681221096, 17411.5647108822, 17460.476185714448, 14991.650751432424, 15038.924805376013, 15109.47116850905, 15117.39530418782, 15124.773128403083, 3553.129483276588, 3567.233044895334, 3585.928281434357, 3599.6760291573883, 3601.243738814852, 113808.43095784468, 125340.0, 127793.0, 158309.4169058306, 158528.0, 13906.026578139434, 14218.431262749375, 14227.004109712429, 14380.856191437952, 14422.0, 27460.588091178248, 27484.725152748615, 27786.305342366602, 27788.30529236785, 27915.0, 7835.608219588998, 8163.346932245406, 8227.177282271845, 8312.958435207905, 8331.750047498585, 4089.284375234322, 4118.5263694674895, 4147.19129769692, 4155.459790227251, 4158.293090174678, 4355.651547876162, 4357.520672725997, 4651.62786977041, 4666.626669866402, 4726.0, 838.1450920061503, 869.4174902815129, 869.6217145541709, 1006.2000709436016, 1008.9041541053588, 644.458654730029, 658.9538732288789, 682.331315310998, 697.6825544377297, 707.5754547271699, 11265.15511336653, 11269.380184089954, 11691.590794322157, 11894.583689570823, 12399.442025108892, 580.7241560258843, 620.2340109964191, 642.1202951955757, 669.210331808461, 671.1744554198364, 5109.412417571953, 5824.592278540546, 6037.09443583466, 6555.672216389162, 6668.761565587809, 136535.31732341473, 142116.0, 143668.0, 144111.0, 149826.50173498126, 106443.0, 113443.86556134498, 113447.0, 113874.0, 114709.0, 101255.0, 103040.0, 103604.0, 106297.0, 108177.0, 10940.45297735126, 11897.988670962719, 12421.441035153259, 12475.563355282522, 12892.935535322264, 37420.81289593535, 38846.44614215513, 46763.06473870572, 46992.530074698814, 49758.502414976836, 56212.718936406694, 60237.350285975226, 61630.0, 64937.35062649313, 67278.32721672721, 16408.917955410623, 16690.66618667644, 17391.913040434716, 17518.73721894147, 17564.946103233848, 18788.530286742392, 18797.90601046986, 19020.524486887938, 19042.952637605064, 19184.712229316294, 11189.992900638981, 14930.552083437515, 15191.316390762664, 17049.48851534456, 17323.653526929644, 16604.50186494407, 16730.4144354947, 16884.0, 17299.7405038922, 17600.29598816033, 16299.674006520041, 16366.509004729876, 16689.49931502057, 16820.747688784435, 16927.915360423118, 11288.49201785938, 11421.37182454973, 12001.519939202333, 12199.14605977573, 12209.328486932949, 13058.934705326412, 13143.868561314264, 13153.868461315264, 13175.538856139989, 13484.797728033891], "value": 8805.204215429874, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1523.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 124.0, 123.0, 119.0, 115.0, 113.0, 102.0, 114.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 113.0, 151.0, 141.0, 109.0, 126.0, 112.0, 145.0, 147.0, 111.0], "value": 128.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 202.0, 202.0, 199.0, 193.0, 196.0, 197.0, 197.0, 199.0, 197.0], "value": 198.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 75.0, 80.0, 147.0, 57.0, 58.0, 61.0, 60.0, 126.0, 60.0], "value": 79.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 127.0, 69.0, 72.0, 84.0, 134.0, 69.0, 71.0, 106.0], "value": 87.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 105.0, 93.0, 92.0, 92.0, 92.0, 92.0, 92.0, 93.0, 89.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 125.0, 95.0, 138.0, 153.0, 98.0, 138.0, 153.0, 96.0, 95.0], "value": 118.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 92.0, 90.0, 92.0, 91.0, 89.0, 93.0, 125.0, 146.0], "value": 99.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 47.0, 51.0, 50.0, 50.0, 51.0, 52.0, 52.0, 53.0, 52.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 71.0, 54.0, 56.0, 60.0, 58.0, 56.0, 56.0, 55.0, 56.0], "value": 57.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 83.0, 78.0, 79.0, 83.0, 79.0, 80.0, 84.0, 80.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 136.0, 135.0, 142.0, 135.0, 137.0, 140.0, 146.0, 148.0, 171.0], "value": 143.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 233.0, 222.0, 184.0, 232.0, 223.0, 182.0, 183.0, 181.0, 180.0], "value": 200.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 63.0, 66.0, 66.0, 64.0, 68.0, 67.0, 69.0, 77.0, 94.0], "value": 70.2, "unit": "score"}]}]} 16:04:04 INFO - Return code: 0 16:04:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:04:04 INFO - # TBPL SUCCESS # 16:04:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:04:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:04:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:04:04 INFO - 2017-06-20 16:04:04.177 screenresolution[1665:10995] starting screenresolution argv=screenresolution get 16:04:04 INFO - 2017-06-20 16:04:04.179 screenresolution[1665:10995] Display 0: 1600x1200x32@60 16:04:04 INFO - 2017-06-20 16:04:04.185 screenresolution[1666:10998] starting screenresolution argv=screenresolution list 16:04:04 INFO - Available Modes on Display 0 16:04:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:04:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:04:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:04:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:04:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:04:04 INFO - Intel Iris: 16:04:04 INFO - Chipset Model: Intel Iris 16:04:04 INFO - Type: GPU 16:04:04 INFO - Bus: Built-In 16:04:04 INFO - VRAM (Dynamic, Max): 1536 MB 16:04:04 INFO - Vendor: Intel (0x8086) 16:04:04 INFO - Device ID: 0x0a2e 16:04:04 INFO - Revision ID: 0x0009 16:04:04 INFO - Displays: 16:04:04 INFO - Display: 16:04:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:04:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:04:04 INFO - Display Serial Number: 1600x1200 60 16:04:04 INFO - Main Display: Yes 16:04:04 INFO - Mirror: Off 16:04:04 INFO - Online: Yes 16:04:04 INFO - Rotation: Supported 16:04:04 INFO - Return code: 0 16:04:04 INFO - Running post-action listener: _resource_record_post_action 16:04:04 INFO - [mozharness: 2017-06-20 23:04:04.424970Z] Finished run-tests step (success) 16:04:04 INFO - Running post-run listener: _resource_record_post_run 16:04:04 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 43534848; Write bytes: 557658112; Read time: 485; Write time: 1840 16:04:04 INFO - TinderboxPrint: CPU usage
53.1% 16:04:04 INFO - TinderboxPrint: I/O read bytes / time
43,534,848 / 485 16:04:04 INFO - TinderboxPrint: I/O write bytes / time
557,658,112 / 1,840 16:04:04 INFO - TinderboxPrint: CPU idle
2,359.2 (46.9%) 16:04:04 INFO - TinderboxPrint: CPU system
1,020.8 (20.3%) 16:04:04 INFO - TinderboxPrint: CPU user
1,649.6 (32.8%) 16:04:04 INFO - TinderboxPrint: Swap in / out
282,161,152 / 0 16:04:04 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 225788416; Write bytes: 225724416; Read time: 16604; Write time: 469 16:04:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:04:04 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 42498560; Write bytes: 310962176; Read time: 467; Write time: 1317 16:04:04 INFO - Running post-run listener: _upload_blobber_files 16:04:04 INFO - Blob upload gear active. 16:04:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:04:04 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 16:04:04 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:04:04 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:04:05 INFO - (blobuploader) - INFO - Open directory for files ... 16:04:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:04:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:04:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:06 INFO - (blobuploader) - INFO - Done attempting. 16:04:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:04:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:04:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:07 INFO - (blobuploader) - INFO - Done attempting. 16:04:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:04:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:04:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:08 INFO - (blobuploader) - INFO - Done attempting. 16:04:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:04:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:04:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:04:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:04:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:04:10 INFO - (blobuploader) - INFO - Done attempting. 16:04:10 INFO - (blobuploader) - INFO - Iteration through files over. 16:04:10 INFO - Return code: 0 16:04:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:04:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:04:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66191e371f0c8f9de434ba9ba1107346fb22a78f2d60197a96b8c970093143fc0e9a46ee215cdb9eaf61a53fb715f38bebdd96e5489abb89aa2e8c1161cfcb7c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37f228258ad8950d53916d3c8306c566163b04d39736143c464970abf8d0eb99bd1d8b223f0930e59c6c852088746f99d3b1276ad970ca12874e15952fef60c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ad24a2f3c80d44240e2e6bb00d6c5cd475ec8f157da5a2f622839f3694bb400fe09b6e38ac839cde16ac00dd26af6d146063fa3a7925bfe7408b338d393d7910", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0638e28d7ff99843d3b57c05f83467a13a2800acc859c8976f704e6aef7b5e43de52efa84b6d1887f4d9919281c773a8ab45963dddf3f5c15a5e7f7a576f4a7"} 16:04:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:04:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:04:10 INFO - Contents: 16:04:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66191e371f0c8f9de434ba9ba1107346fb22a78f2d60197a96b8c970093143fc0e9a46ee215cdb9eaf61a53fb715f38bebdd96e5489abb89aa2e8c1161cfcb7c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37f228258ad8950d53916d3c8306c566163b04d39736143c464970abf8d0eb99bd1d8b223f0930e59c6c852088746f99d3b1276ad970ca12874e15952fef60c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ad24a2f3c80d44240e2e6bb00d6c5cd475ec8f157da5a2f622839f3694bb400fe09b6e38ac839cde16ac00dd26af6d146063fa3a7925bfe7408b338d393d7910", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0638e28d7ff99843d3b57c05f83467a13a2800acc859c8976f704e6aef7b5e43de52efa84b6d1887f4d9919281c773a8ab45963dddf3f5c15a5e7f7a576f4a7"} 16:04:10 INFO - Running post-run listener: copy_logs_to_upload_dir 16:04:10 INFO - Copying logs to upload dir... 16:04:10 INFO - mkdir: /builds/slave/test/build/upload/logs 16:04:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.291315 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-20 16:04:10.642093) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 16:04:10.645949) ========= 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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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/Mozilla-Beta/sha512/66191e371f0c8f9de434ba9ba1107346fb22a78f2d60197a96b8c970093143fc0e9a46ee215cdb9eaf61a53fb715f38bebdd96e5489abb89aa2e8c1161cfcb7c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37f228258ad8950d53916d3c8306c566163b04d39736143c464970abf8d0eb99bd1d8b223f0930e59c6c852088746f99d3b1276ad970ca12874e15952fef60c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ad24a2f3c80d44240e2e6bb00d6c5cd475ec8f157da5a2f622839f3694bb400fe09b6e38ac839cde16ac00dd26af6d146063fa3a7925bfe7408b338d393d7910", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0638e28d7ff99843d3b57c05f83467a13a2800acc859c8976f704e6aef7b5e43de52efa84b6d1887f4d9919281c773a8ab45963dddf3f5c15a5e7f7a576f4a7"} build_url:https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011021 build_url: 'https://queue.taskcluster.net/v1/task/ZUoiQLwhRaWx3w0S2PIKLg/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/66191e371f0c8f9de434ba9ba1107346fb22a78f2d60197a96b8c970093143fc0e9a46ee215cdb9eaf61a53fb715f38bebdd96e5489abb89aa2e8c1161cfcb7c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/37f228258ad8950d53916d3c8306c566163b04d39736143c464970abf8d0eb99bd1d8b223f0930e59c6c852088746f99d3b1276ad970ca12874e15952fef60c6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ad24a2f3c80d44240e2e6bb00d6c5cd475ec8f157da5a2f622839f3694bb400fe09b6e38ac839cde16ac00dd26af6d146063fa3a7925bfe7408b338d393d7910", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0638e28d7ff99843d3b57c05f83467a13a2800acc859c8976f704e6aef7b5e43de52efa84b6d1887f4d9919281c773a8ab45963dddf3f5c15a5e7f7a576f4a7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 16:04:10.678943) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 16:04:10.679286) ========= 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.vgo58dRZxJ/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.A21LZ5Nzrr/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005135 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 16:04:10.728312) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 16:04:10.728643) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 16:04:10.728964) ========= ========= Total master_lag: 0.17 =========