builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0024 starttime: 1497999519.02 results: success (0) buildid: 20170620140823 builduid: e2f6e4560d064f1ab548814aeb70f182 revision: cd547b10e59e7db0b381a21598c65e183fc4dc2b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:39.020910) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:39.021431) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:39.021793) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:39.046424) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:39.047143) ========= 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.oKedHuTzSs/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.10lWI6oqBw/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:58:39-- 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% 76.4M=0s 2017-06-20 15:58:39 (76.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125680 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:39.194573) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:39.194917) ========= 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.oKedHuTzSs/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.10lWI6oqBw/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.021457 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:39.243638) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-20 15:58:39.244024) ========= 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.oKedHuTzSs/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.10lWI6oqBw/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:58:39,317 truncating revision to first 12 chars 2017-06-20 15:58:39,317 Setting DEBUG logging. 2017-06-20 15:58:39,317 attempt 1/10 2017-06-20 15:58:39,317 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:58:41,435 unpacking tar archive at: mozilla-beta-cd547b10e59e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.384172 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-20 15:58:41.653553) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:41.654217) ========= script_repo_revision: cd547b10e59e7db0b381a21598c65e183fc4dc2b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:41.655244) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:41.655838) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 15:58:41.675391) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 15:58:41.676134) ========= /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.oKedHuTzSs/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.10lWI6oqBw/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:58:41 INFO - MultiFileLogger online at 20170620 15:58:41 in /builds/slave/test 15:58:41 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:58:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:58:41 INFO - {'append_to_log': False, 15:58:41 INFO - 'base_work_dir': '/builds/slave/test', 15:58:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:58:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:58:41 INFO - 'branch': 'Mozilla-Beta', 15:58:41 INFO - 'buildbot_json_path': 'buildprops.json', 15:58:41 INFO - 'config_files': ('talos/mac_config.py',), 15:58:41 INFO - 'default_actions': ('clobber', 15:58:41 INFO - 'read-buildbot-config', 15:58:41 INFO - 'download-and-extract', 15:58:41 INFO - 'populate-webroot', 15:58:41 INFO - 'create-virtualenv', 15:58:41 INFO - 'install', 15:58:41 INFO - 'setup-mitmproxy', 15:58:41 INFO - 'run-tests'), 15:58:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:58:41 INFO - 'download_minidump_stackwalk': True, 15:58:41 INFO - 'download_symbols': 'ondemand', 15:58:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:58:41 INFO - 'tooltool.py': '/tools/tooltool.py', 15:58:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:58:41 INFO - '/tools/misc-python/virtualenv.py')}, 15:58:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:58:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:58:41 INFO - 'gecko_profile': False, 15:58:41 INFO - 'gecko_profile_interval': 0, 15:58:41 INFO - 'installer_path': 'installer.exe', 15:58:41 INFO - 'log_level': 'info', 15:58:41 INFO - 'log_name': 'talos', 15:58:41 INFO - 'log_to_console': True, 15:58:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:58:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:58:41 INFO - 'opt_config_files': (), 15:58:41 INFO - 'pip_index': False, 15:58:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:58:41 INFO - 'cmd': ('bash', 15:58:41 INFO - '-c', 15:58:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:58:41 INFO - 'enabled': True, 15:58:41 INFO - 'halt_on_failure': False, 15:58:41 INFO - 'name': 'check_screen_resolution'},), 15:58:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:58:41 INFO - 'cmd': ('bash', 15:58:41 INFO - '-c', 15:58:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:58:41 INFO - 'enabled': True, 15:58:41 INFO - 'halt_on_failure': False, 15:58:41 INFO - 'name': 'check_screen_resolution'},), 15:58:41 INFO - 'run_cmd_checks_enabled': True, 15:58:41 INFO - 'suite': 'dromaeojs-e10s', 15:58:41 INFO - 'system_bits': '32', 15:58:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:58:41 INFO - 'title': 't-yosemite-r7-0024', 15:58:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:58:41 INFO - 'use_talos_json': True, 15:58:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:58:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:58:41 INFO - 'work_dir': 'build'} 15:58:41 INFO - [mozharness: 2017-06-20 22:58:41.881714Z] Running clobber step. 15:58:41 INFO - Running pre-action listener: _resource_record_pre_action 15:58:41 INFO - Running main action method: clobber 15:58:41 INFO - rmtree: /builds/slave/test/build 15:58:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:58:45 INFO - Running post-action listener: _resource_record_post_action 15:58:45 INFO - [mozharness: 2017-06-20 22:58:45.415386Z] Finished clobber step (success) 15:58:45 INFO - [mozharness: 2017-06-20 22:58:45.415603Z] Running read-buildbot-config step. 15:58:45 INFO - Running pre-action listener: _resource_record_pre_action 15:58:45 INFO - Running main action method: read_buildbot_config 15:58:45 INFO - Using buildbot properties: 15:58:45 INFO - { 15:58:45 INFO - "project": "", 15:58:45 INFO - "product": "firefox", 15:58:45 INFO - "script_repo_revision": "production", 15:58:45 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 15:58:45 INFO - "repository": "", 15:58:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 15:58:45 INFO - "buildid": "20170620140823", 15:58:45 INFO - "pgo_build": "False", 15:58:45 INFO - "basedir": "/builds/slave/test", 15:58:45 INFO - "buildnumber": 5, 15:58:45 INFO - "slavename": "t-yosemite-r7-0024", 15:58:45 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:58:45 INFO - "platform": "yosemite_r7_devedition", 15:58:45 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 15:58:45 INFO - "branch": "mozilla-beta", 15:58:45 INFO - "repo_path": "releases/mozilla-beta", 15:58:45 INFO - "revision": "cd547b10e59e7db0b381a21598c65e183fc4dc2b", 15:58:45 INFO - "stage_platform": "macosx64-devedition", 15:58:45 INFO - "builduid": "e2f6e4560d064f1ab548814aeb70f182", 15:58:45 INFO - "slavebuilddir": "test" 15:58:45 INFO - } 15:58:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 15:58:45 INFO - Running post-action listener: _resource_record_post_action 15:58:45 INFO - [mozharness: 2017-06-20 22:58:45.421074Z] Finished read-buildbot-config step (success) 15:58:45 INFO - [mozharness: 2017-06-20 22:58:45.421242Z] Running download-and-extract step. 15:58:45 INFO - Running pre-action listener: _resource_record_pre_action 15:58:45 INFO - Running main action method: download_and_extract 15:58:45 INFO - mkdir: /builds/slave/test/build/tests 15:58: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:58:45 INFO - trying https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 15:58:45 INFO - Downloading https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/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:58:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/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:58:46 INFO - Downloaded 1290 bytes. 15:58:46 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 15:58:46 INFO - Using the following test package requirements: 15:58:46 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:58:46 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 15:58:46 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:58:46 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:58:46 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 15:58:46 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:58:46 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 15:58:46 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:58:46 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:58:46 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 15:58:46 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 15:58:46 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:58:46 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 15:58:46 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:58:46 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 15:58:46 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:58:46 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 15:58:46 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 15:58:46 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 15:58:46 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 15:58:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:58:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:58:46 INFO - Fetch https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:58:48 INFO - Content-Length response header: 59545242 15:58:48 INFO - Bytes received: 59545242 15:59:06 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:59:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 15:59:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 15:59:06 INFO - Fetch https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 15:59:08 INFO - Content-Length response header: 59545242 15:59:08 INFO - Bytes received: 59545242 15:59:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 15:59:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 15:59:25 INFO - Fetch https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 15:59:26 INFO - Content-Length response header: 14454470 15:59:26 INFO - Bytes received: 14454470 15:59:27 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:59:27 INFO - trying https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:59:27 INFO - Downloading https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 15:59:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/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:59:29 INFO - Downloaded 66842906 bytes. 15:59:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:59:29 INFO - mkdir: /builds/slave/test/properties 15:59:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:59:29 INFO - Writing to file /builds/slave/test/properties/build_url 15:59:29 INFO - Contents: 15:59:29 INFO - build_url:https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 15:59:29 INFO - Running post-action listener: _resource_record_post_action 15:59:29 INFO - Running post-action listener: set_extra_try_arguments 15:59:29 INFO - [mozharness: 2017-06-20 22:59:29.901927Z] Finished download-and-extract step (success) 15:59:29 INFO - [mozharness: 2017-06-20 22:59:29.902129Z] Running populate-webroot step. 15:59:29 INFO - Running pre-action listener: _resource_record_pre_action 15:59:29 INFO - Running main action method: populate_webroot 15:59:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:59:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:59:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:59:29 INFO - '--tppagecycles', 15:59:29 INFO - '7'], 15:59:29 INFO - 'tests': ['tsvgm']}}, 15:59:29 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:59:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:59:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:59:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:59:29 INFO - 'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:59:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:59:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:59:29 INFO - 'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['damp', 'tps']}, 15:59:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:59:29 INFO - 'tests': ['damp', 'tps']}, 15:59:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['dromaeo_dom']}, 15:59:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:59:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:59:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:59:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:59:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:59:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['a11yr', 15:59:29 INFO - 'ts_paint', 15:59:29 INFO - 'tpaint', 15:59:29 INFO - 'sessionrestore', 15:59:29 INFO - 'sessionrestore_no_auto_restore', 15:59:29 INFO - 'tabpaint']}, 15:59:29 INFO - 'other-e10s': {'tests': ['a11yr', 15:59:29 INFO - 'ts_paint', 15:59:29 INFO - 'tpaint', 15:59:29 INFO - 'sessionrestore', 15:59:29 INFO - 'sessionrestore_no_auto_restore', 15:59:29 INFO - 'tabpaint']}, 15:59:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:59:29 INFO - 'ts_paint', 15:59:29 INFO - 'tpaint', 15:59:29 INFO - 'sessionrestore', 15:59:29 INFO - 'sessionrestore_no_auto_restore', 15:59:29 INFO - 'tabpaint']}, 15:59:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:59:29 INFO - 'ts_paint', 15:59:29 INFO - 'tpaint', 15:59:29 INFO - 'sessionrestore', 15:59:29 INFO - 'sessionrestore_no_auto_restore', 15:59:29 INFO - 'tabpaint']}, 15:59:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['a11yr', 15:59:29 INFO - 'ts_paint', 15:59:29 INFO - 'tpaint', 15:59:29 INFO - 'sessionrestore', 15:59:29 INFO - 'sessionrestore_no_auto_restore', 15:59:29 INFO - 'tabpaint']}, 15:59:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['a11yr', 15:59:29 INFO - 'ts_paint', 15:59:29 INFO - 'tpaint', 15:59:29 INFO - 'sessionrestore', 15:59:29 INFO - 'sessionrestore_no_auto_restore', 15:59:29 INFO - 'tabpaint']}, 15:59:29 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:59:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:59:29 INFO - 'bloom_basic_ref']}, 15:59:29 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 15:59:29 INFO - 'talos_options': ['--mitmproxy', 15:59:29 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 15:59:29 INFO - '--firstNonBlankPaint'], 15:59:29 INFO - 'tests': ['quantum_pageload_google', 15:59:29 INFO - 'quantum_pageload_youtube', 15:59:29 INFO - 'quantum_pageload_amazon', 15:59:29 INFO - 'quantum_pageload_facebook']}, 15:59:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['tsvgx', 15:59:29 INFO - 'tsvgr_opacity', 15:59:29 INFO - 'tart', 15:59:29 INFO - 'tscrollx', 15:59:29 INFO - 'cart', 15:59:29 INFO - 'tsvg_static']}, 15:59:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:59:29 INFO - 'tsvgr_opacity', 15:59:29 INFO - 'tart', 15:59:29 INFO - 'tscrollx', 15:59:29 INFO - 'cart', 15:59:29 INFO - 'tsvg_static']}, 15:59:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:59:29 INFO - 'talos_options': ['--disable-e10s'], 15:59:29 INFO - 'tests': ['tp5o']}, 15:59:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:59:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:59:29 INFO - 'talos_options': ['--disable-e10s', 15:59:29 INFO - '--xperf_path', 15:59:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:29 INFO - 'tests': ['tp5n']}, 15:59:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:59:29 INFO - 'talos_options': ['--xperf_path', 15:59:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:59:29 INFO - 'tests': ['tp5n']}}, 15:59:29 INFO - 'talos.zip': {'path': '', 15:59:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:59:29 INFO - Running post-action listener: _resource_record_post_action 15:59:29 INFO - [mozharness: 2017-06-20 22:59:29.923119Z] Finished populate-webroot step (success) 15:59:29 INFO - [mozharness: 2017-06-20 22:59:29.923251Z] Running create-virtualenv step. 15:59:29 INFO - Running pre-action listener: _resource_record_pre_action 15:59:29 INFO - Running main action method: create_virtualenv 15:59:29 INFO - Creating virtualenv /builds/slave/test/build/venv 15:59:29 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:59:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:59:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:59:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:59:30 INFO - Using real prefix '/tools/python27' 15:59:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:59:30 INFO - Installing distribute.............................................................................................................................................................................................done. 15:59:33 INFO - Installing pip.................done. 15:59:33 INFO - Return code: 0 15:59:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:59:34 INFO - Reading from file tmpfile_stdout 15:59:34 INFO - Output received: 15:59:34 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:59:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:59:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:34 INFO - 'HOME': '/Users/cltbld', 15:59:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:34 INFO - 'LOGNAME': 'cltbld', 15:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:34 INFO - 'MOZ_NO_REMOTE': '1', 15:59:34 INFO - 'NO_EM_RESTART': '1', 15:59:34 INFO - 'PAGER': '/bin/cat', 15:59:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:34 INFO - 'PWD': '/builds/slave/test', 15:59:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:34 INFO - 'SHELL': '/bin/bash', 15:59:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:34 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:34 INFO - 'USER': 'cltbld', 15:59:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:34 INFO - 'XPC_FLAGS': '0x0', 15:59:34 INFO - 'XPC_SERVICE_NAME': '0', 15:59:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:34 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:59:34 INFO - Cleaning up... 15:59:34 INFO - Return code: 0 15:59:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:59:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:34 INFO - 'HOME': '/Users/cltbld', 15:59:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:34 INFO - 'LOGNAME': 'cltbld', 15:59:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:34 INFO - 'MOZ_NO_REMOTE': '1', 15:59:34 INFO - 'NO_EM_RESTART': '1', 15:59:34 INFO - 'PAGER': '/bin/cat', 15:59:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:34 INFO - 'PWD': '/builds/slave/test', 15:59:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:34 INFO - 'SHELL': '/bin/bash', 15:59:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:34 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:34 INFO - 'USER': 'cltbld', 15:59:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:34 INFO - 'XPC_FLAGS': '0x0', 15:59:34 INFO - 'XPC_SERVICE_NAME': '0', 15:59:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:34 INFO - Downloading/unpacking psutil>=3.1.1 15:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:59:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:59:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:59:36 INFO - Installing collected packages: psutil 15:59:36 INFO - Running setup.py install for psutil 15:59:37 INFO - building 'psutil._psutil_osx' extension 15:59:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:59:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:59:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:59:37 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:59:37 INFO - building 'psutil._psutil_posix' extension 15:59:37 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:59:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:59:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:59:37 INFO - ^ 15:59:37 INFO - 1 warning generated. 15:59:37 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:59:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:59:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:59:37 INFO - Successfully installed psutil 15:59:37 INFO - Cleaning up... 15:59:37 INFO - Return code: 0 15:59:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:59:37 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:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:37 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:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:37 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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:37 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:59:37 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:59:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:37 INFO - 'HOME': '/Users/cltbld', 15:59:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:37 INFO - 'LOGNAME': 'cltbld', 15:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:37 INFO - 'MOZ_NO_REMOTE': '1', 15:59:37 INFO - 'NO_EM_RESTART': '1', 15:59:37 INFO - 'PAGER': '/bin/cat', 15:59:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:37 INFO - 'PWD': '/builds/slave/test', 15:59:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:37 INFO - 'SHELL': '/bin/bash', 15:59:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:37 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:37 INFO - 'USER': 'cltbld', 15:59:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:37 INFO - 'XPC_FLAGS': '0x0', 15:59:37 INFO - 'XPC_SERVICE_NAME': '0', 15:59:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:59:38 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:59:38 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:59:38 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:59:38 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:59:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:59:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:59:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:59:39 INFO - Installing collected packages: mozsystemmonitor 15:59:39 INFO - Running setup.py install for mozsystemmonitor 15:59:39 INFO - Successfully installed mozsystemmonitor 15:59:39 INFO - Cleaning up... 15:59:39 INFO - Return code: 0 15:59:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:59:39 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:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:39 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:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:59:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:59:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:39 INFO - 'HOME': '/Users/cltbld', 15:59:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:39 INFO - 'LOGNAME': 'cltbld', 15:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:39 INFO - 'MOZ_NO_REMOTE': '1', 15:59:39 INFO - 'NO_EM_RESTART': '1', 15:59:39 INFO - 'PAGER': '/bin/cat', 15:59:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:39 INFO - 'PWD': '/builds/slave/test', 15:59:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:39 INFO - 'SHELL': '/bin/bash', 15:59:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:39 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:39 INFO - 'USER': 'cltbld', 15:59:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:39 INFO - 'XPC_FLAGS': '0x0', 15:59:39 INFO - 'XPC_SERVICE_NAME': '0', 15:59:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:39 INFO - Downloading/unpacking jsonschema==2.5.1 15:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:59:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:59:41 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:59:41 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:59:41 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:59:41 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:59:41 INFO - Downloading functools32-3.2.3-2.tar.gz 15:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:59:41 INFO - warning: no files found matching '*.txt' 15:59:41 INFO - no previously-included directories found matching 'build' 15:59:41 INFO - no previously-included directories found matching 'dist' 15:59:41 INFO - no previously-included directories found matching '.git*' 15:59:41 INFO - Installing collected packages: jsonschema, functools32 15:59:41 INFO - Running setup.py install for functools32 15:59:41 INFO - warning: no files found matching '*.txt' 15:59:41 INFO - no previously-included directories found matching 'build' 15:59:41 INFO - no previously-included directories found matching 'dist' 15:59:41 INFO - no previously-included directories found matching '.git*' 15:59:41 INFO - Successfully installed jsonschema functools32 15:59:41 INFO - Cleaning up... 15:59:41 INFO - Return code: 0 15:59:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:59:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:59:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:41 INFO - 'HOME': '/Users/cltbld', 15:59:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:41 INFO - 'LOGNAME': 'cltbld', 15:59:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:41 INFO - 'MOZ_NO_REMOTE': '1', 15:59:41 INFO - 'NO_EM_RESTART': '1', 15:59:41 INFO - 'PAGER': '/bin/cat', 15:59:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:41 INFO - 'PWD': '/builds/slave/test', 15:59:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:41 INFO - 'SHELL': '/bin/bash', 15:59:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:41 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:41 INFO - 'USER': 'cltbld', 15:59:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:41 INFO - 'XPC_FLAGS': '0x0', 15:59:41 INFO - 'XPC_SERVICE_NAME': '0', 15:59:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:59:42 INFO - Cleaning up... 15:59:42 INFO - Return code: 0 15:59:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:59: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:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59: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:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59: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', '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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:59: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 blobuploader==1.2.4 15:59:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:42 INFO - 'HOME': '/Users/cltbld', 15:59:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:42 INFO - 'LOGNAME': 'cltbld', 15:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:42 INFO - 'MOZ_NO_REMOTE': '1', 15:59:42 INFO - 'NO_EM_RESTART': '1', 15:59:42 INFO - 'PAGER': '/bin/cat', 15:59:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:42 INFO - 'PWD': '/builds/slave/test', 15:59:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:42 INFO - 'SHELL': '/bin/bash', 15:59:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:42 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:42 INFO - 'USER': 'cltbld', 15:59:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:42 INFO - 'XPC_FLAGS': '0x0', 15:59:42 INFO - 'XPC_SERVICE_NAME': '0', 15:59:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:42 INFO - Downloading/unpacking blobuploader==1.2.4 15:59: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:59: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:59: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:59: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:59:43 INFO - Downloading blobuploader-1.2.4.tar.gz 15:59:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:59:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:59:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:44 INFO - Downloading docopt-0.6.1.tar.gz 15:59:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:59:44 INFO - Installing collected packages: blobuploader, requests, docopt 15:59:44 INFO - Running setup.py install for blobuploader 15:59:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:59:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:59:44 INFO - Running setup.py install for requests 15:59:45 INFO - Running setup.py install for docopt 15:59:45 INFO - Successfully installed blobuploader requests docopt 15:59:45 INFO - Cleaning up... 15:59:45 INFO - Return code: 0 15:59:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59: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:59:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59: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:59:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:45 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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:45 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:59:45 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:59:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:45 INFO - 'HOME': '/Users/cltbld', 15:59:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:45 INFO - 'LOGNAME': 'cltbld', 15:59:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:45 INFO - 'MOZ_NO_REMOTE': '1', 15:59:45 INFO - 'NO_EM_RESTART': '1', 15:59:45 INFO - 'PAGER': '/bin/cat', 15:59:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:45 INFO - 'PWD': '/builds/slave/test', 15:59:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:45 INFO - 'SHELL': '/bin/bash', 15:59:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:45 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:45 INFO - 'USER': 'cltbld', 15:59:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:45 INFO - 'XPC_FLAGS': '0x0', 15:59:45 INFO - 'XPC_SERVICE_NAME': '0', 15:59:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9AcD8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-rbhzgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-nWVk8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-myti3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-4Yo6Vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-zemSsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-dcW6Ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-khHbJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-m4JBXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Fepf3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-BBmbeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-FqsR8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-TmVe7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-RW9oW_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-OrdMWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-nLGh5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-1gMoS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:59:47 INFO - Running setup.py install for manifestparser 15:59:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:59:47 INFO - Running setup.py install for mozcrash 15:59:48 INFO - Running setup.py install for mozdebug 15:59:48 INFO - Running setup.py install for mozdevice 15:59:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Running setup.py install for mozfile 15:59:48 INFO - Running setup.py install for mozhttpd 15:59:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Running setup.py install for mozinfo 15:59:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Running setup.py install for mozInstall 15:59:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:59:48 INFO - Running setup.py install for mozleak 15:59:49 INFO - Running setup.py install for mozlog 15:59:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:59:49 INFO - Running setup.py install for moznetwork 15:59:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:59:49 INFO - Running setup.py install for mozprocess 15:59:49 INFO - Running setup.py install for mozprofile 15:59:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:59:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:59:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:59:49 INFO - Running setup.py install for mozrunner 15:59:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:59:49 INFO - Running setup.py install for mozscreenshot 15:59:50 INFO - Running setup.py install for moztest 15:59:50 INFO - Running setup.py install for mozversion 15:59:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:59:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:59:50 INFO - Cleaning up... 15:59:50 INFO - Return code: 0 15:59:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:50 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:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:50 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:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:50 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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:50 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:59:50 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:59:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:50 INFO - 'HOME': '/Users/cltbld', 15:59:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:50 INFO - 'LOGNAME': 'cltbld', 15:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:50 INFO - 'MOZ_NO_REMOTE': '1', 15:59:50 INFO - 'NO_EM_RESTART': '1', 15:59:50 INFO - 'PAGER': '/bin/cat', 15:59:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:50 INFO - 'PWD': '/builds/slave/test', 15:59:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:50 INFO - 'SHELL': '/bin/bash', 15:59:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:50 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:50 INFO - 'USER': 'cltbld', 15:59:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:50 INFO - 'XPC_FLAGS': '0x0', 15:59:50 INFO - 'XPC_SERVICE_NAME': '0', 15:59:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:59:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-6tX863-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:59:50 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:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:59:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0QGK02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:59:50 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:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:59:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-rIGUfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:59:50 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:59:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:59:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-5fEcfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-uAZE_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-o4RPG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-fdEUyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-NepgVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-2RotxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-dNttBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-PqEc2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ByDN4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:59:51 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:59:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:59:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3zcman-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:59:52 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:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:59:52 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ybKW_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:59:52 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:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:52 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-edvipx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:59:52 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:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:59:52 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-U7GKpf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:59:52 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:59:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:59:52 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-8sfa17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:59:52 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:59:52 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:59:52 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:59:52 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:59:52 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:59:52 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:59:52 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:59:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:59:52 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:59:52 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:59:52 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:59:52 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:59:53 INFO - Downloading blessings-1.6.tar.gz 15:59:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:59:53 INFO - Installing collected packages: blessings 15:59:53 INFO - Running setup.py install for blessings 15:59:54 INFO - Successfully installed blessings 15:59:54 INFO - Cleaning up... 15:59:54 INFO - Return code: 0 15:59:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:59:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:59:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:59:54 INFO - Reading from file tmpfile_stdout 15:59:54 INFO - Current package versions: 15:59:54 INFO - blessings == 1.6 15:59:54 INFO - blobuploader == 1.2.4 15:59:54 INFO - docopt == 0.6.1 15:59:54 INFO - functools32 == 3.2.3-2 15:59:54 INFO - jsonschema == 2.5.1 15:59:54 INFO - manifestparser == 1.1 15:59:54 INFO - mozInstall == 1.13 15:59:54 INFO - mozcrash == 1.0 15:59:54 INFO - mozdebug == 0.1 15:59:54 INFO - mozdevice == 0.51 15:59:54 INFO - mozfile == 1.2 15:59:54 INFO - mozhttpd == 0.7 15:59:54 INFO - mozinfo == 0.10 15:59:54 INFO - mozleak == 0.1 15:59:54 INFO - mozlog == 3.4 15:59:54 INFO - moznetwork == 0.27 15:59:54 INFO - mozprocess == 0.25 15:59:54 INFO - mozprofile == 0.28 15:59:54 INFO - mozrunner == 6.13 15:59:54 INFO - mozscreenshot == 0.1 15:59:54 INFO - mozsystemmonitor == 0.3 15:59:54 INFO - moztest == 0.8 15:59:54 INFO - mozversion == 1.4 15:59:54 INFO - psutil == 3.1.1 15:59:54 INFO - requests == 1.2.3 15:59:54 INFO - wsgiref == 0.1.2 15:59:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:59:54 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:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:54 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:59:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:54 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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:54 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:59:54 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:59:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:54 INFO - 'HOME': '/Users/cltbld', 15:59:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:54 INFO - 'LOGNAME': 'cltbld', 15:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:54 INFO - 'MOZ_NO_REMOTE': '1', 15:59:54 INFO - 'NO_EM_RESTART': '1', 15:59:54 INFO - 'PAGER': '/bin/cat', 15:59:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:54 INFO - 'PWD': '/builds/slave/test', 15:59:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:54 INFO - 'SHELL': '/bin/bash', 15:59:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:54 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:54 INFO - 'USER': 'cltbld', 15:59:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:54 INFO - 'XPC_FLAGS': '0x0', 15:59:54 INFO - 'XPC_SERVICE_NAME': '0', 15:59:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:54 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:59:54 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:59:54 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:59:54 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:59:54 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:59:54 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:59:54 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:59:54 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:59:54 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:59:54 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:59:56 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:59:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:59:56 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:59:56 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:59:56 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:59:56 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:59:56 INFO - Installing collected packages: marionette-driver 15:59:56 INFO - Running setup.py install for marionette-driver 15:59:56 INFO - Successfully installed marionette-driver 15:59:56 INFO - Cleaning up... 15:59:56 INFO - Return code: 0 15:59:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:59:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:59:56 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 0x106301c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106305768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde22c9a9e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, '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.oKedHuTzSs/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.10lWI6oqBw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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:59:56 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:59:56 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:59:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 15:59:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:59:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:59:56 INFO - 'HOME': '/Users/cltbld', 15:59:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:59:56 INFO - 'LOGNAME': 'cltbld', 15:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:59:56 INFO - 'MOZ_NO_REMOTE': '1', 15:59:56 INFO - 'NO_EM_RESTART': '1', 15:59:56 INFO - 'PAGER': '/bin/cat', 15:59:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:59:56 INFO - 'PWD': '/builds/slave/test', 15:59:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:59:56 INFO - 'SHELL': '/bin/bash', 15:59:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 15:59:56 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 15:59:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:59:56 INFO - 'USER': 'cltbld', 15:59:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:59:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:59:56 INFO - 'XPC_FLAGS': '0x0', 15:59:56 INFO - 'XPC_SERVICE_NAME': '0', 15:59:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:59:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:59:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:59:56 INFO - Cleaning up... 15:59:56 INFO - Return code: 0 15:59:56 INFO - Running post-action listener: _resource_record_post_action 15:59:56 INFO - Running post-action listener: _start_resource_monitoring 15:59:56 INFO - Starting resource monitoring. 15:59:56 INFO - [mozharness: 2017-06-20 22:59:56.802415Z] Finished create-virtualenv step (success) 15:59:56 INFO - [mozharness: 2017-06-20 22:59:56.803389Z] Running install step. 15:59:56 INFO - Running pre-action listener: _resource_record_pre_action 15:59:56 INFO - Running main action method: install 15:59:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:59:57 INFO - Reading from file tmpfile_stdout 15:59:57 INFO - Detecting whether we're running mozinstall >=1.0... 15:59:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:59:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:59:57 INFO - Reading from file tmpfile_stdout 15:59:57 INFO - Output received: 15:59:57 INFO - Usage: mozinstall [options] installer 15:59:57 INFO - Options: 15:59:57 INFO - -h, --help show this help message and exit 15:59:57 INFO - -d DEST, --destination=DEST 15:59:57 INFO - Directory to install application into. [default: 15:59:57 INFO - "/builds/slave/test"] 15:59:57 INFO - --app=APP Application being installed. [default: firefox] 15:59:57 INFO - mkdir: /builds/slave/test/build/application 15:59:57 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:59:57 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 16:00:18 INFO - Reading from file tmpfile_stdout 16:00:18 INFO - Output received: 16:00:18 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:00:18 INFO - Running post-action listener: _resource_record_post_action 16:00:18 INFO - [mozharness: 2017-06-20 23:00:18.305379Z] Finished install step (success) 16:00:18 INFO - [mozharness: 2017-06-20 23:00:18.305532Z] Running setup-mitmproxy step. 16:00:18 INFO - Running pre-action listener: _resource_record_pre_action 16:00:18 INFO - Running main action method: setup_mitmproxy 16:00:18 INFO - Skipping: mitmproxy is not required 16:00:18 INFO - Running post-action listener: _resource_record_post_action 16:00:18 INFO - [mozharness: 2017-06-20 23:00:18.306270Z] Finished setup-mitmproxy step (success) 16:00:18 INFO - [mozharness: 2017-06-20 23:00:18.306406Z] Running run-tests step. 16:00:18 INFO - Running pre-action listener: _resource_record_pre_action 16:00:18 INFO - Running main action method: run_tests 16:00:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:00:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:00:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:00:18 INFO - 2017-06-20 16:00:18.321 screenresolution[1948:10486] starting screenresolution argv=screenresolution get 16:00:18 INFO - 2017-06-20 16:00:18.323 screenresolution[1948:10486] Display 0: 1600x1200x32@60 16:00:18 INFO - 2017-06-20 16:00:18.329 screenresolution[1949:10489] starting screenresolution argv=screenresolution list 16:00:18 INFO - Available Modes on Display 0 16:00:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:00:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:00:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:00:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:00:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:00:18 INFO - Intel Iris: 16:00:18 INFO - Chipset Model: Intel Iris 16:00:18 INFO - Type: GPU 16:00:18 INFO - Bus: Built-In 16:00:18 INFO - VRAM (Dynamic, Max): 1536 MB 16:00:18 INFO - Vendor: Intel (0x8086) 16:00:18 INFO - Device ID: 0x0a2e 16:00:18 INFO - Revision ID: 0x0009 16:00:18 INFO - Displays: 16:00:18 INFO - Display: 16:00:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:00:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:00:18 INFO - Display Serial Number: 1600x1200 60 16:00:18 INFO - Main Display: Yes 16:00:18 INFO - Mirror: Off 16:00:18 INFO - Online: Yes 16:00:18 INFO - Rotation: Supported 16:00:18 INFO - Return code: 0 16:00:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:00:18 INFO - Python 2.7.3 16:00:18 INFO - Return code: 0 16:00:18 INFO - grabbing minidump binary from tooltool 16:00:18 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')]} 16:00:18 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 0x106057d30>, '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 0x106021cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106048be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:00:18 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 16:00:18 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 16:00:18 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 16:00:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:18 INFO - Return code: 0 16:00:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:00:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:00:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:00:18 INFO - ENV: RUST_BACKTRACE is now 1 16:00:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:00:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:00:18 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/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 16:00:18 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/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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 16:00:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oKedHuTzSs/Render', 16:00:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:18 INFO - 'HOME': '/Users/cltbld', 16:00:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:18 INFO - 'LOGNAME': 'cltbld', 16:00:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:00:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:18 INFO - 'MOZ_NO_REMOTE': '1', 16:00:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:00:18 INFO - 'NO_EM_RESTART': '1', 16:00:18 INFO - 'PAGER': '/bin/cat', 16:00:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:18 INFO - 'PWD': '/builds/slave/test', 16:00:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:00:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:18 INFO - 'RUST_BACKTRACE': '1', 16:00:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:00:18 INFO - 'SHELL': '/bin/bash', 16:00:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.10lWI6oqBw/Listeners', 16:00:18 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 16:00:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:18 INFO - 'USER': 'cltbld', 16:00:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:18 INFO - 'XPC_FLAGS': '0x0', 16:00:18 INFO - 'XPC_SERVICE_NAME': '0', 16:00:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:18 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/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 16:00:19 INFO - mozversion application_buildid: 20170620140823 16:00:19 INFO - mozversion application_changeset: cd547b10e59e7db0b381a21598c65e183fc4dc2b 16:00:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:00:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:00:19 INFO - mozversion application_name: Firefox 16:00:19 INFO - mozversion application_remotingname: firefox-dev 16:00:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:00:19 INFO - mozversion application_vendor: Mozilla 16:00:19 INFO - mozversion application_version: 55.0 16:00:19 INFO - mozversion platform_buildid: 20170620140823 16:00:19 INFO - mozversion platform_changeset: cd547b10e59e7db0b381a21598c65e183fc4dc2b 16:00:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:00:19 INFO - mozversion platform_version: 55.0 16:00:19 INFO - using testdate: 1497999619 16:00:19 INFO - actual date: 1497999619 16:00:19 INFO - starting webserver on 'localhost:49325' 16:00:19 INFO - SUITE-START | Running 2 tests 16:00:19 INFO - TEST-START | dromaeo_css 16:00:19 INFO - operating with platform_type : mac_ 16:00:19 INFO - Initialising browser for dromaeo_css test... 16:00:19 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49325/getInfo.html -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpsZxLEQ/profile 16:00:19 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49325/getInfo.html -foreground) 16:00:52 INFO - TEST-INFO | 1959: killed by SIGTERM 16:00:52 INFO - Browser initialized. 16:00:52 INFO - Running cycle 1/1 for dromaeo_css test... 16:00:52 INFO - TEST-INFO | started process 1966 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpsZxLEQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:00:54 INFO - PID 1966 | 2017-06-20 16:00:54.248 plugin-container[1967:11050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 16:00:54 INFO - PID 1966 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:00:54 INFO - PID 1966 | 2017-06-20 16:00:54.249 plugin-container[1967:11050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4917, name = 'com.apple.CFPasteboardClient' 16:00:54 INFO - PID 1966 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:00:54 INFO - PID 1966 | 2017-06-20 16:00:54.249 plugin-container[1967:11050] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:00:55 INFO - PID 1966 | 2017-06-20 16:00:55.062 plugin-container[1968:11092] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 16:00:55 INFO - PID 1966 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:00:55 INFO - PID 1966 | 2017-06-20 16:00:55.063 plugin-container[1968:11092] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 16:00:55 INFO - PID 1966 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:00:55 INFO - PID 1966 | 2017-06-20 16:00:55.063 plugin-container[1968:11092] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:01:04 INFO - PID 1966 | RSS: Main: 215093248 16:01:04 INFO - PID 1966 | 16:04:09 INFO - PID 1966 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49325/tests/dromaeo/cssquery-ext.html) 16:04:12 INFO - PID 1966 | RSS: Main: 232345600 16:04:12 INFO - PID 1966 | 16:07:13 INFO - PID 1966 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-ext.html (next: http://localhost:49325/tests/dromaeo/cssquery-jquery.html) 16:07:14 INFO - PID 1966 | RSS: Main: 241995776 16:07:14 INFO - PID 1966 | 16:10:16 INFO - PID 1966 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49325/tests/dromaeo/cssquery-mootools.html) 16:10:16 INFO - PID 1966 | RSS: Main: 244240384 16:10:16 INFO - PID 1966 | 16:13:18 INFO - PID 1966 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49325/tests/dromaeo/cssquery-prototype.html) 16:13:18 INFO - PID 1966 | RSS: Main: 245948416 16:13:18 INFO - PID 1966 | 16:16:21 INFO - PID 1966 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49325/tests/dromaeo/cssquery-yui.html) 16:16:22 INFO - PID 1966 | RSS: Main: 246489088 16:16:22 INFO - PID 1966 | 16:19:23 INFO - PID 1966 | Cycle 1(1): loaded http://localhost:49325/tests/dromaeo/cssquery-yui.html (next: http://localhost:49325/tests/dromaeo/cssquery-dojo.html) 16:19:24 INFO - PID 1966 | RSS: Main: 244645888 16:19:24 INFO - PID 1966 | 16:19:24 INFO - PID 1966 | __start_tp_report 16:19:24 INFO - PID 1966 | _x_x_mozilla_page_load 16:19:24 INFO - PID 1966 | _x_x_mozilla_page_load_details 16:19:24 INFO - PID 1966 | |i|pagename|runs| 16:19:24 INFO - PID 1966 | |0;dojo.html;2691.9730802691975;3549.5563054618174;3588.587312459065;3668.4680721295395;3696.7597106188095;4424.33634954757;4704.670673052888;5717.399673034329;6185.134081228632;6315.084312774645;6224.097505861648;6288.339724328936;6334.84162895929;6337.556371054016;6376.394242546962;5619.438056194368;5628.859278518029;5655.830325090254;5710.200571919934;5779.942200578003;5717.285339332583;5739.799107031255;5752.165935939286;5774.855628609276;5789.884202315951;5518.889622207534;5650.74571644277;5660.716964151817;5678.886422271573;5692.089265717506;5560.7219639017885;5573.554115670737;5607.243022191993;5670.886582268373;5690.3456102548325;5554.833354999357;5600.579956503238;5660;5663.971680141614;5683.573731970118;4912.214045752662;6290.528210384239;6362.204724409449;6405.039244113374;6442.355764423567;6858.965705171442;6890.31096890307;6908.171019477644;6921.653917304165;6924.757633482804;6231.56673964986;6539.378759017885;6841.17905851296;6851.314868513158;6909.239983601749;5241.344831896013;6424.678766061772;6426.550141490145;6467.353264673589;6485.9946708260295;7017.543859649123;7158.928410715826;7175.995360649513;7176.7846964591145;7178.5692858428665;6541.116949211891;6603.603783772989;6605.07528945948;6656.733730650816;6811;6054.576179667381;6793.422559082534;6820.31796820324;6922.376986071277;6947.305269473012;6552.57408268461;6850.828729281808;6855.7600483983815;6859.5541289816;6872.484563657746;6619.569727967667;6711.6979735912;6890.414314783199;6899.586024838526;6924.099867017357;5378.2470454136455;5433.945660543423;5434.538064264502;5436.075867102602;5489.066858634042;3795.658390744846;4814.494478079751;4942.505749425029;4944.876378090505;4987.675801072919;6449.290578036413;6530.412262896362;6536.803895883131;6541.771038013741;6577.736890524421;6968.44252459802;6991.720331186797;6996.475264355092;7005.789826305219;7028.929710702828;6507.251666058369;6515.348465153446;6533.281339052701;6552.541322107407;6578.440832529192;5782.653040817564;6049.818505444844;6056.545759068087;6068.605540639845;6084.361142080106;7001.754938577125;7020.052292940388;7024.5434046786795;7031.929680703128;7140.6072666003865;6917.827054323682;6958.686859091252;6960.164780226405;6965.65171741421;6977.755778547727;7161.4986950912935;7173.856522869409;7193.676284567312;7200.279972002758;7207.459440541876;6812.795616131524;6828.180618325832;6831.146106736658;6846.897296540456;6849.452043836581;6723.831904202434;7009.404200643003;7009.509334346547;7013.158420989514;7039.612821294878;5520.503154716095;6605.9009114864275;6733.6296503692765;6744.393004629607;6778.288279730755;5612.915806262828;6659.53383263167;6663.507345421816;6751.898721519083;6778.932210678027;6408.519361048034;6466.741330346922;6608.966955165386;6620.337966203534;6664.166979127609;5500.58745594074;6567.883459811939;6632.30360812127;6636.568623039487;6663.900041499478;5385.488378604026;6318.05229215603;6458.967705161444;6537.5096867736065;6585.670716464062 16:19:24 INFO - PID 1966 | |1;ext.html;783.7805414483945;848.9320854331652;910.7677542226731;1214.6659668591142;1281.8846303832647;36970.63029369705;37784.73291602502;37838.24323513528;37846.62153378465;37880.24239515215;38153.23693526128;38354.23291534168;38488.23023539528;38517.229655406874;38816;7844.41166912481;7978.042634883793;7989.760307190793;8014.398920080988;8026.157253488387;7842.294193522582;7878.251566101211;7933.880991785099;7938.126806051329;7941.16617755136;6829.8975515367065;7365.963170184142;7914.841703165933;7953.920460795376;7958.204179582053;8505.192006759347;8558.144185581454;8580.485170889735;8596.097409772008;8605.268552173073;8254.215849494289;8332.62503187358;8349.03986041607;8360.540170290631;8373.8325233495;36324.63675363239;36554.903352899455;36572.085697857765;36706.63293367059;36880.44679329812;3530.293941211768;3549.94675079874;3563.0379797454802;3563.127033876691;3565.714742820568;29315.12054638364;29660.70339296601;29728.70271297281;29792.70207297921;29862.85068574665;3052.8168309901257;3060.4032213718333;3109.626844778641;3141.387429451258;3155.9053228403186;317903;317973;317986;318430;319165;38231.4265286021;39112.6088739109;39225;39228.607713922494;47922.76038619854;49328.50671493311;49680.50319496759;49723.75138124359;49808.25287620688;49876.50123498718;15147.166905820286;15219;15254;15264.007839490398;15278.847211527742;12381.876181238253;12382.442790074288;12422.503099876085;12438;12480;13444.798328025088;13509.864901351057;13551.66120846967;13627.863721362659;13682;5950.018246989285;5956.315023772235;5983.1623572699855;6019.518438524908;6025.728842202024;960.9711708648751;989.2382865193761;998.5157198758596;1007.6900029961167;1029.3206483720708;22981.425464363525;23050.769492304862;23562.528749424917;23571;23935.401614959417;1065.5684447798653;1080.9081228095542;1081.8323159910226;1142.497301187475;1148.1159507179425;10299.639512617023;11786.528538858349;11816.05471562273;11999.280043197436;12013.579524716593;332042;333665;333943;333960;334582;52309.47690523122;52606.473935260925;52735.472645273054;52740.20889686658;52792.94414111696;1498.086167437862;1498.4156179090164;1533.9233038348038;1536.7157075940986;1545.1424459425127;22031.559368812854;22533.774662253825;23085.307440776804;23102.306930792103;24392.51214975726;37556.81221593874;37600.81199593985;37696.811515942245;37699;37794.81102594469;279788;281569;282820;283525;283730;32196.517052244704;33027.83486082554;33203.169920752174;33757.81847635321;33844.81543145978;42057.369139462324;44173;44233.557664422944;44418.555814442734;44625.55374446214;25387.746122539276;25740.742592574585;26101.08646197374;27814;31180.53229201609;29441.263968400963;29480.705192948655;29593.85203073971;31020.689793102683;31145.37709245848;34607.82696086504;35471.82264088663;35512.46731298981;36562.63437365592;37146.07134821651;21509.67735484;29226.56160157557;30429.543556846224;30770.846145769126;30947.0715878524;22730.090796368622;23463.648045279675;24325.148619798223;24639.8768006166;24646 16:19:24 INFO - PID 1966 | |2;jquery.html;504.44762984531945;511.54472519457676;516.2153526639506;528.5084871069905;539.0566508609933;21411.67882481764;22828.97269622867;22861.856907154637;22872.19947301845;23990.760092399072;25183;25379.619305710385;25642.358941026483;25692.74307256927;25803.87098064512;5531.889362212753;6456.935430645693;6604.372584604479;6645.202575690924;6656.633885136315;6422.325655806143;6550.984597387413;6615.6030638161865;6616.371444712745;6622.238442579117;6122.326544080148;6513.316101809313;6516.67416629169;6545.509086818508;6574.506911981596;6497.252815926158;6516.468629871976;6536.11762412073;6539.836504087389;6555.573887697308;5965.940340596625;6028.849278768022;6033.245844269466;6035.698215089272;6040.48655864252;28698.569521457193;30422.695773042207;30473.085807425814;30579.082627520987;30633.234169145726;2281.646344816556;2302.481941563152;2316.293530473205;2325.2442956039185;2327.034280773477;19599.31402400909;20065.498362540908;20149.697754533692;20156.29452969154;20186.899065504724;2152.9354119376444;2205.930123889912;2254.729432468098;2289.004283136824;2301.516681496871;213659;219800;223270;223486;224081;15333.003354781904;15337.616559585877;15748.291326890318;16166.35334586627;16218.35126594923;20821.895890520755;20985.055672494775;20990.580188396147;20990.580188396452;21004.15983360679;10287.845682314772;10399;10417.427041512638;10470.895291047143;10499.475026248658;9668.564914578861;9693.757656058655;9694.951525242328;9760.97509761479;9840.704778856645;9065.274778017874;9288.860667089999;9400.670976515788;9463.242940564738;9474.194693450996;2256.717910261217;2684.9996752419574;2686.7178946210756;2692.9326766830595;2832.6459192600923;939.9342046056847;956.531299663174;967.9128878400837;1006.2603986070233;1026.1893104447404;21241.575168496543;21264.681029784566;21404.464888377915;22050.558988820132;22142.446438838837;977.953589659058;979.4661909259471;1008.6772232885406;1030.98963016243;1034.1158309645145;10953.507092180871;11130.777384452427;11159.27464714791;11369.715757105972;11747.588834390755;467856;530576.6942330528;572931;580095;602291;152269;153903.46096538892;155130;156071;156128;1153.4290526189423;1159.3507635724022;1169.6783384569312;1180.2210541043041;1187.9109066820024;15509.457168999032;18815;19257;20310.31165737132;20330.085146168163;61691;63635.09094727166;67176.6564668694;67635;71029.28970710227;88177;88487;90833;91827;92394.53802730943;11521.423928803693;11726.00328972047;11747.588834390755;11851.229670071418;11879.58421455245;13467.528636497676;14252;15408.922955385151;18340.633187335912;18366.081695915418;11428.314301141958;14630.780538292147;14684.559463216121;14758.262086895827;14859.925700371428;12926.676833079247;13015.934920325337;13085.803712944124;13876.653083672989;13882.444702211798;15249.008814427027;15299.005564638263;15326.233688315762;15408.383664653289;15416.691666166838;17639.94160350383;18018.279268829625;18214.544636384195;18631.161597728405;18730.438086857415;15843.762343564626;16012;16016.439424620083;16033.919830400773;16064.67870642604 16:19:24 INFO - PID 1966 | |3;mootools.html;1321.4119716726054;1328.830629046439;1481.8073650425442;1489.873360764335;1492.097281144908;13609.319534023296;14184.936129790269;14204.36080376383;14207.857921420782;14223.502177423767;10947.34315941044;13830.93084534576;13943.233122178277;13945;13961.860381396209;2806.677232118307;2831.3204830840655;2857.342811153436;2857.7570906472974;2864.2982469295052;2779.0829026421234;2815.4369126174743;2833.971660283402;2837.2197645647407;2839.403725217707;2684.932876678079;2792.567152091429;2867.5125228711063;2880.798344115902;2881.1644623059287;2848.743613074823;2877.568364745284;2882.3514709190263;2883.5818806273182;2885.8268503889835;2366.2073205476186;2382.976170238293;2796.161151654516;2822.2662107852107;2854.6574411070806;13666.18002919828;13701.246431446267;13724.588262352145;13732.313384330844;13777.173369597856;7615.31462168402;7829.608519574055;7903.683852645887;7928.524288542706;7955.721549745731;4545.181867263925;4841.878953026167;4899.657024008321;4913.164761990434;5001.724905130216;1228.520876858026;1259.124908188809;1274.7896597061474;1278.7340532872481;1279.878411550901;16822.24299906495;21305.68041479379;21328.04023818932;21400.35798926035;21450.14199432021;6660.333966603301;6711.765088221889;6717;6855.245922948472;6873.175218973755;14164.787528187086;14242.145471271757;14365.425382984564;14440.277986100653;14467.638309042148;7383.852322953511;7552.66013029415;7631.435555711066;7633.313001829862;7636;6367.204099487564;6929.757458488929;7015.964920175366;7081.221065682771;7081.716731330689;4707.223308154116;5123.666961647481;5230.979958907977;5320.17537281722;5347.197920311906;1388.625260991611;1458.8978771486106;1546.5901536092945;1567.3338830996781;1572.2610373124612;679.0123456790163;820.8932838730926;821.9301359384519;845.5434065604628;869.6217145541709;7778;8040.422012204513;8088.150744171895;8129.593520323961;8507.659693612186;952.5761036338902;963.6145541783343;976.5410257179115;1010.6109147978082;1036.6682661548232;4813.205821039489;4913.582345500601;5089.338386009795;5138.563222126087;5151.356080489939;17969.910150449425;19029.524261893286;19032.52418689544;19070.52323691891;19111.713324300148;18137.727934081;18786.342478013205;18863.15115819764;18967.525811854815;19009.61980760377;9274.768130796783;9352.485613291334;9477.763055923657;9485.33602647808;9492.098250666175;7529.623518823927;8043.959780201061;8069.636866341146;8351.540665263468;8637.438566493409;16950.660986780444;17215.65568688594;17588.91205543964;17702.822971770118;17819.821801781814;16677.74983375226;17383.391581294592;17976.460706178834;18007;18225.45323640293;10354.896451035393;11092.223544351818;11162.16283778736;11179.217454778087;11227.49476273586;12646.367681615699;12789.296588687339;12804.423800928795;12820.61538153857;12844.165129266568;8254.958725206336;10224.897751022692;10276.948615256875;10434.739131521772;10590.417527036087;8542.35932305092;9849.60601575929;9887.802243955224;10192.133668637953;10316.226283028953;9383.530823458936;9508.239340852853;9528.95235523818;9529.332946693665;9538.475383853955;6662.766803161866;6715.697793599397;6981.406580440516;7013.824654383681;7309.378702810108;5889.234399528205;6999.475039371966;7221.494495385273;7310.561366318038;7359.227281135618 16:19:24 INFO - PID 1966 | |4;prototype.html;381.29270203772;431.62879923266007;444.0231490720416;470.20065887990427;472.83450792222715;6106.389361063897;6446.226452825662;6453.257875344337;6471.676416179196;6523.119378883849;5902.675352855602;6081.54388420868;6311.747530098791;6331.746730130789;6333.429991300782;4582.747948862811;4740.739259340735;4778.78495467703;4791.257355109946;4796.664233503656;3955.367141257385;4530;4779.761011949406;4799.808007679689;4823.469418363977;3866.729328946975;4558.566936141078;4758.0753919693125;4766.880827979294;4817.181079216542;4162.83348666053;4761.761911904426;4768.427788665348;4769.856904292877;4820.566149046585;3396.8811091611924;3634.727395445352;3798.955287295988;4182.53992060873;4195.391668208093;5234.790608375661;6128.846778830521;6138.416850399205;6171.506279497629;6208.412428814058;5653.632513886584;5676.772929082832;5681.176229446748;5697.914531282034;5702.800401985911;5620.747066382022;6157.8607957528;6676.933230667728;6884.896726549106;6888.793336199922;3983.143624120828;4189.853355132586;4770.427548694144;4790.664653474259;4791.065742180276;6612.404883560503;7209.783706488814;7209.855802883913;7235.602041887694;7264.982902393669;4852.684575502581;4857.514248575114;4858.319835223001;4869.318295438641;4875.317455556225;4845.563899249085;4863.562279394871;4959.752012399438;4964.478729733398;4966.875828104254;4717.504662010508;5617.073182924853;5661.773529058792;5676.205331253628;5694.117411801178;5218.452062533379;6854.9657251713425;6866.862662746817;6873.415759660485;6930.203026651899;1563.9843601564066;1687.392538686072;1687.5274923021573;1693.8390852868959;1703.9659206815795;1054.9924821795166;1129.5086637312795;1138.4706111658118;1151.8675352334421;1166.2419427372106;5464.726763661801;6043.607165534227;6124.081387791867;6181.578237005424;6503.6097834130105;6200.80658460893;6222;7074.92925070753;7103.822404439827;7209.179135058323;4943.653944223942;5290.862464570136;6438.742450301936;6465.3857883501;6541.378569035934;3188.442022646074;3389.8305084745666;3681.7054635629083;3715.554133503997;3721.702263818888;6522.988936714817;6717.085630595257;7204.135503739585;7311.744088956967;7332.926670733331;6154.292256390483;6750.594964302059;6822.181338239443;7023.227444981048;7332.743353982585;6712.96643516789;7223.674934627849;7258.346748792634;7321.597312147776;7336.7432139874845;6766.15423072116;7030.543014704028;7077.82305442368;7139.82150446243;7184.275773814352;5794.478496935296;6416.454601358937;6991.755288564876;7008.053912721921;7015.754448594274;5425.565954723692;6441.742330306735;6954.443644508326;7040.542364746275;7137.325052090889;4024.9798751006056;4450.354698568624;4457.799399027116;4823.758812059453;4872.951270487391;5594.804181853616;5638.464345887134;6604.966975165094;6826.214985276558;7136.964315178391;5992.520598352208;6743.966280168568;6847.07564478789;6847.486438517031;6895.931040689529;6811.489138314547;6865.759698410531;6903.620300883299;6910.689018994057;6936.445084393337;6677.058976357557;6691.364320389555;6714.227863795726;6739.393454588914;6789.388954994074;6804.965975170093;6851.897221541781;6859.2454829969665;6872.8041460719005;6929.826754331182;5465.672059676433;5466.098093814635;5985.910211346921;6604.405603495708;6647.169103862017 16:19:24 INFO - PID 1966 | |5;yui.html;650.4406210658833;721.8664547058795;782.7534326687094;816.7753867686382;850.629975960457;16693.33226670933;17228.56928576783;17460.91269543652;17461;17543.210555525002;15766.448174313902;18206.635867282646;18865.62268754624;18943.810561894377;19026.61946761064;992.320260621473;1004.5981607357043;1018.3329918903094;1019.9286049976505;1022.642075273652;1011.3729487717625;1015.735908663749;1015.7968406318729;1017.8269694152012;1022.5091955861191;1058.6347710040025;1065.5844220753872;1075.4138994248115;1078.4014871746178;1079.6760971708495;1058.0794708603564;1063.5639387850945;1063.8085144673955;1073.1093192650121;1073.125402796722;772.2007722007695;786.9921300787033;788.8777239527882;791.1732239809413;793.5040599625235;17792.55518612032;17995.280188792432;17999.64000719978;18014.639707205915;18016.819831801644;16939.153042347833;17953.551161221076;18087.276508939627;18113.728294075598;18167.72748408775;14562.854371456257;14874.181919994397;14927.776083358649;14970.251487425585;15192.696146077016;3640.144566027008;3655.707543396522;3666.981665091684;3667.504886840269;3684.4841722158917;131483;138955;140968;149379.2531037338;150060;13435.261060641758;13613.523526676718;13800.654983625289;13911.095778774346;13915.373808178454;26497.735022649525;27544.449111017668;27557.58663620007;27624.861875690494;27925.16224513268;7371.852562948711;7642.08295004603;7683.346915512244;7736.574488403079;7754.457187996786;3715.145516531213;3794.867179648699;3825.3114439401174;3836.9616303836606;3845.8653947111716;4649.395578574764;4670.136024835417;4722.929156062662;4739.928901066487;4762.333273341735;875.4790899414838;907.2761752669979;949.852772820214;950.6340059077166;961.024560071528;626.27352271365;630.2405601250574;636.7453018792428;645.7223393940557;663.9601623902581;11092.389918554558;11912.783548441872;11988.220765650205;12161.270323780425;12385.814212786816;622.3931666625004;641.7208514296296;650.3171669746747;672.2168673495421;674.1775034457953;3633.7274704396746;3659.9817000914827;4449.822007119679;4646.047560250208;4652;152464;153146;153546;154625;157982;126078;130830;132446;132803.33598331947;139009;71255.6437217821;73123.63438182882;81809.59095204606;107668;114478.42760786143;12003.399830008639;12100.394980251129;12196.716076854393;12326.302109367334;12731.87268127307;44518.777406112764;44596.55403445924;44794.3280850781;44895;47189.29216061831;63948.68025659842;64120;72180.27819721735;82034.58982705048;87599.56200218959;16583.336666533687;16877.831221688117;16937.491875243762;17461.95228286307;17656;18306.725399118757;18560.443186704422;18819.964901930525;18843.811561884206;18926;16843.494695159166;17327.56681082983;17330.30678772835;17424.128793560525;17989.190486428404;16637.334506619598;16767.32930682759;17118;17655.470335889942;17784.46646600604;16777.664446711242;16820.66358672795;17157.742633860253;17178.31286748516;17379.56551086233;10888.020078193;10946.507407166537;11203.663890083311;11242.606508772154;11721.238119522204;12636.296041239539;12710.491580336682;12712.74574508523;13122.671933201746;13200.471981121033 16:19:24 INFO - PID 1966 | __end_tp_report 16:19:24 INFO - PID 1966 | __start_cc_report 16:19:24 INFO - PID 1966 | _x_x_mozilla_cycle_collect,400 16:19:24 INFO - PID 1966 | __end_cc_report 16:19:24 INFO - PID 1966 | __startTimestamp1498000764210__endTimestamp 16:19:24 INFO - PID 1966 | 16:19:24 INFO - PID 1966 | ------- Summary: start ------- 16:19:24 INFO - PID 1966 | Number of tests: 6 16:19:24 INFO - PID 1966 | 16:19:24 INFO - PID 1966 | [#0] dojo.html Cycles:165 Average:6269.04 Median:6539.38 stddev:822.49 (12.6%) stddev-sans-first:775.67 16:19:24 INFO - PID 1966 | Values: 2692.0 3549.6 3588.6 3668.5 3696.8 4424.3 4704.7 5717.4 6185.1 6315.1 6224.1 6288.3 6334.8 6337.6 6376.4 5619.4 5628.9 5655.8 5710.2 5779.9 5717.3 5739.8 5752.2 5774.9 5789.9 5518.9 5650.7 5660.7 5678.9 5692.1 5560.7 5573.6 5607.2 5670.9 5690.3 5554.8 5600.6 5660.0 5664.0 5683.6 4912.2 6290.5 6362.2 6405.0 6442.4 6859.0 6890.3 6908.2 6921.7 6924.8 6231.6 6539.4 6841.2 6851.3 6909.2 5241.3 6424.7 6426.6 6467.4 6486.0 7017.5 7158.9 7176.0 7176.8 7178.6 6541.1 6603.6 6605.1 6656.7 6811.0 6054.6 6793.4 6820.3 6922.4 6947.3 6552.6 6850.8 6855.8 6859.6 6872.5 6619.6 6711.7 6890.4 6899.6 6924.1 5378.2 5433.9 5434.5 5436.1 5489.1 3795.7 4814.5 4942.5 4944.9 4987.7 6449.3 6530.4 6536.8 6541.8 6577.7 6968.4 6991.7 6996.5 7005.8 7028.9 6507.3 6515.3 6533.3 6552.5 6578.4 5782.7 6049.8 6056.5 6068.6 6084.4 7001.8 7020.1 7024.5 7031.9 7140.6 6917.8 6958.7 6960.2 6965.7 6977.8 7161.5 7173.9 7193.7 7200.3 7207.5 6812.8 6828.2 6831.1 6846.9 6849.5 6723.8 7009.4 7009.5 7013.2 7039.6 5520.5 6605.9 6733.6 6744.4 6778.3 5612.9 6659.5 6663.5 6751.9 6778.9 6408.5 6466.7 6609.0 6620.3 6664.2 5500.6 6567.9 6632.3 6636.6 6663.9 5385.5 6318.1 6459.0 6537.5 6585.7 16:19:24 INFO - PID 1966 | 16:19:24 INFO - PID 1966 | [#1] ext.html Cycles:180 Average:45476.60 Median:334271.00 stddev:82024.58 (24.5%) stddev-sans-first:82186.04 16:19:24 INFO - PID 1966 | Values: 783.8 848.9 910.8 1214.7 1281.9 36970.6 37784.7 37838.2 37846.6 37880.2 38153.2 38354.2 38488.2 38517.2 38816.0 7844.4 7978.0 7989.8 8014.4 8026.2 7842.3 7878.3 7933.9 7938.1 7941.2 6829.9 7366.0 7914.8 7953.9 7958.2 8505.2 8558.1 8580.5 8596.1 8605.3 8254.2 8332.6 8349.0 8360.5 8373.8 36324.6 36554.9 36572.1 36706.6 36880.4 3530.3 3549.9 3563.0 3563.1 3565.7 29315.1 29660.7 29728.7 29792.7 29862.9 3052.8 3060.4 3109.6 3141.4 3155.9 317903.0 317973.0 317986.0 318430.0 319165.0 38231.4 39112.6 39225.0 39228.6 47922.8 49328.5 49680.5 49723.8 49808.3 49876.5 15147.2 15219.0 15254.0 15264.0 15278.8 12381.9 12382.4 12422.5 12438.0 12480.0 13444.8 13509.9 13551.7 13627.9 13682.0 5950.0 5956.3 5983.2 6019.5 6025.7 961.0 989.2 998.5 1007.7 1029.3 22981.4 23050.8 23562.5 23571.0 23935.4 1065.6 1080.9 1081.8 1142.5 1148.1 10299.6 11786.5 11816.1 11999.3 12013.6 332042.0 333665.0 333943.0 333960.0 334582.0 52309.5 52606.5 52735.5 52740.2 52792.9 1498.1 1498.4 1533.9 1536.7 1545.1 22031.6 22533.8 23085.3 23102.3 24392.5 37556.8 37600.8 37696.8 37699.0 37794.8 279788.0 281569.0 282820.0 283525.0 283730.0 32196.5 33027.8 33203.2 33757.8 33844.8 42057.4 44173.0 44233.6 44418.6 44625.6 25387.7 25740.7 26101.1 27814.0 31180.5 29441.3 29480.7 29593.9 31020.7 31145.4 34607.8 35471.8 35512.5 36562.6 37146.1 21509.7 29226.6 30429.5 30770.8 30947.1 22730.1 23463.6 24325.1 24639.9 24646.0 16:19:24 INFO - PID 1966 | 16:19:24 INFO - PID 1966 | [#2] jquery.html Cycles:180 Average:40191.23 Median:223378.00 stddev:97304.79 (43.6%) stddev-sans-first:97532.13 16:19:24 INFO - PID 1966 | Values: 504.4 511.5 516.2 528.5 539.1 21411.7 22829.0 22861.9 22872.2 23990.8 25183.0 25379.6 25642.4 25692.7 25803.9 5531.9 6456.9 6604.4 6645.2 6656.6 6422.3 6551.0 6615.6 6616.4 6622.2 6122.3 6513.3 6516.7 6545.5 6574.5 6497.3 6516.5 6536.1 6539.8 6555.6 5965.9 6028.8 6033.2 6035.7 6040.5 28698.6 30422.7 30473.1 30579.1 30633.2 2281.6 2302.5 2316.3 2325.2 2327.0 19599.3 20065.5 20149.7 20156.3 20186.9 2152.9 2205.9 2254.7 2289.0 2301.5 213659.0 219800.0 223270.0 223486.0 224081.0 15333.0 15337.6 15748.3 16166.4 16218.4 20821.9 20985.1 20990.6 20990.6 21004.2 10287.8 10399.0 10417.4 10470.9 10499.5 9668.6 9693.8 9695.0 9761.0 9840.7 9065.3 9288.9 9400.7 9463.2 9474.2 2256.7 2685.0 2686.7 2692.9 2832.6 939.9 956.5 967.9 1006.3 1026.2 21241.6 21264.7 21404.5 22050.6 22142.4 978.0 979.5 1008.7 1031.0 1034.1 10953.5 11130.8 11159.3 11369.7 11747.6 467856.0 530576.7 572931.0 580095.0 602291.0 152269.0 153903.5 155130.0 156071.0 156128.0 1153.4 1159.4 1169.7 1180.2 1187.9 15509.5 18815.0 19257.0 20310.3 20330.1 61691.0 63635.1 67176.7 67635.0 71029.3 88177.0 88487.0 90833.0 91827.0 92394.5 11521.4 11726.0 11747.6 11851.2 11879.6 13467.5 14252.0 15408.9 18340.6 18366.1 11428.3 14630.8 14684.6 14758.3 14859.9 12926.7 13015.9 13085.8 13876.7 13882.4 15249.0 15299.0 15326.2 15408.4 15416.7 17639.9 18018.3 18214.5 18631.2 18730.4 15843.8 16012.0 16016.4 16033.9 16064.7 16:19:24 INFO - PID 1966 | 16:19:24 INFO - PID 1966 | [#3] mootools.html Cycles:180 Average:8479.17 Median:2844.07 stddev:5669.69 (199.4%) stddev-sans-first:5660.08 16:19:24 INFO - PID 1966 | Values: 1321.4 1328.8 1481.8 1489.9 1492.1 13609.3 14184.9 14204.4 14207.9 14223.5 10947.3 13830.9 13943.2 13945.0 13961.9 2806.7 2831.3 2857.3 2857.8 2864.3 2779.1 2815.4 2834.0 2837.2 2839.4 2684.9 2792.6 2867.5 2880.8 2881.2 2848.7 2877.6 2882.4 2883.6 2885.8 2366.2 2383.0 2796.2 2822.3 2854.7 13666.2 13701.2 13724.6 13732.3 13777.2 7615.3 7829.6 7903.7 7928.5 7955.7 4545.2 4841.9 4899.7 4913.2 5001.7 1228.5 1259.1 1274.8 1278.7 1279.9 16822.2 21305.7 21328.0 21400.4 21450.1 6660.3 6711.8 6717.0 6855.2 6873.2 14164.8 14242.1 14365.4 14440.3 14467.6 7383.9 7552.7 7631.4 7633.3 7636.0 6367.2 6929.8 7016.0 7081.2 7081.7 4707.2 5123.7 5231.0 5320.2 5347.2 1388.6 1458.9 1546.6 1567.3 1572.3 679.0 820.9 821.9 845.5 869.6 7778.0 8040.4 8088.2 8129.6 8507.7 952.6 963.6 976.5 1010.6 1036.7 4813.2 4913.6 5089.3 5138.6 5151.4 17969.9 19029.5 19032.5 19070.5 19111.7 18137.7 18786.3 18863.2 18967.5 19009.6 9274.8 9352.5 9477.8 9485.3 9492.1 7529.6 8044.0 8069.6 8351.5 8637.4 16950.7 17215.7 17588.9 17702.8 17819.8 16677.7 17383.4 17976.5 18007.0 18225.5 10354.9 11092.2 11162.2 11179.2 11227.5 12646.4 12789.3 12804.4 12820.6 12844.2 8255.0 10224.9 10276.9 10434.7 10590.4 8542.4 9849.6 9887.8 10192.1 10316.2 9383.5 9508.2 9529.0 9529.3 9538.5 6662.8 6715.7 6981.4 7013.8 7309.4 5889.2 6999.5 7221.5 7310.6 7359.2 16:19:24 INFO - PID 1966 | 16:19:24 INFO - PID 1966 | [#4] prototype.html Cycles:180 Average:5482.36 Median:6062.58 stddev:1701.12 (28.1%) stddev-sans-first:1662.25 16:19:24 INFO - PID 1966 | Values: 381.3 431.6 444.0 470.2 472.8 6106.4 6446.2 6453.3 6471.7 6523.1 5902.7 6081.5 6311.7 6331.7 6333.4 4582.7 4740.7 4778.8 4791.3 4796.7 3955.4 4530.0 4779.8 4799.8 4823.5 3866.7 4558.6 4758.1 4766.9 4817.2 4162.8 4761.8 4768.4 4769.9 4820.6 3396.9 3634.7 3799.0 4182.5 4195.4 5234.8 6128.8 6138.4 6171.5 6208.4 5653.6 5676.8 5681.2 5697.9 5702.8 5620.7 6157.9 6676.9 6884.9 6888.8 3983.1 4189.9 4770.4 4790.7 4791.1 6612.4 7209.8 7209.9 7235.6 7265.0 4852.7 4857.5 4858.3 4869.3 4875.3 4845.6 4863.6 4959.8 4964.5 4966.9 4717.5 5617.1 5661.8 5676.2 5694.1 5218.5 6855.0 6866.9 6873.4 6930.2 1564.0 1687.4 1687.5 1693.8 1704.0 1055.0 1129.5 1138.5 1151.9 1166.2 5464.7 6043.6 6124.1 6181.6 6503.6 6200.8 6222.0 7074.9 7103.8 7209.2 4943.7 5290.9 6438.7 6465.4 6541.4 3188.4 3389.8 3681.7 3715.6 3721.7 6523.0 6717.1 7204.1 7311.7 7332.9 6154.3 6750.6 6822.2 7023.2 7332.7 6713.0 7223.7 7258.3 7321.6 7336.7 6766.2 7030.5 7077.8 7139.8 7184.3 5794.5 6416.5 6991.8 7008.1 7015.8 5425.6 6441.7 6954.4 7040.5 7137.3 4025.0 4450.4 4457.8 4823.8 4873.0 5594.8 5638.5 6605.0 6826.2 7137.0 5992.5 6744.0 6847.1 6847.5 6895.9 6811.5 6865.8 6903.6 6910.7 6936.4 6677.1 6691.4 6714.2 6739.4 6789.4 6805.0 6851.9 6859.2 6872.8 6929.8 5465.7 5466.1 5985.9 6604.4 6647.2 16:19:24 INFO - PID 1966 | 16:19:24 INFO - PID 1966 | [#5] yui.html Cycles:180 Average:25904.82 Median:17720.23 stddev:40280.87 (227.3%) stddev-sans-first:40349.24 16:19:24 INFO - PID 1966 | Values: 650.4 721.9 782.8 816.8 850.6 16693.3 17228.6 17460.9 17461.0 17543.2 15766.4 18206.6 18865.6 18943.8 19026.6 992.3 1004.6 1018.3 1019.9 1022.6 1011.4 1015.7 1015.8 1017.8 1022.5 1058.6 1065.6 1075.4 1078.4 1079.7 1058.1 1063.6 1063.8 1073.1 1073.1 772.2 787.0 788.9 791.2 793.5 17792.6 17995.3 17999.6 18014.6 18016.8 16939.2 17953.6 18087.3 18113.7 18167.7 14562.9 14874.2 14927.8 14970.3 15192.7 3640.1 3655.7 3667.0 3667.5 3684.5 131483.0 138955.0 140968.0 149379.3 150060.0 13435.3 13613.5 13800.7 13911.1 13915.4 26497.7 27544.4 27557.6 27624.9 27925.2 7371.9 7642.1 7683.3 7736.6 7754.5 3715.1 3794.9 3825.3 3837.0 3845.9 4649.4 4670.1 4722.9 4739.9 4762.3 875.5 907.3 949.9 950.6 961.0 626.3 630.2 636.7 645.7 664.0 11092.4 11912.8 11988.2 12161.3 12385.8 622.4 641.7 650.3 672.2 674.2 3633.7 3660.0 4449.8 4646.0 4652.0 152464.0 153146.0 153546.0 154625.0 157982.0 126078.0 130830.0 132446.0 132803.3 139009.0 71255.6 73123.6 81809.6 107668.0 114478.4 12003.4 12100.4 12196.7 12326.3 12731.9 44518.8 44596.6 44794.3 44895.0 47189.3 63948.7 64120.0 72180.3 82034.6 87599.6 16583.3 16877.8 16937.5 17462.0 17656.0 18306.7 18560.4 18820.0 18843.8 18926.0 16843.5 17327.6 17330.3 17424.1 17989.2 16637.3 16767.3 17118.0 17655.5 17784.5 16777.7 16820.7 17157.7 17178.3 17379.6 10888.0 10946.5 11203.7 11242.6 11721.2 12636.3 12710.5 12712.7 13122.7 13200.5 16:19:24 INFO - PID 1966 | -------- Summary: end -------- 16:19:24 INFO - PID 1966 | 16:19:24 INFO - TEST-INFO | 1966: exit 0 16:19:24 INFO - TEST-OK | dromaeo_css | took 1145779ms 16:19:24 INFO - TEST-START | kraken 16:19:24 INFO - operating with platform_type : mac_ 16:19:24 INFO - Initialising browser for kraken test... 16:19:24 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49325/getInfo.html -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmp3ofa86/profile 16:19:24 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49325/getInfo.html -foreground) 16:19:58 INFO - TEST-INFO | 1975: killed by SIGTERM 16:19:58 INFO - Browser initialized. 16:19:58 INFO - Running cycle 1/1 for kraken test... 16:19:58 INFO - TEST-INFO | started process 1979 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmp3ofa86/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:00 INFO - PID 1979 | 2017-06-20 16:20:00.040 plugin-container[1980:13477] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9747, name = 'com.apple.tsm.portname' 16:20:00 INFO - PID 1979 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:00 INFO - PID 1979 | 2017-06-20 16:20:00.041 plugin-container[1980:13477] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x974b, name = 'com.apple.CFPasteboardClient' 16:20:00 INFO - PID 1979 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:00 INFO - PID 1979 | 2017-06-20 16:20:00.041 plugin-container[1980:13477] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:20:00 INFO - PID 1979 | 2017-06-20 16:20:00.846 plugin-container[1981:13519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x922b, name = 'com.apple.tsm.portname' 16:20:00 INFO - PID 1979 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:00 INFO - PID 1979 | 2017-06-20 16:20:00.846 plugin-container[1981:13519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 16:20:00 INFO - PID 1979 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:00 INFO - PID 1979 | 2017-06-20 16:20:00.847 plugin-container[1981:13519] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:20:10 INFO - PID 1979 | RSS: Main: 214966272 16:20:10 INFO - PID 1979 | 16:20:13 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49325/tests/kraken/driver.html?testName=audio-beat-detection) 16:20:13 INFO - PID 1979 | RSS: Main: 228294656 16:20:13 INFO - PID 1979 | 16:20:16 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49325/tests/kraken/driver.html?testName=audio-dft) 16:20:17 INFO - PID 1979 | RSS: Main: 237449216 16:20:17 INFO - PID 1979 | 16:20:20 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49325/tests/kraken/driver.html?testName=audio-fft) 16:20:20 INFO - PID 1979 | RSS: Main: 241889280 16:20:20 INFO - PID 1979 | 16:20:22 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49325/tests/kraken/driver.html?testName=audio-oscillator) 16:20:23 INFO - PID 1979 | RSS: Main: 243818496 16:20:23 INFO - PID 1979 | 16:20:25 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49325/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:20:26 INFO - PID 1979 | RSS: Main: 245297152 16:20:26 INFO - PID 1979 | 16:20:29 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49325/tests/kraken/driver.html?testName=imaging-darkroom) 16:20:29 INFO - PID 1979 | RSS: Main: 249757696 16:20:29 INFO - PID 1979 | 16:20:32 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49325/tests/kraken/driver.html?testName=imaging-desaturate) 16:20:33 INFO - PID 1979 | RSS: Main: 250376192 16:20:33 INFO - PID 1979 | 16:20:36 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49325/tests/kraken/driver.html?testName=json-parse-financial) 16:20:37 INFO - PID 1979 | RSS: Main: 250019840 16:20:37 INFO - PID 1979 | 16:20:38 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49325/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:20:39 INFO - PID 1979 | RSS: Main: 249626624 16:20:39 INFO - PID 1979 | 16:20:40 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49325/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:20:41 INFO - PID 1979 | RSS: Main: 249602048 16:20:41 INFO - PID 1979 | 16:20:43 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49325/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:20:44 INFO - PID 1979 | RSS: Main: 250081280 16:20:44 INFO - PID 1979 | 16:20:46 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49325/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:20:47 INFO - PID 1979 | RSS: Main: 249634816 16:20:47 INFO - PID 1979 | 16:20:50 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49325/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:20:51 INFO - PID 1979 | RSS: Main: 249774080 16:20:51 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | Cycle 1(1): loaded http://localhost:49325/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49325/tests/kraken/driver.html?testName=ai-astar) 16:20:53 INFO - PID 1979 | RSS: Main: 251473920 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | __start_tp_report 16:20:53 INFO - PID 1979 | _x_x_mozilla_page_load 16:20:53 INFO - PID 1979 | _x_x_mozilla_page_load_details 16:20:53 INFO - PID 1979 | |i|pagename|runs| 16:20:53 INFO - PID 1979 | |0;ai-astar;103;117;106;113;130;174;172;103;114;116 16:20:53 INFO - PID 1979 | |1;audio-beat-detection;138;118;148;155;110;122;121;112;155;109 16:20:53 INFO - PID 1979 | |2;audio-dft;197;200;198;199;198;196;197;199;202;194 16:20:53 INFO - PID 1979 | |3;audio-fft;69;86;106;201;85;83;58;66;119;57 16:20:53 INFO - PID 1979 | |4;audio-oscillator;69;70;125;70;73;85;104;71;73;111 16:20:53 INFO - PID 1979 | |5;imaging-gaussian-blur;142;93;93;93;93;92;94;93;93;89 16:20:53 INFO - PID 1979 | |6;imaging-darkroom;98;98;101;97;97;105;97;97;104;98 16:20:53 INFO - PID 1979 | |7;imaging-desaturate;93;95;91;90;92;92;112;92;91;117 16:20:53 INFO - PID 1979 | |8;json-parse-financial;48;48;52;54;51;52;52;47;46;53 16:20:53 INFO - PID 1979 | |9;json-stringify-tinderbox;56;55;57;60;60;64;56;58;59;58 16:20:53 INFO - PID 1979 | |10;stanford-crypto-aes;80;78;78;80;81;80;79;82;80;82 16:20:53 INFO - PID 1979 | |11;stanford-crypto-ccm;137;134;144;138;129;136;145;139;149;177 16:20:53 INFO - PID 1979 | |12;stanford-crypto-pbkdf2;174;180;182;182;229;224;233;182;181;167 16:20:53 INFO - PID 1979 | |13;stanford-crypto-sha256-iterative;68;65;61;65;65;67;63;67;66;63 16:20:53 INFO - PID 1979 | __end_tp_report 16:20:53 INFO - PID 1979 | __start_cc_report 16:20:53 INFO - PID 1979 | _x_x_mozilla_cycle_collect,1047 16:20:53 INFO - PID 1979 | __end_cc_report 16:20:53 INFO - PID 1979 | __startTimestamp1498000853334__endTimestamp 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | ------- Summary: start ------- 16:20:53 INFO - PID 1979 | Number of tests: 14 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#0] ai-astar Cycles:10 Average:124.80 Median:116.50 stddev:26.61 (22.8%) stddev-sans-first:27.03 16:20:53 INFO - PID 1979 | Values: 103.0 117.0 106.0 113.0 130.0 174.0 172.0 103.0 114.0 116.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:130.00 stddev:18.49 (14.2%) stddev-sans-first:19.31 16:20:53 INFO - PID 1979 | Values: 138.0 118.0 148.0 155.0 110.0 122.0 121.0 112.0 155.0 109.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#2] audio-dft Cycles:10 Average:198.00 Median:198.50 stddev:2.21 (1.1%) stddev-sans-first:2.32 16:20:53 INFO - PID 1979 | Values: 197.0 200.0 198.0 199.0 198.0 196.0 197.0 199.0 202.0 194.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#3] audio-fft Cycles:10 Average:93.00 Median:67.50 stddev:42.88 (63.5%) stddev-sans-first:44.59 16:20:53 INFO - PID 1979 | Values: 69.0 86.0 106.0 201.0 85.0 83.0 58.0 66.0 119.0 57.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:70.50 stddev:20.62 (29.2%) stddev-sans-first:21.03 16:20:53 INFO - PID 1979 | Values: 69.0 70.0 125.0 70.0 73.0 85.0 104.0 71.0 73.0 111.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:93.00 stddev:15.69 (16.9%) stddev-sans-first:1.42 16:20:53 INFO - PID 1979 | Values: 142.0 93.0 93.0 93.0 93.0 92.0 94.0 93.0 93.0 89.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#6] imaging-darkroom Cycles:10 Average:99.20 Median:97.00 stddev:3.05 (3.1%) stddev-sans-first:3.20 16:20:53 INFO - PID 1979 | Values: 98.0 98.0 101.0 97.0 97.0 105.0 97.0 97.0 104.0 98.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:92.00 stddev:9.65 (10.5%) stddev-sans-first:10.15 16:20:53 INFO - PID 1979 | Values: 93.0 95.0 91.0 90.0 92.0 92.0 112.0 92.0 91.0 117.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:52.00 stddev:2.79 (5.4%) stddev-sans-first:2.83 16:20:53 INFO - PID 1979 | Values: 48.0 48.0 52.0 54.0 51.0 52.0 52.0 47.0 46.0 53.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#9] json-stringify-tinderbox Cycles:10 Average:58.30 Median:58.50 stddev:2.63 (4.5%) stddev-sans-first:2.65 16:20:53 INFO - PID 1979 | Values: 56.0 55.0 57.0 60.0 60.0 64.0 56.0 58.0 59.0 58.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.00 stddev:1.41 (1.8%) stddev-sans-first:1.50 16:20:53 INFO - PID 1979 | Values: 80.0 78.0 78.0 80.0 81.0 80.0 79.0 82.0 80.0 82.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:141.50 stddev:13.33 (9.4%) stddev-sans-first:13.97 16:20:53 INFO - PID 1979 | Values: 137.0 134.0 144.0 138.0 129.0 136.0 145.0 139.0 149.0 177.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.40 Median:182.00 stddev:24.87 (13.7%) stddev-sans-first:25.37 16:20:53 INFO - PID 1979 | Values: 174.0 180.0 182.0 182.0 229.0 224.0 233.0 182.0 181.0 167.0 16:20:53 INFO - PID 1979 | 16:20:53 INFO - PID 1979 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.50 stddev:2.16 (3.3%) stddev-sans-first:2.00 16:20:53 INFO - PID 1979 | Values: 68.0 65.0 61.0 65.0 65.0 67.0 63.0 67.0 66.0 63.0 16:20:53 INFO - PID 1979 | -------- Summary: end -------- 16:20:53 INFO - PID 1979 | 16:20:54 INFO - TEST-INFO | 1979: exit 0 16:20:54 INFO - TEST-OK | kraken | took 89362ms 16:20:54 INFO - SUITE-END | took 1235s 16:20:54 INFO - Completed test suite (00:20:35) 16:20:54 INFO - javascript benchmark 16:20:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8401.741085562335, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2691.9730802691975, 3549.5563054618174, 3588.587312459065, 3668.4680721295395, 3696.7597106188095, 4424.33634954757, 4704.670673052888, 5717.399673034329, 6185.134081228632, 6315.084312774645, 6224.097505861648, 6288.339724328936, 6334.84162895929, 6337.556371054016, 6376.394242546962, 5619.438056194368, 5628.859278518029, 5655.830325090254, 5710.200571919934, 5779.942200578003, 5717.285339332583, 5739.799107031255, 5752.165935939286, 5774.855628609276, 5789.884202315951, 5518.889622207534, 5650.74571644277, 5660.716964151817, 5678.886422271573, 5692.089265717506, 5560.7219639017885, 5573.554115670737, 5607.243022191993, 5670.886582268373, 5690.3456102548325, 5554.833354999357, 5600.579956503238, 5660.0, 5663.971680141614, 5683.573731970118, 4912.214045752662, 6290.528210384239, 6362.204724409449, 6405.039244113374, 6442.355764423567, 6858.965705171442, 6890.31096890307, 6908.171019477644, 6921.653917304165, 6924.757633482804, 6231.56673964986, 6539.378759017885, 6841.17905851296, 6851.314868513158, 6909.239983601749, 5241.344831896013, 6424.678766061772, 6426.550141490145, 6467.353264673589, 6485.9946708260295, 7017.543859649123, 7158.928410715826, 7175.995360649513, 7176.7846964591145, 7178.5692858428665, 6541.116949211891, 6603.603783772989, 6605.07528945948, 6656.733730650816, 6811.0, 6054.576179667381, 6793.422559082534, 6820.31796820324, 6922.376986071277, 6947.305269473012, 6552.57408268461, 6850.828729281808, 6855.7600483983815, 6859.5541289816, 6872.484563657746, 6619.569727967667, 6711.6979735912, 6890.414314783199, 6899.586024838526, 6924.099867017357, 5378.2470454136455, 5433.945660543423, 5434.538064264502, 5436.075867102602, 5489.066858634042, 3795.658390744846, 4814.494478079751, 4942.505749425029, 4944.876378090505, 4987.675801072919, 6449.290578036413, 6530.412262896362, 6536.803895883131, 6541.771038013741, 6577.736890524421, 6968.44252459802, 6991.720331186797, 6996.475264355092, 7005.789826305219, 7028.929710702828, 6507.251666058369, 6515.348465153446, 6533.281339052701, 6552.541322107407, 6578.440832529192, 5782.653040817564, 6049.818505444844, 6056.545759068087, 6068.605540639845, 6084.361142080106, 7001.754938577125, 7020.052292940388, 7024.5434046786795, 7031.929680703128, 7140.6072666003865, 6917.827054323682, 6958.686859091252, 6960.164780226405, 6965.65171741421, 6977.755778547727, 7161.4986950912935, 7173.856522869409, 7193.676284567312, 7200.279972002758, 7207.459440541876, 6812.795616131524, 6828.180618325832, 6831.146106736658, 6846.897296540456, 6849.452043836581, 6723.831904202434, 7009.404200643003, 7009.509334346547, 7013.158420989514, 7039.612821294878, 5520.503154716095, 6605.9009114864275, 6733.6296503692765, 6744.393004629607, 6778.288279730755, 5612.915806262828, 6659.53383263167, 6663.507345421816, 6751.898721519083, 6778.932210678027, 6408.519361048034, 6466.741330346922, 6608.966955165386, 6620.337966203534, 6664.166979127609, 5500.58745594074, 6567.883459811939, 6632.30360812127, 6636.568623039487, 6663.900041499478, 5385.488378604026, 6318.05229215603, 6458.967705161444, 6537.5096867736065, 6585.670716464062], "value": 6212.161589775448, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.7805414483945, 848.9320854331652, 910.7677542226731, 1214.6659668591142, 1281.8846303832647, 36970.63029369705, 37784.73291602502, 37838.24323513528, 37846.62153378465, 37880.24239515215, 38153.23693526128, 38354.23291534168, 38488.23023539528, 38517.229655406874, 38816.0, 7844.41166912481, 7978.042634883793, 7989.760307190793, 8014.398920080988, 8026.157253488387, 7842.294193522582, 7878.251566101211, 7933.880991785099, 7938.126806051329, 7941.16617755136, 6829.8975515367065, 7365.963170184142, 7914.841703165933, 7953.920460795376, 7958.204179582053, 8505.192006759347, 8558.144185581454, 8580.485170889735, 8596.097409772008, 8605.268552173073, 8254.215849494289, 8332.62503187358, 8349.03986041607, 8360.540170290631, 8373.8325233495, 36324.63675363239, 36554.903352899455, 36572.085697857765, 36706.63293367059, 36880.44679329812, 3530.293941211768, 3549.94675079874, 3563.0379797454802, 3563.127033876691, 3565.714742820568, 29315.12054638364, 29660.70339296601, 29728.70271297281, 29792.70207297921, 29862.85068574665, 3052.8168309901257, 3060.4032213718333, 3109.626844778641, 3141.387429451258, 3155.9053228403186, 317903.0, 317973.0, 317986.0, 318430.0, 319165.0, 38231.4265286021, 39112.6088739109, 39225.0, 39228.607713922494, 47922.76038619854, 49328.50671493311, 49680.50319496759, 49723.75138124359, 49808.25287620688, 49876.50123498718, 15147.166905820286, 15219.0, 15254.0, 15264.007839490398, 15278.847211527742, 12381.876181238253, 12382.442790074288, 12422.503099876085, 12438.0, 12480.0, 13444.798328025088, 13509.864901351057, 13551.66120846967, 13627.863721362659, 13682.0, 5950.018246989285, 5956.315023772235, 5983.1623572699855, 6019.518438524908, 6025.728842202024, 960.9711708648751, 989.2382865193761, 998.5157198758596, 1007.6900029961167, 1029.3206483720708, 22981.425464363525, 23050.769492304862, 23562.528749424917, 23571.0, 23935.401614959417, 1065.5684447798653, 1080.9081228095542, 1081.8323159910226, 1142.497301187475, 1148.1159507179425, 10299.639512617023, 11786.528538858349, 11816.05471562273, 11999.280043197436, 12013.579524716593, 332042.0, 333665.0, 333943.0, 333960.0, 334582.0, 52309.47690523122, 52606.473935260925, 52735.472645273054, 52740.20889686658, 52792.94414111696, 1498.086167437862, 1498.4156179090164, 1533.9233038348038, 1536.7157075940986, 1545.1424459425127, 22031.559368812854, 22533.774662253825, 23085.307440776804, 23102.306930792103, 24392.51214975726, 37556.81221593874, 37600.81199593985, 37696.811515942245, 37699.0, 37794.81102594469, 279788.0, 281569.0, 282820.0, 283525.0, 283730.0, 32196.517052244704, 33027.83486082554, 33203.169920752174, 33757.81847635321, 33844.81543145978, 42057.369139462324, 44173.0, 44233.557664422944, 44418.555814442734, 44625.55374446214, 25387.746122539276, 25740.742592574585, 26101.08646197374, 27814.0, 31180.53229201609, 29441.263968400963, 29480.705192948655, 29593.85203073971, 31020.689793102683, 31145.37709245848, 34607.82696086504, 35471.82264088663, 35512.46731298981, 36562.63437365592, 37146.07134821651, 21509.67735484, 29226.56160157557, 30429.543556846224, 30770.846145769126, 30947.0715878524, 22730.090796368622, 23463.648045279675, 24325.148619798223, 24639.8768006166, 24646.0], "value": 17260.54349142684, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.44762984531945, 511.54472519457676, 516.2153526639506, 528.5084871069905, 539.0566508609933, 21411.67882481764, 22828.97269622867, 22861.856907154637, 22872.19947301845, 23990.760092399072, 25183.0, 25379.619305710385, 25642.358941026483, 25692.74307256927, 25803.87098064512, 5531.889362212753, 6456.935430645693, 6604.372584604479, 6645.202575690924, 6656.633885136315, 6422.325655806143, 6550.984597387413, 6615.6030638161865, 6616.371444712745, 6622.238442579117, 6122.326544080148, 6513.316101809313, 6516.67416629169, 6545.509086818508, 6574.506911981596, 6497.252815926158, 6516.468629871976, 6536.11762412073, 6539.836504087389, 6555.573887697308, 5965.940340596625, 6028.849278768022, 6033.245844269466, 6035.698215089272, 6040.48655864252, 28698.569521457193, 30422.695773042207, 30473.085807425814, 30579.082627520987, 30633.234169145726, 2281.646344816556, 2302.481941563152, 2316.293530473205, 2325.2442956039185, 2327.034280773477, 19599.31402400909, 20065.498362540908, 20149.697754533692, 20156.29452969154, 20186.899065504724, 2152.9354119376444, 2205.930123889912, 2254.729432468098, 2289.004283136824, 2301.516681496871, 213659.0, 219800.0, 223270.0, 223486.0, 224081.0, 15333.003354781904, 15337.616559585877, 15748.291326890318, 16166.35334586627, 16218.35126594923, 20821.895890520755, 20985.055672494775, 20990.580188396147, 20990.580188396452, 21004.15983360679, 10287.845682314772, 10399.0, 10417.427041512638, 10470.895291047143, 10499.475026248658, 9668.564914578861, 9693.757656058655, 9694.951525242328, 9760.97509761479, 9840.704778856645, 9065.274778017874, 9288.860667089999, 9400.670976515788, 9463.242940564738, 9474.194693450996, 2256.717910261217, 2684.9996752419574, 2686.7178946210756, 2692.9326766830595, 2832.6459192600923, 939.9342046056847, 956.531299663174, 967.9128878400837, 1006.2603986070233, 1026.1893104447404, 21241.575168496543, 21264.681029784566, 21404.464888377915, 22050.558988820132, 22142.446438838837, 977.953589659058, 979.4661909259471, 1008.6772232885406, 1030.98963016243, 1034.1158309645145, 10953.507092180871, 11130.777384452427, 11159.27464714791, 11369.715757105972, 11747.588834390755, 467856.0, 530576.6942330528, 572931.0, 580095.0, 602291.0, 152269.0, 153903.46096538892, 155130.0, 156071.0, 156128.0, 1153.4290526189423, 1159.3507635724022, 1169.6783384569312, 1180.2210541043041, 1187.9109066820024, 15509.457168999032, 18815.0, 19257.0, 20310.31165737132, 20330.085146168163, 61691.0, 63635.09094727166, 67176.6564668694, 67635.0, 71029.28970710227, 88177.0, 88487.0, 90833.0, 91827.0, 92394.53802730943, 11521.423928803693, 11726.00328972047, 11747.588834390755, 11851.229670071418, 11879.58421455245, 13467.528636497676, 14252.0, 15408.922955385151, 18340.633187335912, 18366.081695915418, 11428.314301141958, 14630.780538292147, 14684.559463216121, 14758.262086895827, 14859.925700371428, 12926.676833079247, 13015.934920325337, 13085.803712944124, 13876.653083672989, 13882.444702211798, 15249.008814427027, 15299.005564638263, 15326.233688315762, 15408.383664653289, 15416.691666166838, 17639.94160350383, 18018.279268829625, 18214.544636384195, 18631.161597728405, 18730.438086857415, 15843.762343564626, 16012.0, 16016.439424620083, 16033.919830400773, 16064.67870642604], "value": 12033.155697750799, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1321.4119716726054, 1328.830629046439, 1481.8073650425442, 1489.873360764335, 1492.097281144908, 13609.319534023296, 14184.936129790269, 14204.36080376383, 14207.857921420782, 14223.502177423767, 10947.34315941044, 13830.93084534576, 13943.233122178277, 13945.0, 13961.860381396209, 2806.677232118307, 2831.3204830840655, 2857.342811153436, 2857.7570906472974, 2864.2982469295052, 2779.0829026421234, 2815.4369126174743, 2833.971660283402, 2837.2197645647407, 2839.403725217707, 2684.932876678079, 2792.567152091429, 2867.5125228711063, 2880.798344115902, 2881.1644623059287, 2848.743613074823, 2877.568364745284, 2882.3514709190263, 2883.5818806273182, 2885.8268503889835, 2366.2073205476186, 2382.976170238293, 2796.161151654516, 2822.2662107852107, 2854.6574411070806, 13666.18002919828, 13701.246431446267, 13724.588262352145, 13732.313384330844, 13777.173369597856, 7615.31462168402, 7829.608519574055, 7903.683852645887, 7928.524288542706, 7955.721549745731, 4545.181867263925, 4841.878953026167, 4899.657024008321, 4913.164761990434, 5001.724905130216, 1228.520876858026, 1259.124908188809, 1274.7896597061474, 1278.7340532872481, 1279.878411550901, 16822.24299906495, 21305.68041479379, 21328.04023818932, 21400.35798926035, 21450.14199432021, 6660.333966603301, 6711.765088221889, 6717.0, 6855.245922948472, 6873.175218973755, 14164.787528187086, 14242.145471271757, 14365.425382984564, 14440.277986100653, 14467.638309042148, 7383.852322953511, 7552.66013029415, 7631.435555711066, 7633.313001829862, 7636.0, 6367.204099487564, 6929.757458488929, 7015.964920175366, 7081.221065682771, 7081.716731330689, 4707.223308154116, 5123.666961647481, 5230.979958907977, 5320.17537281722, 5347.197920311906, 1388.625260991611, 1458.8978771486106, 1546.5901536092945, 1567.3338830996781, 1572.2610373124612, 679.0123456790163, 820.8932838730926, 821.9301359384519, 845.5434065604628, 869.6217145541709, 7778.0, 8040.422012204513, 8088.150744171895, 8129.593520323961, 8507.659693612186, 952.5761036338902, 963.6145541783343, 976.5410257179115, 1010.6109147978082, 1036.6682661548232, 4813.205821039489, 4913.582345500601, 5089.338386009795, 5138.563222126087, 5151.356080489939, 17969.910150449425, 19029.524261893286, 19032.52418689544, 19070.52323691891, 19111.713324300148, 18137.727934081, 18786.342478013205, 18863.15115819764, 18967.525811854815, 19009.61980760377, 9274.768130796783, 9352.485613291334, 9477.763055923657, 9485.33602647808, 9492.098250666175, 7529.623518823927, 8043.959780201061, 8069.636866341146, 8351.540665263468, 8637.438566493409, 16950.660986780444, 17215.65568688594, 17588.91205543964, 17702.822971770118, 17819.821801781814, 16677.74983375226, 17383.391581294592, 17976.460706178834, 18007.0, 18225.45323640293, 10354.896451035393, 11092.223544351818, 11162.16283778736, 11179.217454778087, 11227.49476273586, 12646.367681615699, 12789.296588687339, 12804.423800928795, 12820.61538153857, 12844.165129266568, 8254.958725206336, 10224.897751022692, 10276.948615256875, 10434.739131521772, 10590.417527036087, 8542.35932305092, 9849.60601575929, 9887.802243955224, 10192.133668637953, 10316.226283028953, 9383.530823458936, 9508.239340852853, 9528.95235523818, 9529.332946693665, 9538.475383853955, 6662.766803161866, 6715.697793599397, 6981.406580440516, 7013.824654383681, 7309.378702810108, 5889.234399528205, 6999.475039371966, 7221.494495385273, 7310.561366318038, 7359.227281135618], "value": 6235.8034913171205, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.29270203772, 431.62879923266007, 444.0231490720416, 470.20065887990427, 472.83450792222715, 6106.389361063897, 6446.226452825662, 6453.257875344337, 6471.676416179196, 6523.119378883849, 5902.675352855602, 6081.54388420868, 6311.747530098791, 6331.746730130789, 6333.429991300782, 4582.747948862811, 4740.739259340735, 4778.78495467703, 4791.257355109946, 4796.664233503656, 3955.367141257385, 4530.0, 4779.761011949406, 4799.808007679689, 4823.469418363977, 3866.729328946975, 4558.566936141078, 4758.0753919693125, 4766.880827979294, 4817.181079216542, 4162.83348666053, 4761.761911904426, 4768.427788665348, 4769.856904292877, 4820.566149046585, 3396.8811091611924, 3634.727395445352, 3798.955287295988, 4182.53992060873, 4195.391668208093, 5234.790608375661, 6128.846778830521, 6138.416850399205, 6171.506279497629, 6208.412428814058, 5653.632513886584, 5676.772929082832, 5681.176229446748, 5697.914531282034, 5702.800401985911, 5620.747066382022, 6157.8607957528, 6676.933230667728, 6884.896726549106, 6888.793336199922, 3983.143624120828, 4189.853355132586, 4770.427548694144, 4790.664653474259, 4791.065742180276, 6612.404883560503, 7209.783706488814, 7209.855802883913, 7235.602041887694, 7264.982902393669, 4852.684575502581, 4857.514248575114, 4858.319835223001, 4869.318295438641, 4875.317455556225, 4845.563899249085, 4863.562279394871, 4959.752012399438, 4964.478729733398, 4966.875828104254, 4717.504662010508, 5617.073182924853, 5661.773529058792, 5676.205331253628, 5694.117411801178, 5218.452062533379, 6854.9657251713425, 6866.862662746817, 6873.415759660485, 6930.203026651899, 1563.9843601564066, 1687.392538686072, 1687.5274923021573, 1693.8390852868959, 1703.9659206815795, 1054.9924821795166, 1129.5086637312795, 1138.4706111658118, 1151.8675352334421, 1166.2419427372106, 5464.726763661801, 6043.607165534227, 6124.081387791867, 6181.578237005424, 6503.6097834130105, 6200.80658460893, 6222.0, 7074.92925070753, 7103.822404439827, 7209.179135058323, 4943.653944223942, 5290.862464570136, 6438.742450301936, 6465.3857883501, 6541.378569035934, 3188.442022646074, 3389.8305084745666, 3681.7054635629083, 3715.554133503997, 3721.702263818888, 6522.988936714817, 6717.085630595257, 7204.135503739585, 7311.744088956967, 7332.926670733331, 6154.292256390483, 6750.594964302059, 6822.181338239443, 7023.227444981048, 7332.743353982585, 6712.96643516789, 7223.674934627849, 7258.346748792634, 7321.597312147776, 7336.7432139874845, 6766.15423072116, 7030.543014704028, 7077.82305442368, 7139.82150446243, 7184.275773814352, 5794.478496935296, 6416.454601358937, 6991.755288564876, 7008.053912721921, 7015.754448594274, 5425.565954723692, 6441.742330306735, 6954.443644508326, 7040.542364746275, 7137.325052090889, 4024.9798751006056, 4450.354698568624, 4457.799399027116, 4823.758812059453, 4872.951270487391, 5594.804181853616, 5638.464345887134, 6604.966975165094, 6826.214985276558, 7136.964315178391, 5992.520598352208, 6743.966280168568, 6847.07564478789, 6847.486438517031, 6895.931040689529, 6811.489138314547, 6865.759698410531, 6903.620300883299, 6910.689018994057, 6936.445084393337, 6677.058976357557, 6691.364320389555, 6714.227863795726, 6739.393454588914, 6789.388954994074, 6804.965975170093, 6851.897221541781, 6859.2454829969665, 6872.8041460719005, 6929.826754331182, 5465.672059676433, 5466.098093814635, 5985.910211346921, 6604.405603495708, 6647.169103862017], "value": 4969.981003693231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.4406210658833, 721.8664547058795, 782.7534326687094, 816.7753867686382, 850.629975960457, 16693.33226670933, 17228.56928576783, 17460.91269543652, 17461.0, 17543.210555525002, 15766.448174313902, 18206.635867282646, 18865.62268754624, 18943.810561894377, 19026.61946761064, 992.320260621473, 1004.5981607357043, 1018.3329918903094, 1019.9286049976505, 1022.642075273652, 1011.3729487717625, 1015.735908663749, 1015.7968406318729, 1017.8269694152012, 1022.5091955861191, 1058.6347710040025, 1065.5844220753872, 1075.4138994248115, 1078.4014871746178, 1079.6760971708495, 1058.0794708603564, 1063.5639387850945, 1063.8085144673955, 1073.1093192650121, 1073.125402796722, 772.2007722007695, 786.9921300787033, 788.8777239527882, 791.1732239809413, 793.5040599625235, 17792.55518612032, 17995.280188792432, 17999.64000719978, 18014.639707205915, 18016.819831801644, 16939.153042347833, 17953.551161221076, 18087.276508939627, 18113.728294075598, 18167.72748408775, 14562.854371456257, 14874.181919994397, 14927.776083358649, 14970.251487425585, 15192.696146077016, 3640.144566027008, 3655.707543396522, 3666.981665091684, 3667.504886840269, 3684.4841722158917, 131483.0, 138955.0, 140968.0, 149379.2531037338, 150060.0, 13435.261060641758, 13613.523526676718, 13800.654983625289, 13911.095778774346, 13915.373808178454, 26497.735022649525, 27544.449111017668, 27557.58663620007, 27624.861875690494, 27925.16224513268, 7371.852562948711, 7642.08295004603, 7683.346915512244, 7736.574488403079, 7754.457187996786, 3715.145516531213, 3794.867179648699, 3825.3114439401174, 3836.9616303836606, 3845.8653947111716, 4649.395578574764, 4670.136024835417, 4722.929156062662, 4739.928901066487, 4762.333273341735, 875.4790899414838, 907.2761752669979, 949.852772820214, 950.6340059077166, 961.024560071528, 626.27352271365, 630.2405601250574, 636.7453018792428, 645.7223393940557, 663.9601623902581, 11092.389918554558, 11912.783548441872, 11988.220765650205, 12161.270323780425, 12385.814212786816, 622.3931666625004, 641.7208514296296, 650.3171669746747, 672.2168673495421, 674.1775034457953, 3633.7274704396746, 3659.9817000914827, 4449.822007119679, 4646.047560250208, 4652.0, 152464.0, 153146.0, 153546.0, 154625.0, 157982.0, 126078.0, 130830.0, 132446.0, 132803.33598331947, 139009.0, 71255.6437217821, 73123.63438182882, 81809.59095204606, 107668.0, 114478.42760786143, 12003.399830008639, 12100.394980251129, 12196.716076854393, 12326.302109367334, 12731.87268127307, 44518.777406112764, 44596.55403445924, 44794.3280850781, 44895.0, 47189.29216061831, 63948.68025659842, 64120.0, 72180.27819721735, 82034.58982705048, 87599.56200218959, 16583.336666533687, 16877.831221688117, 16937.491875243762, 17461.95228286307, 17656.0, 18306.725399118757, 18560.443186704422, 18819.964901930525, 18843.811561884206, 18926.0, 16843.494695159166, 17327.56681082983, 17330.30678772835, 17424.128793560525, 17989.190486428404, 16637.334506619598, 16767.32930682759, 17118.0, 17655.470335889942, 17784.46646600604, 16777.664446711242, 16820.66358672795, 17157.742633860253, 17178.31286748516, 17379.56551086233, 10888.020078193, 10946.507407166537, 11203.663890083311, 11242.606508772154, 11721.238119522204, 12636.296041239539, 12710.491580336682, 12712.74574508523, 13122.671933201746, 13200.471981121033], "value": 8795.579358371828, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1512.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 106.0, 113.0, 130.0, 174.0, 172.0, 103.0, 114.0, 116.0], "value": 124.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 118.0, 148.0, 155.0, 110.0, 122.0, 121.0, 112.0, 155.0, 109.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 198.0, 199.0, 198.0, 196.0, 197.0, 199.0, 202.0, 194.0], "value": 198.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 86.0, 106.0, 201.0, 85.0, 83.0, 58.0, 66.0, 119.0, 57.0], "value": 93.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 125.0, 70.0, 73.0, 85.0, 104.0, 71.0, 73.0, 111.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 93.0, 93.0, 93.0, 93.0, 92.0, 94.0, 93.0, 93.0, 89.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 101.0, 97.0, 97.0, 105.0, 97.0, 97.0, 104.0, 98.0], "value": 99.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 91.0, 90.0, 92.0, 92.0, 112.0, 92.0, 91.0, 117.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 52.0, 54.0, 51.0, 52.0, 52.0, 47.0, 46.0, 53.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 60.0, 60.0, 64.0, 56.0, 58.0, 59.0, 58.0], "value": 58.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 78.0, 80.0, 81.0, 80.0, 79.0, 82.0, 80.0, 82.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 134.0, 144.0, 138.0, 129.0, 136.0, 145.0, 139.0, 149.0, 177.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 180.0, 182.0, 182.0, 229.0, 224.0, 233.0, 182.0, 181.0, 167.0], "value": 193.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 61.0, 65.0, 65.0, 67.0, 63.0, 67.0, 66.0, 63.0], "value": 65.0, "unit": "score"}]}]} 16:20:54 INFO - Return code: 0 16:20:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:20:54 INFO - # TBPL SUCCESS # 16:20:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:20:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:20:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:20:54 INFO - 2017-06-20 16:20:54.704 screenresolution[1987:13900] starting screenresolution argv=screenresolution get 16:20:54 INFO - 2017-06-20 16:20:54.707 screenresolution[1987:13900] Display 0: 1600x1200x32@60 16:20:54 INFO - 2017-06-20 16:20:54.713 screenresolution[1988:13903] starting screenresolution argv=screenresolution list 16:20:54 INFO - Available Modes on Display 0 16:20:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:20:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:20:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:20:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:20:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:20:54 INFO - Intel Iris: 16:20:54 INFO - Chipset Model: Intel Iris 16:20:54 INFO - Type: GPU 16:20:54 INFO - Bus: Built-In 16:20:54 INFO - VRAM (Dynamic, Max): 1536 MB 16:20:54 INFO - Vendor: Intel (0x8086) 16:20:54 INFO - Device ID: 0x0a2e 16:20:54 INFO - Revision ID: 0x0009 16:20:54 INFO - Displays: 16:20:54 INFO - Display: 16:20:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:20:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:20:54 INFO - Display Serial Number: 1600x1200 60 16:20:54 INFO - Main Display: Yes 16:20:54 INFO - Mirror: Off 16:20:54 INFO - Online: Yes 16:20:54 INFO - Rotation: Supported 16:20:54 INFO - Return code: 0 16:20:54 INFO - Running post-action listener: _resource_record_post_action 16:20:54 INFO - [mozharness: 2017-06-20 23:20:54.948049Z] Finished run-tests step (success) 16:20:54 INFO - Running post-run listener: _resource_record_post_run 16:20:55 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 2588672; Write bytes: 561576448; Read time: 45; Write time: 1975 16:20:55 INFO - TinderboxPrint: CPU usage
53.0% 16:20:55 INFO - TinderboxPrint: I/O read bytes / time
2,588,672 / 45 16:20:55 INFO - TinderboxPrint: I/O write bytes / time
561,576,448 / 1,975 16:20:55 INFO - TinderboxPrint: CPU idle
2,361.4 (47.0%) 16:20:55 INFO - TinderboxPrint: CPU system
1,019.6 (20.3%) 16:20:55 INFO - TinderboxPrint: CPU user
1,648.7 (32.8%) 16:20:55 INFO - TinderboxPrint: Swap in / out
245,874,688 / 0 16:20:55 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231772672; Write bytes: 227608064; Read time: 16737; Write time: 472 16:20:55 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:20:55 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 2588672; Write bytes: 314655744; Read time: 45; Write time: 1396 16:20:55 INFO - Running post-run listener: _upload_blobber_files 16:20:55 INFO - Blob upload gear active. 16:20:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:20:55 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:20:55 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:20:55 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:20:55 INFO - (blobuploader) - INFO - Open directory for files ... 16:20:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:20:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:20:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:56 INFO - (blobuploader) - INFO - Done attempting. 16:20:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:20:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:20:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:57 INFO - (blobuploader) - INFO - Done attempting. 16:20:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:20:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:20:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:20:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:20:58 INFO - (blobuploader) - INFO - Done attempting. 16:20:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:20:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:20:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:00 INFO - (blobuploader) - INFO - Done attempting. 16:21:00 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:00 INFO - Return code: 0 16:21:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6a6973898ad9011b00c9e03bee5d5be5f9cc6b3697acd794709dc43b509ddebc51c5c255b95e1e6095c72dc110011967fea2a89c6cae4cd5597d6ee902682d7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9291a3e8e0d9c184b577139176f47a2e085007195aac995cb4ba7aaa6ea1ba2dc9ac6c37bea7121fe9d52829c2ae13293efc8bfea173f70f348e81eb57648201", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b9b6e0acacff61cefe5c722afd080b2a31463392249921b9497beb4d639767fc784a3bcbd233a41f72612620ab0a14f9ee78b423e9476e440f7dd2703f58531", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a5c6f3d061a3476cd0299ce7d55579951adfce31c74f33ec2e6fad03b379de5d3cd6cc520ab9cf56927d673ace72e9f5498c3f078139f1de3f069c5dba36338"} 16:21:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:00 INFO - Contents: 16:21:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a6a6973898ad9011b00c9e03bee5d5be5f9cc6b3697acd794709dc43b509ddebc51c5c255b95e1e6095c72dc110011967fea2a89c6cae4cd5597d6ee902682d7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9291a3e8e0d9c184b577139176f47a2e085007195aac995cb4ba7aaa6ea1ba2dc9ac6c37bea7121fe9d52829c2ae13293efc8bfea173f70f348e81eb57648201", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b9b6e0acacff61cefe5c722afd080b2a31463392249921b9497beb4d639767fc784a3bcbd233a41f72612620ab0a14f9ee78b423e9476e440f7dd2703f58531", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a5c6f3d061a3476cd0299ce7d55579951adfce31c74f33ec2e6fad03b379de5d3cd6cc520ab9cf56927d673ace72e9f5498c3f078139f1de3f069c5dba36338"} 16:21:00 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:00 INFO - Copying logs to upload dir... 16:21:00 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.179080 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 16:21:00.908358) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 16:21:00.912228) ========= 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.oKedHuTzSs/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.10lWI6oqBw/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/a6a6973898ad9011b00c9e03bee5d5be5f9cc6b3697acd794709dc43b509ddebc51c5c255b95e1e6095c72dc110011967fea2a89c6cae4cd5597d6ee902682d7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9291a3e8e0d9c184b577139176f47a2e085007195aac995cb4ba7aaa6ea1ba2dc9ac6c37bea7121fe9d52829c2ae13293efc8bfea173f70f348e81eb57648201", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b9b6e0acacff61cefe5c722afd080b2a31463392249921b9497beb4d639767fc784a3bcbd233a41f72612620ab0a14f9ee78b423e9476e440f7dd2703f58531", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a5c6f3d061a3476cd0299ce7d55579951adfce31c74f33ec2e6fad03b379de5d3cd6cc520ab9cf56927d673ace72e9f5498c3f078139f1de3f069c5dba36338"} build_url:https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011226 build_url: 'https://queue.taskcluster.net/v1/task/CUu5_F-yTAaJihUyToi1Sw/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/a6a6973898ad9011b00c9e03bee5d5be5f9cc6b3697acd794709dc43b509ddebc51c5c255b95e1e6095c72dc110011967fea2a89c6cae4cd5597d6ee902682d7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9291a3e8e0d9c184b577139176f47a2e085007195aac995cb4ba7aaa6ea1ba2dc9ac6c37bea7121fe9d52829c2ae13293efc8bfea173f70f348e81eb57648201", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b9b6e0acacff61cefe5c722afd080b2a31463392249921b9497beb4d639767fc784a3bcbd233a41f72612620ab0a14f9ee78b423e9476e440f7dd2703f58531", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a5c6f3d061a3476cd0299ce7d55579951adfce31c74f33ec2e6fad03b379de5d3cd6cc520ab9cf56927d673ace72e9f5498c3f078139f1de3f069c5dba36338"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 16:21:00.948888) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 16:21:00.949244) ========= 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.oKedHuTzSs/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.10lWI6oqBw/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005267 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 16:21:00.998866) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 16:21:00.999221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 16:21:00.999543) ========= ========= Total master_lag: 0.20 =========