builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0230 starttime: 1486045464.85 results: success (0) buildid: 20170202052009 builduid: d9aca38237404166b5a28eadd01723e5 revision: dd7cce059626e9787537bf9ba7863a6ad8ac06a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.847171) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.847678) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.847954) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.896868) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:24.897194) ========= 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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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-02-02 06:24:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2017-02-02 06:24:25 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.123090 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:25.139975) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:25.140244) ========= 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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.026235 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:25.197780) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 06:24:25.198081) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev dd7cce059626e9787537bf9ba7863a6ad8ac06a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev dd7cce059626e9787537bf9ba7863a6ad8ac06a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mT1OUCNHSR/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.mnTR0xtAX4/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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-02-02 06:24:25,309 truncating revision to first 12 chars 2017-02-02 06:24:25,310 Setting DEBUG logging. 2017-02-02 06:24:25,310 attempt 1/10 2017-02-02 06:24:25,310 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/dd7cce059626?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 06:24:26,467 unpacking tar archive at: mozilla-central-dd7cce059626/testing/mozharness/ program finished with exit code 0 elapsedTime=1.418249 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-02 06:24:26.643602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:26.643933) ========= script_repo_revision: dd7cce059626e9787537bf9ba7863a6ad8ac06a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:26.644345) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:26.644624) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 06:24:26.664054) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-02 06:24:26.664427) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mT1OUCNHSR/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.mnTR0xtAX4/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:24:26 INFO - MultiFileLogger online at 20170202 06:24:26 in /builds/slave/test 06:24:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 06:24:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:24:26 INFO - {'append_to_log': False, 06:24:26 INFO - 'base_work_dir': '/builds/slave/test', 06:24:26 INFO - 'blob_upload_branch': 'Firefox', 06:24:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:24:26 INFO - 'branch': 'Firefox', 06:24:26 INFO - 'buildbot_json_path': 'buildprops.json', 06:24:26 INFO - 'config_files': ('talos/mac_config.py',), 06:24:26 INFO - 'default_actions': ('clobber', 06:24:26 INFO - 'read-buildbot-config', 06:24:26 INFO - 'download-and-extract', 06:24:26 INFO - 'populate-webroot', 06:24:26 INFO - 'create-virtualenv', 06:24:26 INFO - 'install', 06:24:26 INFO - 'run-tests'), 06:24:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:24:26 INFO - 'download_minidump_stackwalk': True, 06:24:26 INFO - 'download_symbols': 'ondemand', 06:24:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:24:26 INFO - 'tooltool.py': '/tools/tooltool.py', 06:24:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:24:26 INFO - '/tools/misc-python/virtualenv.py')}, 06:24:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:24:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:24:26 INFO - 'installer_path': 'installer.exe', 06:24:26 INFO - 'log_level': 'info', 06:24:26 INFO - 'log_name': 'talos', 06:24:26 INFO - 'log_to_console': True, 06:24:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:24:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:24:26 INFO - 'opt_config_files': (), 06:24:26 INFO - 'pip_index': False, 06:24:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:24:26 INFO - 'cmd': ('bash', 06:24:26 INFO - '-c', 06:24:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:24:26 INFO - 'enabled': True, 06:24:26 INFO - 'halt_on_failure': False, 06:24:26 INFO - 'name': 'check_screen_resolution'},), 06:24:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:24:26 INFO - 'cmd': ('bash', 06:24:26 INFO - '-c', 06:24:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:24:26 INFO - 'enabled': True, 06:24:26 INFO - 'halt_on_failure': False, 06:24:26 INFO - 'name': 'check_screen_resolution'},), 06:24:26 INFO - 'run_cmd_checks_enabled': True, 06:24:26 INFO - 'sps_profile': False, 06:24:26 INFO - 'sps_profile_interval': 0, 06:24:26 INFO - 'suite': 'dromaeojs-e10s', 06:24:26 INFO - 'system_bits': '32', 06:24:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:24:26 INFO - 'title': 't-yosemite-r7-0230', 06:24:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:24:26 INFO - 'use_talos_json': True, 06:24:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:24:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:24:26 INFO - 'work_dir': 'build'} 06:24:26 INFO - [mozharness: 2017-02-02 14:24:26.880883Z] Running clobber step. 06:24:26 INFO - Running pre-action listener: _resource_record_pre_action 06:24:26 INFO - Running main action method: clobber 06:24:26 INFO - rmtree: /builds/slave/test/build 06:24:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:24:29 INFO - Running post-action listener: _resource_record_post_action 06:24:29 INFO - [mozharness: 2017-02-02 14:24:29.233296Z] Finished clobber step (success) 06:24:29 INFO - [mozharness: 2017-02-02 14:24:29.233427Z] Running read-buildbot-config step. 06:24:29 INFO - Running pre-action listener: _resource_record_pre_action 06:24:29 INFO - Running main action method: read_buildbot_config 06:24:29 INFO - Using buildbot properties: 06:24:29 INFO - { 06:24:29 INFO - "project": "", 06:24:29 INFO - "product": "firefox", 06:24:29 INFO - "script_repo_revision": "production", 06:24:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 06:24:29 INFO - "repository": "", 06:24:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 06:24:29 INFO - "buildid": "20170202052009", 06:24:29 INFO - "pgo_build": "False", 06:24:29 INFO - "basedir": "/builds/slave/test", 06:24:29 INFO - "buildnumber": 62, 06:24:29 INFO - "slavename": "t-yosemite-r7-0230", 06:24:29 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:24:29 INFO - "platform": "yosemite_r7", 06:24:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 06:24:29 INFO - "branch": "mozilla-central", 06:24:29 INFO - "repo_path": "mozilla-central", 06:24:29 INFO - "revision": "dd7cce059626e9787537bf9ba7863a6ad8ac06a1", 06:24:29 INFO - "stage_platform": "macosx64", 06:24:29 INFO - "builduid": "d9aca38237404166b5a28eadd01723e5", 06:24:29 INFO - "slavebuilddir": "test" 06:24:29 INFO - } 06:24:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 06:24:29 INFO - Running post-action listener: _resource_record_post_action 06:24:29 INFO - [mozharness: 2017-02-02 14:24:29.236840Z] Finished read-buildbot-config step (success) 06:24:29 INFO - [mozharness: 2017-02-02 14:24:29.236955Z] Running download-and-extract step. 06:24:29 INFO - Running pre-action listener: _resource_record_pre_action 06:24:29 INFO - Running main action method: download_and_extract 06:24:29 INFO - mkdir: /builds/slave/test/build/tests 06:24:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:24:29 INFO - trying https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 06:24:29 INFO - Downloading https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:24:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 06:24:30 INFO - Downloaded 1199 bytes. 06:24:30 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 06:24:30 INFO - Using the following test package requirements: 06:24:30 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:24:30 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 06:24:30 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 06:24:30 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'jsshell-mac.zip'], 06:24:30 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 06:24:30 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 06:24:30 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 06:24:30 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 06:24:30 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 06:24:30 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 06:24:30 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 06:24:30 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:24:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:24:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:24:30 INFO - Fetch https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:24:31 INFO - Expected file size: 34848321 06:24:31 INFO - Obtained file size: 34848321 06:24:38 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:24:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 06:24:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 06:24:38 INFO - Fetch https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 06:24:39 INFO - Expected file size: 34848321 06:24:39 INFO - Obtained file size: 34848321 06:24:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 06:24:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 06:24:45 INFO - Fetch https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 06:24:46 INFO - Expected file size: 14403454 06:24:46 INFO - Obtained file size: 14403454 06:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:24:47 INFO - trying https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:24:47 INFO - Downloading https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 06:24:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 06:24:49 INFO - Downloaded 70195404 bytes. 06:24:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:24:49 INFO - mkdir: /builds/slave/test/properties 06:24:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:24:49 INFO - Writing to file /builds/slave/test/properties/build_url 06:24:49 INFO - Contents: 06:24:49 INFO - build_url:https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 06:24:49 INFO - Running post-action listener: _resource_record_post_action 06:24:49 INFO - Running post-action listener: set_extra_try_arguments 06:24:49 INFO - [mozharness: 2017-02-02 14:24:49.725011Z] Finished download-and-extract step (success) 06:24:49 INFO - [mozharness: 2017-02-02 14:24:49.725165Z] Running populate-webroot step. 06:24:49 INFO - Running pre-action listener: _resource_record_pre_action 06:24:49 INFO - Running main action method: populate_webroot 06:24:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:24:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:24:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:24:49 INFO - '--tppagecycles', 06:24:49 INFO - '7'], 06:24:49 INFO - 'tests': ['tsvgm']}}, 06:24:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:24:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:24:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:24:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:24:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:24:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:24:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['damp', 'tps']}, 06:24:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'tests': ['damp', 'tps']}, 06:24:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['dromaeo_dom']}, 06:24:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:24:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['basic_compositor_video']}, 06:24:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:24:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other-e10s': {'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['a11yr', 06:24:49 INFO - 'ts_paint', 06:24:49 INFO - 'tpaint', 06:24:49 INFO - 'sessionrestore', 06:24:49 INFO - 'sessionrestore_no_auto_restore', 06:24:49 INFO - 'tabpaint']}, 06:24:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['tsvgx', 06:24:49 INFO - 'tsvgr_opacity', 06:24:49 INFO - 'tart', 06:24:49 INFO - 'tscrollx', 06:24:49 INFO - 'cart', 06:24:49 INFO - 'tsvg_static']}, 06:24:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:24:49 INFO - 'tsvgr_opacity', 06:24:49 INFO - 'tart', 06:24:49 INFO - 'tscrollx', 06:24:49 INFO - 'cart', 06:24:49 INFO - 'tsvg_static']}, 06:24:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--disable-e10s'], 06:24:49 INFO - 'tests': ['tp5o']}, 06:24:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:24:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--disable-e10s', 06:24:49 INFO - '--xperf_path', 06:24:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:24:49 INFO - 'tests': ['tp5n']}, 06:24:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:24:49 INFO - 'talos_options': ['--xperf_path', 06:24:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:24:49 INFO - 'tests': ['tp5n']}}, 06:24:49 INFO - 'talos.zip': {'path': '', 06:24:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:24:49 INFO - Running post-action listener: _resource_record_post_action 06:24:49 INFO - [mozharness: 2017-02-02 14:24:49.743847Z] Finished populate-webroot step (success) 06:24:49 INFO - [mozharness: 2017-02-02 14:24:49.743985Z] Running create-virtualenv step. 06:24:49 INFO - Running pre-action listener: _resource_record_pre_action 06:24:49 INFO - Running main action method: create_virtualenv 06:24:49 INFO - Creating virtualenv /builds/slave/test/build/venv 06:24:49 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 06:24:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:24:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:24:49 INFO - Using real prefix '/tools/python27' 06:24:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:24:50 INFO - Installing distribute.............................................................................................................................................................................................done. 06:24:53 INFO - Installing pip.................done. 06:24:53 INFO - Return code: 0 06:24:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:24:53 INFO - Reading from file tmpfile_stdout 06:24:53 INFO - Output received: 06:24:53 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) 06:24:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:53 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:53 INFO - 'HOME': '/Users/cltbld', 06:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:53 INFO - 'LOGNAME': 'cltbld', 06:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:53 INFO - 'MOZ_NO_REMOTE': '1', 06:24:53 INFO - 'NO_EM_RESTART': '1', 06:24:53 INFO - 'PAGER': '/bin/cat', 06:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:53 INFO - 'PWD': '/builds/slave/test', 06:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:53 INFO - 'SHELL': '/bin/bash', 06:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:24:53 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:53 INFO - 'USER': 'cltbld', 06:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:53 INFO - 'XPC_FLAGS': '0x0', 06:24:53 INFO - 'XPC_SERVICE_NAME': '0', 06:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:24:54 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 06:24:54 INFO - Cleaning up... 06:24:54 INFO - Return code: 0 06:24:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:54 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:24:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:24:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:54 INFO - 'HOME': '/Users/cltbld', 06:24:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:54 INFO - 'LOGNAME': 'cltbld', 06:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:54 INFO - 'MOZ_NO_REMOTE': '1', 06:24:54 INFO - 'NO_EM_RESTART': '1', 06:24:54 INFO - 'PAGER': '/bin/cat', 06:24:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:54 INFO - 'PWD': '/builds/slave/test', 06:24:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:54 INFO - 'SHELL': '/bin/bash', 06:24:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:24:54 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:24:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:54 INFO - 'USER': 'cltbld', 06:24:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:54 INFO - 'XPC_FLAGS': '0x0', 06:24:54 INFO - 'XPC_SERVICE_NAME': '0', 06:24:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:24:54 INFO - Downloading/unpacking psutil>=3.1.1 06:24: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 06:24: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 06:24:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:24:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:24: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 06:24: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 06:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:24:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:24:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:24:57 INFO - Installing collected packages: psutil 06:24:57 INFO - Running setup.py install for psutil 06:24:57 INFO - building 'psutil._psutil_osx' extension 06:24:57 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 06:24:58 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 06:24:58 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 06:24:58 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 06:24:58 INFO - building 'psutil._psutil_posix' extension 06:24:58 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 06:24:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:24:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:24:58 INFO - ^ 06:24:58 INFO - 1 warning generated. 06:24:58 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 06:24:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:24:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:24:58 INFO - Successfully installed psutil 06:24:58 INFO - Cleaning up... 06:24:59 INFO - Return code: 0 06:24:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:24:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:24:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:24:59 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:24:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:24:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:24:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:24:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:24:59 INFO - 'HOME': '/Users/cltbld', 06:24:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:24:59 INFO - 'LOGNAME': 'cltbld', 06:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:24:59 INFO - 'MOZ_NO_REMOTE': '1', 06:24:59 INFO - 'NO_EM_RESTART': '1', 06:24:59 INFO - 'PAGER': '/bin/cat', 06:24:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:24:59 INFO - 'PWD': '/builds/slave/test', 06:24:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:24:59 INFO - 'SHELL': '/bin/bash', 06:24:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:24:59 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:24:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:24:59 INFO - 'USER': 'cltbld', 06:24:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:24:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:24:59 INFO - 'XPC_FLAGS': '0x0', 06:24:59 INFO - 'XPC_SERVICE_NAME': '0', 06:24:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:24:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:24:59 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 06:24:59 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 06:24:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:24:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:24:59 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 06:24:59 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 06:25:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:25:01 INFO - Installing collected packages: mozsystemmonitor 06:25:01 INFO - Running setup.py install for mozsystemmonitor 06:25:02 INFO - Successfully installed mozsystemmonitor 06:25:02 INFO - Cleaning up... 06:25:02 INFO - Return code: 0 06:25:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:02 INFO - 'HOME': '/Users/cltbld', 06:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:02 INFO - 'LOGNAME': 'cltbld', 06:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:02 INFO - 'MOZ_NO_REMOTE': '1', 06:25:02 INFO - 'NO_EM_RESTART': '1', 06:25:02 INFO - 'PAGER': '/bin/cat', 06:25:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:02 INFO - 'PWD': '/builds/slave/test', 06:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:02 INFO - 'SHELL': '/bin/bash', 06:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:25:02 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:02 INFO - 'USER': 'cltbld', 06:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:02 INFO - 'XPC_FLAGS': '0x0', 06:25:02 INFO - 'XPC_SERVICE_NAME': '0', 06:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:02 INFO - Downloading/unpacking jsonschema==2.5.1 06:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:25:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:25:05 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 06:25:05 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 06:25:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:05 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 06:25:05 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 06:25:05 INFO - Downloading functools32-3.2.3-2.tar.gz 06:25:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:25:05 INFO - warning: no files found matching '*.txt' 06:25:05 INFO - no previously-included directories found matching 'build' 06:25:05 INFO - no previously-included directories found matching 'dist' 06:25:05 INFO - no previously-included directories found matching '.git*' 06:25:05 INFO - Installing collected packages: jsonschema, functools32 06:25:05 INFO - Running setup.py install for functools32 06:25:05 INFO - warning: no files found matching '*.txt' 06:25:05 INFO - no previously-included directories found matching 'build' 06:25:05 INFO - no previously-included directories found matching 'dist' 06:25:05 INFO - no previously-included directories found matching '.git*' 06:25:05 INFO - Successfully installed jsonschema functools32 06:25:05 INFO - Cleaning up... 06:25:05 INFO - Return code: 0 06:25:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:25:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:25:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:05 INFO - 'HOME': '/Users/cltbld', 06:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:05 INFO - 'LOGNAME': 'cltbld', 06:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:05 INFO - 'MOZ_NO_REMOTE': '1', 06:25:05 INFO - 'NO_EM_RESTART': '1', 06:25:05 INFO - 'PAGER': '/bin/cat', 06:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:05 INFO - 'PWD': '/builds/slave/test', 06:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:05 INFO - 'SHELL': '/bin/bash', 06:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:25:05 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:05 INFO - 'USER': 'cltbld', 06:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:05 INFO - 'XPC_FLAGS': '0x0', 06:25:05 INFO - 'XPC_SERVICE_NAME': '0', 06:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:25:06 INFO - Cleaning up... 06:25:06 INFO - Return code: 0 06:25:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:25:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:25:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:25:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:25:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:06 INFO - 'HOME': '/Users/cltbld', 06:25:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:06 INFO - 'LOGNAME': 'cltbld', 06:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:06 INFO - 'MOZ_NO_REMOTE': '1', 06:25:06 INFO - 'NO_EM_RESTART': '1', 06:25:06 INFO - 'PAGER': '/bin/cat', 06:25:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:06 INFO - 'PWD': '/builds/slave/test', 06:25:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:06 INFO - 'SHELL': '/bin/bash', 06:25:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:25:06 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:25:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:06 INFO - 'USER': 'cltbld', 06:25:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:06 INFO - 'XPC_FLAGS': '0x0', 06:25:06 INFO - 'XPC_SERVICE_NAME': '0', 06:25:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:06 INFO - Downloading/unpacking blobuploader==1.2.4 06:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:08 INFO - Downloading blobuploader-1.2.4.tar.gz 06:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:25:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:25:08 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 06:25:08 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 06:25:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:08 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 06:25:08 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 06:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:25:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:25:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:25:10 INFO - Downloading docopt-0.6.1.tar.gz 06:25:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:25:10 INFO - Installing collected packages: blobuploader, requests, docopt 06:25:10 INFO - Running setup.py install for blobuploader 06:25:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:25:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:25:10 INFO - Running setup.py install for requests 06:25:10 INFO - Running setup.py install for docopt 06:25:10 INFO - Successfully installed blobuploader requests docopt 06:25:10 INFO - Cleaning up... 06:25:11 INFO - Return code: 0 06:25:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:25:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:25:11 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:25:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:25:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:11 INFO - 'HOME': '/Users/cltbld', 06:25:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:11 INFO - 'LOGNAME': 'cltbld', 06:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:11 INFO - 'MOZ_NO_REMOTE': '1', 06:25:11 INFO - 'NO_EM_RESTART': '1', 06:25:11 INFO - 'PAGER': '/bin/cat', 06:25:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:11 INFO - 'PWD': '/builds/slave/test', 06:25:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:11 INFO - 'SHELL': '/bin/bash', 06:25:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:25:11 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:25:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:11 INFO - 'USER': 'cltbld', 06:25:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:11 INFO - 'XPC_FLAGS': '0x0', 06:25:11 INFO - 'XPC_SERVICE_NAME': '0', 06:25:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:25:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-7t5soY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:25:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-6JKbC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:25:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-kUSMFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:25:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-a7mu1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:25:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-gBsrrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:25:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-14EfHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:25:11 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-IfXotN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-7BBFuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-xZzJ6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-vIIghs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-C_mOdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-X9fLP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-9aP0zJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-mW3Cdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-sdMVlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:25:12 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ivKR1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:25:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:25:13 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-PC2Qf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:25:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:25:13 INFO - Running setup.py install for manifestparser 06:25:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Running setup.py install for mozcrash 06:25:13 INFO - Running setup.py install for mozdebug 06:25:13 INFO - Running setup.py install for mozdevice 06:25:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:25:13 INFO - Running setup.py install for mozfile 06:25:13 INFO - Running setup.py install for mozhttpd 06:25:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for mozinfo 06:25:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for mozInstall 06:25:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for mozleak 06:25:14 INFO - Running setup.py install for mozlog 06:25:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for moznetwork 06:25:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:25:14 INFO - Running setup.py install for mozprocess 06:25:14 INFO - Running setup.py install for mozprofile 06:25:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Running setup.py install for mozrunner 06:25:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Running setup.py install for mozscreenshot 06:25:15 INFO - Running setup.py install for moztest 06:25:15 INFO - Running setup.py install for mozversion 06:25:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:25:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:25:15 INFO - Cleaning up... 06:25:15 INFO - Return code: 0 06:25:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:15 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:25:15 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:25:15 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:25:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:25:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:15 INFO - 'HOME': '/Users/cltbld', 06:25:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:15 INFO - 'LOGNAME': 'cltbld', 06:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:15 INFO - 'MOZ_NO_REMOTE': '1', 06:25:15 INFO - 'NO_EM_RESTART': '1', 06:25:15 INFO - 'PAGER': '/bin/cat', 06:25:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:15 INFO - 'PWD': '/builds/slave/test', 06:25:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:15 INFO - 'SHELL': '/bin/bash', 06:25:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:25:15 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:25:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:15 INFO - 'USER': 'cltbld', 06:25:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:15 INFO - 'XPC_FLAGS': '0x0', 06:25:15 INFO - 'XPC_SERVICE_NAME': '0', 06:25:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-ddui_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-S7lDnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-m4dnZ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-XZLPn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:25:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-8knaMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-_u8jMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-YF9k2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:25:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-B1qmvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:25:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:25:16 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-Z7RnHf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:25:16 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)) 06:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:25:17 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-UA_RYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:25:17 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-yUOvHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:25:17 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-gvYvxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:25:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:25:17 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-SBvfbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:25:17 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-_cXjUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:17 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-7mxmjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:25:17 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-r1f45i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:25:17 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)) 06:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:25:17 INFO - Running setup.py (path:/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/pip-zPYnWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:25:17 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)) 06:25:17 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)) 06:25:17 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)) 06:25:17 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)) 06:25:17 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:25:17 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:25:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:25:17 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 06:25:17 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 06:25:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:25:17 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 06:25:17 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 06:25:20 INFO - Downloading blessings-1.6.tar.gz 06:25:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:25:20 INFO - Installing collected packages: blessings 06:25:20 INFO - Running setup.py install for blessings 06:25:20 INFO - Successfully installed blessings 06:25:20 INFO - Cleaning up... 06:25:20 INFO - Return code: 0 06:25:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:25:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:25:21 INFO - Reading from file tmpfile_stdout 06:25:21 INFO - Current package versions: 06:25:21 INFO - blessings == 1.6 06:25:21 INFO - blobuploader == 1.2.4 06:25:21 INFO - docopt == 0.6.1 06:25:21 INFO - functools32 == 3.2.3-2 06:25:21 INFO - jsonschema == 2.5.1 06:25:21 INFO - manifestparser == 1.1 06:25:21 INFO - mozInstall == 1.12 06:25:21 INFO - mozcrash == 1.0 06:25:21 INFO - mozdebug == 0.1 06:25:21 INFO - mozdevice == 0.49 06:25:21 INFO - mozfile == 1.2 06:25:21 INFO - mozhttpd == 0.7 06:25:21 INFO - mozinfo == 0.9 06:25:21 INFO - mozleak == 0.1 06:25:21 INFO - mozlog == 3.4 06:25:21 INFO - moznetwork == 0.27 06:25:21 INFO - mozprocess == 0.24 06:25:21 INFO - mozprofile == 0.28 06:25:21 INFO - mozrunner == 6.13 06:25:21 INFO - mozscreenshot == 0.1 06:25:21 INFO - mozsystemmonitor == 0.3 06:25:21 INFO - moztest == 0.8 06:25:21 INFO - mozversion == 1.4 06:25:21 INFO - psutil == 3.1.1 06:25:21 INFO - requests == 1.2.3 06:25:21 INFO - wsgiref == 0.1.2 06:25:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:25:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 06:25:21 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:21 INFO - 'HOME': '/Users/cltbld', 06:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:21 INFO - 'LOGNAME': 'cltbld', 06:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:21 INFO - 'MOZ_NO_REMOTE': '1', 06:25:21 INFO - 'NO_EM_RESTART': '1', 06:25:21 INFO - 'PAGER': '/bin/cat', 06:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:21 INFO - 'PWD': '/builds/slave/test', 06:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:21 INFO - 'SHELL': '/bin/bash', 06:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:25:21 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:21 INFO - 'USER': 'cltbld', 06:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:21 INFO - 'XPC_FLAGS': '0x0', 06:25:21 INFO - 'XPC_SERVICE_NAME': '0', 06:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 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)) 06:25:22 INFO - Cleaning up... 06:25:22 INFO - Return code: 0 06:25:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:25:22 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x10bc2db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc30768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb9a2c60280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, '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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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 06:25:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:25:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:25:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:22 INFO - 'HOME': '/Users/cltbld', 06:25:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:22 INFO - 'LOGNAME': 'cltbld', 06:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:22 INFO - 'MOZ_NO_REMOTE': '1', 06:25:22 INFO - 'NO_EM_RESTART': '1', 06:25:22 INFO - 'PAGER': '/bin/cat', 06:25:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:22 INFO - 'PWD': '/builds/slave/test', 06:25:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:22 INFO - 'SHELL': '/bin/bash', 06:25:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:25:22 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:25:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:22 INFO - 'USER': 'cltbld', 06:25:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:22 INFO - 'XPC_FLAGS': '0x0', 06:25:22 INFO - 'XPC_SERVICE_NAME': '0', 06:25:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:25:22 INFO - Cleaning up... 06:25:22 INFO - Return code: 0 06:25:22 INFO - Running post-action listener: _resource_record_post_action 06:25:22 INFO - Running post-action listener: _start_resource_monitoring 06:25:22 INFO - Starting resource monitoring. 06:25:22 INFO - [mozharness: 2017-02-02 14:25:22.720331Z] Finished create-virtualenv step (success) 06:25:22 INFO - [mozharness: 2017-02-02 14:25:22.721321Z] Running install step. 06:25:22 INFO - Running pre-action listener: _resource_record_pre_action 06:25:22 INFO - Running main action method: install 06:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:25:22 INFO - Reading from file tmpfile_stdout 06:25:22 INFO - Detecting whether we're running mozinstall >=1.0... 06:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:25:23 INFO - Reading from file tmpfile_stdout 06:25:23 INFO - Output received: 06:25:23 INFO - Usage: mozinstall [options] installer 06:25:23 INFO - Options: 06:25:23 INFO - -h, --help show this help message and exit 06:25:23 INFO - -d DEST, --destination=DEST 06:25:23 INFO - Directory to install application into. [default: 06:25:23 INFO - "/builds/slave/test"] 06:25:23 INFO - --app=APP Application being installed. [default: firefox] 06:25:23 INFO - mkdir: /builds/slave/test/build/application 06:25:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:25:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:25:44 INFO - Reading from file tmpfile_stdout 06:25:44 INFO - Output received: 06:25:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:25:44 INFO - Running post-action listener: _resource_record_post_action 06:25:44 INFO - [mozharness: 2017-02-02 14:25:44.838992Z] Finished install step (success) 06:25:44 INFO - [mozharness: 2017-02-02 14:25:44.839150Z] Running run-tests step. 06:25:44 INFO - Running pre-action listener: _resource_record_pre_action 06:25:44 INFO - Running main action method: run_tests 06:25:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:25:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:25:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:25:44 INFO - 2017-02-02 06:25:44.853 screenresolution[1625:7399] starting screenresolution argv=screenresolution get 06:25:44 INFO - 2017-02-02 06:25:44.856 screenresolution[1625:7399] Display 0: 1600x1200x32@60 06:25:44 INFO - 2017-02-02 06:25:44.862 screenresolution[1626:7401] starting screenresolution argv=screenresolution list 06:25:44 INFO - Available Modes on Display 0 06:25:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:25:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:25:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:25:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:25:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:25:45 INFO - Intel Iris: 06:25:45 INFO - Chipset Model: Intel Iris 06:25:45 INFO - Type: GPU 06:25:45 INFO - Bus: Built-In 06:25:45 INFO - VRAM (Dynamic, Max): 1536 MB 06:25:45 INFO - Vendor: Intel (0x8086) 06:25:45 INFO - Device ID: 0x0a2e 06:25:45 INFO - Revision ID: 0x0009 06:25:45 INFO - Displays: 06:25:45 INFO - Display: 06:25:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:25:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:25:45 INFO - Display Serial Number: 1600x1200 60 06:25:45 INFO - Main Display: Yes 06:25:45 INFO - Mirror: Off 06:25:45 INFO - Online: Yes 06:25:45 INFO - Rotation: Supported 06:25:45 INFO - Return code: 0 06:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:25:45 INFO - Python 2.7.3 06:25:45 INFO - Return code: 0 06:25:45 INFO - grabbing minidump binary from tooltool 06:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 06:25:45 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 0x10ba0bd30>, '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 0x10b932db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba00be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:25:45 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 06:25:45 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 06:25:45 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 06:25:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:25:45 INFO - Return code: 0 06:25:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:25:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:25:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:25:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:25:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 06:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0230 --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 06:25:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mT1OUCNHSR/Render', 06:25:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:45 INFO - 'HOME': '/Users/cltbld', 06:25:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:45 INFO - 'LOGNAME': 'cltbld', 06:25:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:25:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:45 INFO - 'MOZ_NO_REMOTE': '1', 06:25:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:25:45 INFO - 'NO_EM_RESTART': '1', 06:25:45 INFO - 'PAGER': '/bin/cat', 06:25:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:45 INFO - 'PWD': '/builds/slave/test', 06:25:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:25:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:45 INFO - 'SHELL': '/bin/bash', 06:25:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mnTR0xtAX4/Listeners', 06:25:45 INFO - 'TMPDIR': '/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/', 06:25:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:45 INFO - 'USER': 'cltbld', 06:25:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:45 INFO - 'XPC_FLAGS': '0x0', 06:25:45 INFO - 'XPC_SERVICE_NAME': '0', 06:25:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:25:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0230', '--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 06:25:45 INFO - mozversion application_buildid: 20170202052009 06:25:45 INFO - mozversion application_changeset: dd7cce059626e9787537bf9ba7863a6ad8ac06a1 06:25:45 INFO - mozversion application_display_name: Nightly 06:25:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:25:45 INFO - mozversion application_name: Firefox 06:25:45 INFO - mozversion application_remotingname: firefox 06:25:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 06:25:45 INFO - mozversion application_vendor: Mozilla 06:25:45 INFO - mozversion application_version: 54.0a1 06:25:45 INFO - mozversion platform_buildid: 20170202052009 06:25:45 INFO - mozversion platform_changeset: dd7cce059626e9787537bf9ba7863a6ad8ac06a1 06:25:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 06:25:45 INFO - mozversion platform_version: 54.0a1 06:25:45 INFO - using testdate: 1486045545 06:25:45 INFO - actual date: 1486045545 06:25:45 INFO - starting webserver on 'localhost:49243' 06:25:45 INFO - SUITE-START | Running 2 tests 06:25:45 INFO - TEST-START | dromaeo_css 06:25:45 INFO - operating with platform_type : mac_ 06:25:45 INFO - Initialising browser for dromaeo_css test... 06:25:45 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpcpHUtU/profile http://localhost:49243/getInfo.html) 06:25:48 INFO - PROCESS | 1636 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:25:48 INFO - PROCESS | 1636 | 2017-02-02 06:25:48.526 plugin-container[1638:7568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 06:25:48 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:48 INFO - PROCESS | 1636 | 2017-02-02 06:25:48.531 plugin-container[1638:7568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 06:25:48 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:48 INFO - PROCESS | 1636 | 2017-02-02 06:25:48.531 plugin-container[1638:7568] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:25:48 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 06:25:48 INFO - PROCESS | 1636 | colorDepth:24 06:25:48 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 06:25:48 INFO - PROCESS | 1636 | __metrics 06:25:48 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3281: TypeError: this.tabs is undefined 06:25:48 INFO - PROCESS | 1636 | 1486045548680 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpcpHUtU/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpcpHUtU/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpcpHUtU/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpcpHUtU/profile/extensions/talos-powers@TalosPowersService.js:55:9 06:25:49 INFO - TEST-INFO | 1636: exit 0 06:25:49 INFO - Browser initialized. 06:25:49 INFO - Running cycle 1/1 for dromaeo_css test... 06:25:49 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmpcpHUtU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:25:51 INFO - PROCESS | 1639 | 2017-02-02 06:25:51.756 plugin-container[1640:7687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 06:25:51 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:51 INFO - PROCESS | 1639 | 2017-02-02 06:25:51.756 plugin-container[1640:7687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 06:25:51 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:51 INFO - PROCESS | 1639 | 2017-02-02 06:25:51.756 plugin-container[1640:7687] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:26:01 INFO - PROCESS | 1639 | RSS: Main: 234803200 06:26:01 INFO - PROCESS | 1639 | 06:29:03 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 06:29:05 INFO - PROCESS | 1639 | RSS: Main: 258723840 06:29:05 INFO - PROCESS | 1639 | 06:30:49 INFO - PROCESS | 1639 | ************************* 06:30:49 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 06:30:49 INFO - PROCESS | 1639 | 06:30:49 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 06:30:49 INFO - PROCESS | 1639 | Full stack: 06:30:49 INFO - PROCESS | 1639 | ************************* 06:32:07 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 06:32:08 INFO - PROCESS | 1639 | RSS: Main: 265396224 06:32:08 INFO - PROCESS | 1639 | 06:35:10 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 06:35:10 INFO - PROCESS | 1639 | RSS: Main: 276180992 06:35:10 INFO - PROCESS | 1639 | 06:38:12 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 06:38:13 INFO - PROCESS | 1639 | RSS: Main: 269160448 06:38:13 INFO - PROCESS | 1639 | 06:41:16 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 06:41:16 INFO - PROCESS | 1639 | RSS: Main: 274186240 06:41:16 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 06:44:20 INFO - PROCESS | 1639 | RSS: Main: 276004864 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | __start_tp_report 06:44:20 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 06:44:20 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 06:44:20 INFO - PROCESS | 1639 | |i|pagename|runs| 06:44:20 INFO - PROCESS | 1639 | |0;dojo.html;2874.9425011499784;3354.4297469430194;3359.748018898582;3373.7975721456714;3378.3750006248847;5525.778968841241;5571.721413929307;5601.887962240753;5664.5468362531;5746.885062298751;4909.582685471739;4955.207166853304;5518.806841760529;5607.551395888329;5632.915506267409;4326.913461730764;5074.137396642571;5156.742162891859;5181.922271165936;5283.233931079991;5183.766730497136;5222.791088356461;5236.109861323565;5259.237410575464;5281.18141688037;5059.949400505985;5170.146925757245;5192.792288308463;5232.529072383485;5244.79020839166;5020.447750747415;5168.8449346519665;5181.5077567631015;5239.633225674204;5276.657017293883;5027.4469808321055;5061.822836200752;5065.189569668835;5152.845414637567;5208.791648334062;4391.912161756779;5453.536449401806;5540.279763630702;5554.666719996813;5590.440955904418;6194.1018552310115;6225.159603453566;6239.2824825145235;6242.687865606701;6277.278113017016;6050.878982420371;6073.51411887048;6083.8174854754425;6126.111713801519;6144.156752974134;4170.582941705866;4894.951050489521;5654.293213348332;5660.235868157787;5689.857753556153;6196.256449226032;6201.286852011986;6210.037444196157;6216.191895053614;6228.813135605939;4401.317795741666;5086.796528138833;5915.40845915405;5997.160397544348;6000.549958753111;6070.878582428327;6148.831721972811;6163.445289923929;6176.66028368444;6188.721507532172;6106.45041946218;6109.114178444101;6112.255355561532;6115.6330620162935;6150.261968563801;5254.737263136828;6089;6101.633901965896;6110.480609148272;6112.144299798032;4192.790360481964;4921.827736029293;4928.162212423897;4930.6795058321095;4947.381577302837;4368.912621747547;4409.470863496401;4415.315626077963;4450.154470650566;4470.083632855257;5799.797007104731;5804.854878628068;5817.476427121579;5821.388754180834;5847.152162936435;5721.542276617944;6150.50795936324;6154.015357542772;6185.721642526097;6217.378262173836;4893.584045356185;5877.177195192677;5886.440788125121;5894.4400281974;5919.052951527735;5425.430329815313;5436.483534064258;5436.70098144598;5463.344398672185;5465.836024919259;6114.2357205349335;6205.627662340274;6226.221722284715;6372.394622510854;6373.235211774524;5654.604177707521;6134.681512609229;6213.440790328892;6236.438720515175;6243.8126856194385;5937.3468918418075;6276.843078923063;6277.246730392382;6331.90502142459;6335.176427064452;5967.731452084753;6071.5446341523675;6191.442770150528;6204.13142160089;6206.658633775005;5399.0281749285505;5551.139573366135;6161.6919154041225;6264.123022776906;6270.655113968775;6086.817395478142;6094.116353128683;6097.969510152421;6109.7861574844665;6110.869489144522;5858.5898987070495;6089.293075788369;6105.823680184288;6110.953680641429;6138.296437970319;5035.672681275705;5529.253550770594;5784.768609255582;5841.094630332305;6024.3975602439405;6101.206843110367;6110.1445797587485;6130.478909292582;6134.110553969738;6143.754249829957;5692.772289108555;5752.079667253387;5882.823515294548;5950.494207992369;5978.103284507359 06:44:20 INFO - PROCESS | 1639 | |1;ext.html;460.3807878403548;476.38546275304856;487.3883276488007;499.11335153121195;512.2879197914898;40136.39795403071;42704.359434608465;42779;42872.9281767956;42986;43403.34894976578;43507.56492435075;43781;43800.342994855106;43896;8362.289205417546;8566.957165214166;9405.764855878606;9615.51922403877;11304.434778261095;11234.49444774987;11425.942870285679;11483;11495.827562586526;11522.539098436053;10157.136643385322;11479.59821406251;11523.07815374768;11530.538778448852;11552.133589980744;10071.395716257048;10827.350358978447;10976.506057227443;11429.5428182873;11532.769344613062;11426.828597571042;11518.308901465938;11520.769584608344;11527.308361498253;11531.827022594667;29539.40921181586;36289.45565816515;36407;36576.45135322946;36596.63403365986;3545.964540354589;4039.7980100994832;4074.8573799916862;4160.313548264533;4247.851325203603;31161.376772464424;31416;31465.68534314673;31474.685253147632;32255;3087.3516561522106;3190.6490286068515;3235.352929414103;3265.379577880219;3292.8024318540965;397538.9999999971;454210;463546;465051;466025;53033.46966530362;64344;64588.35411645917;64628.35371646223;64785;67471;67597;67695.66152169208;67870.66064669743;67912;16330.75503867443;16596.336146554244;16596.751048734277;16598.58503537397;16627.50117496477;11228.66314010581;11573.76852462946;12512.49950002008;12629.43167557462;12749.107562470537;15623;18009.54976125581;18467.35364262271;18492.89042657445;18522.444326670222;7439.7768066957;7538.962305188439;7562.584057876869;7588.962055189799;7594.354479869163;843.8818565400849;856.9160012584086;858.2704701004096;860.819227962135;864.5504337744463;29984.55023174654;30231.244218894702;30413.69586304153;30976.69023309783;31068;1008.621766837436;1038.3562191441354;1042.6559235452282;1075.93544387337;1186.7211205366732;14064.929675351557;14717.55847324582;15360.539183824503;16043.197840107949;16179.595510112105;565729;567991;574006;574113;574322;60017;60655.69672151611;60781.17656470313;60843.695781521696;60966;1359.6261028217123;1408.478862820763;1420.850810664886;1430.6780974280703;1433.849445808175;27495;29609.9636512721;30092.54861177128;30682;30688.77244910268;52251;52352.69118272074;52650;52699.47300527099;52726.47273527369;488845;490730;491740;496704;497442;26100.34749131211;26868.731312687403;26895.731042690106;27014.784334704593;41399.1720165601;40706.38940415837;40752.79623601863;42106.789466052476;43350.56649433466;43627.781861090494;35988.82005589955;36050.63949360473;36197.91406257711;37042.07394815151;37490;35656.82171589125;35800;36201.818990904874;36662.8166859164;36695.81652091722;34854.651453486156;35516.82241588776;35904;35932.820335898155;36198.63801362058;26512.602310964965;26736.331591710365;27714.3071423208;28854.12448190861;30119;23502.17742379077;23859.045638174972;24120.87939560291;24522.87738561296;24693.382665433506 06:44:20 INFO - PROCESS | 1639 | |2;jquery.html;378.13973210945096;393.45310019073474;395.436502983249;400.49537582645877;428.5136370219802;13863.653408664763;18879.867207967498;19885.801141988595;20915;21752;21577.67633485495;21953.121875125016;22499;22538.88730556345;22768.203112891053;5861.8827623447505;6111.449969502743;6135.938640613582;6144.293406258271;6150.630962142263;5845.269341332333;6031.185789918349;6038;6122.755089796403;6137.17148184996;6028;6035.788747393842;6037.154798328215;6047.304559975615;6054.969725151368;6005.159277701126;6006.369331220225;6558.442532384729;7788.415868809805;7852.21477852216;7463.477556571042;7687.577183254919;7716.189800071024;7748.7675369739;7759.224077592252;29694.851525742448;30081.548776768366;30183.189008659552;30194.24514387158;30674.38651226963;2600.9479810403686;2873.324768679359;2973;2987.1337312179444;3000.939981200386;13684.726305473934;16800.991940483487;17024.829751702448;17200.39798607055;18366.449006529827;1714.9485515434558;1903.3433465454395;2016.1070499318548;2044.9591008179902;2065.5868826234664;144120;146949;147026.26486867605;152504.237478813;152699;24259.636105458434;24309.7569024311;24573.262802115965;25289.988400463775;25451.87274063618;12124.333161676017;12722.427490762939;41073;41075;41152.79423602923;8264.83470330602;8293.465708843883;8410.201030902055;8502.532360720095;8568.657253709907;8560.571971401405;8681.435706679045;8688;8817.955910220408;8866.93596768392;4089.5297040840087;4121.773302468393;4161.500619925629;4211.873643790691;4226.661867050629;1782.287085165944;1934.8742331748392;2052.640787862118;2092.3618296419736;2109.303929703194;796.665400531778;957.7366224288237;964.5948701545368;966.5602151021268;984.7538115471132;21056.26303079385;21544.676829847565;21661.91690415473;21884.45288867759;21936;958.065885761391;971.9173870220969;999.4053538144792;1000.314784372707;1007.219404961161;10522.263441559171;10859.945700271448;11141.836042007733;11226.754649070219;11251.83122253167;689331;694447;740741;757007;758968;159510.20244898932;159946;160544.19727901285;161093;163913.3608663922;967.066780556766;978.4569563891966;1019.9694009179885;1044.9111825494765;1050.658535975811;17812.109394530482;19701.90149049303;19977.900110499937;20259.79740202579;20325.695114572998;47121;51250;57501;58433;61133;87846.56076719576;89103.55448222718;90252;90733.54633226791;91112;6881.862362752817;8130.634121464666;8259.114149022487;8302.54336011525;8664.78338041554;9420.57607407682;12394.566190183301;13267.137636053625;13422.060455768393;13467.057305988486;11107;11192.608258710907;11519.020883224684;11573.537058517564;11667.299962002307;11059.225854190452;11072.391018493738;11220.0462960646;11554.42227888619;11718.23831450953;11488.19582629208;11563.074954003827;11811.527538898348;11867.584634538096;11905.583304584297;7908.446408751563;8438.029626593021;8550.78623034429;8554.871676924975;8633.784155396166;9580.568874400808;9630.518474076129;11167.553297867995;11288.199154232669;11606.187566870238 06:44:20 INFO - PROCESS | 1639 | |3;mootools.html;1215.8601760797508;1227.5703503773675;1417.4471955937186;1429.5425463851566;1441.9639509012277;7399.963000184992;7677.4241931855195;7906.565138917357;8360.122187170351;8390.035145958216;7849;8056.154103819088;8116.634751436185;8232.670693172266;8258.463199892018;2211.889405529725;2249.3701763506247;2259.197984715427;2259.243153543565;2260.0394832196334;2044.631966246075;2102.127617038927;2246.4608493961414;2272.545490901818;2297.1844995026777;2138.4974530985205;2223.0774228695072;2230.6765518999737;2237.2169740590743;2237.407087121914;2262.570111678784;2267.1384873748034;2292.873891935943;2295.403195169268;2299.4596269876565;2245.438640339915;2288.359259407352;2311.1217737259735;2316.525112351964;2321.199186280747;6951.6871740771785;8163.795905102361;8360;8371.916280837175;8381.832363352698;3834.731568790186;4266.5946735060115;4513.645906228151;4980.203167493184;5160.509751573594;3000.339925216449;3089.8609562569736;3096.427160975204;3155.7002084801907;3300.8349582520777;1057.1173070486154;1121.9551217951273;1136.8067428537086;1138.971525711864;1142.9028532574741;8322.62548185333;10517.053465188093;10554.577816887391;10565.207609429246;10582.9470852646;4361.738295702205;4561.498235194126;4613.746243956548;4618.930716039262;4645.187092258841;9138.268938484905;9294.024127466653;9294.674686385944;9297.674581389754;9300.72097837066;5110.4123025852505;5176.094183517945;5183.144781111075;5207.3230480037355;5213.817516386908;3768.9459244711697;4623.930641040387;4718.787654555554;4748.264019077049;4766.094442055998;3784.6972242220554;3789.0716774390453;3838.136419305634;3878.26313000529;3892.902677433087;1177.7879981603394;1226.7975783995719;1277.8083287506947;1283.4609464025132;1293.5666551705285;758.6699785593174;761.6610608279374;788.2984144111745;807.1000834070087;835.2315869400167;5657.547396208293;5670.829875103754;5739.913901291484;5744.396838331999;5778.94221057784;1001.4441984698562;1004.0963133180196;1011.858339832424;1028.0798685176726;1069.5400977579716;3828.980855095707;3830.195658911604;3844.7116466265143;3894.3184942634925;3947.4670919426085;8083.595820209083;9339.766505837273;9815.460149691835;9824.01759824012;9831.31180817336;9239.72280831576;9648.517574121266;9667.226621870233;9749.561269742879;9774.364666296668;7526.661300241502;7723.382129429632;7725.613719314012;7725.652345644459;7728.729494467667;4343.847965321325;5414.052540805422;5616.803411880564;5654.434556544313;5941.613795103304;7316.268373162854;7395.7041718330665;9050.683226087056;9052.864207036768;9076.455412675261;8211.260986511243;8271.33829293667;8344;8455.069942306465;8463.873041904499;6949.826254343682;7070.717171313296;7082.716691332289;7084.079069720903;7087.503874728925;7418.480706350504;7576.204498527575;7689.307962283421;7694.269044440769;7707.383409327353;6442.935570644234;6978.151727422354;7043.2604576518725;7075.964620176866;7149.070620819468;6949.895751563629;6963.234044255027;6965.616891071039;7063.081799366049;7073.610951397723;6307.274663413767;6325.05124231369;6384.872302554016;6406.519511036784;6427.775027874002;4844.2007068833245;4887.853364399074;4969.00619876019;4969.726665033458;5089.13484707608;4947.579455746302;5022.422421421583;5063.493650634907;5138.306328645585;5155.355580552431 06:44:20 INFO - PROCESS | 1639 | |4;prototype.html;328.4334522947915;352.8641473032883;366.10669965284706;367.8859553538405;375.30755755630867;5100;5149.356330458692;5288.867778305545;5399.3790714067845;5437.728113594319;5145.871353216172;5299.814506492264;5340.279062326585;5348.277982472365;5360.731963401824;3969.2458432897656;3973.483447151866;4200.62194402503;4224.493060832704;4226.72526285792;3375.0549846043145;3845.3078445879732;4258.679809259124;4273.226610956449;4274.615284624399;3447.4828775683723;3634.4184930411006;3662.890113296592;4002.3584434273384;4026.1545075534027;3826.2730081284744;4044.090079732037;4051.6150965658217;4059.6549293310104;4060.593940605945;3512.3150985557827;3516.244007538366;3529.6117427083;3531.5055892175114;3537.8407971641336;4887.511248875119;5058.392992840846;5131.358580177478;5175.715335656538;5230;3919.960800391988;3962.722609417342;4097.660406768504;4609.216433206363;4665.88335291617;5017.473165317626;5533.806316778933;5650.660960342392;5658.151277308396;5681.659100453986;3523.5947865995226;3734.1917223921937;3843.730938834283;3865.40086286626;3873.341531939577;5807.709614519299;5912;5940.198073260098;5947.732352044125;5961.552883533709;3169.429502689492;3381.0871064812363;3451.9633416284514;3906.824192911326;3914.3541315683165;3350.564426624523;3642.852501462031;3764.4917936078277;3771.698264138862;3866.284737323604;4594.88512787183;4610.907781844345;4612.769361531977;4621.768911554409;4673.532646735299;4535;5729.799457019068;5730.389854391707;5750.453706897838;5782.306123265151;1164.4280221700806;1211.7091897944606;1215.4469716279248;1228.9738068712547;1279.6544932868073;753.6608526163249;846.817934144162;860.8880845490171;880.7449384626881;881.3698205782767;4300.462442194726;4322.956770432318;4488.5511448855505;4598.218302888517;4704.929426058612;5516.696976358447;5530.672638566795;5574.498295153456;5578.191162281528;5598.860028499239;4412.492563355191;4555.703879247838;4664.743439110882;4839.644899428165;5055.6713813602;2668.9332766680986;2766.7964435470635;2820.2373516552157;2882.855857207131;2899.941521344736;4902.48523904992;5083.644794435655;5128.640995130379;5190.870228244248;5198.532132108128;4649.95350046502;4780.641451891122;4832.758362081881;5200.76596553156;5461.2354270402175;4633.976830115827;5139.408967968731;5256.4493474425135;5631.493165615032;5804.448577385142;4789.329493870791;4826.831060912851;4967.9815637793445;5573.024720673785;5618.892915156816;4942.0841703393735;5109.616778741535;5429.484199001088;5468.890622187454;5486.177073439016;5029.698218106925;5113.139579617514;5340.919886201787;5486.670799752028;5742.655440673573;3701.3707669695677;3710.6289371062676;3711.9072023199637;3718.2749363873786;3750.868719594801;4558.703684260512;4675.9064818702755;5478.424765399575;5538.113901775697;5582.525485333792;4552.476465206543;4762.95237047639;5107.463716309734;5186.870328241674;5336.279602253646;4277.893052673708;4596.563326483979;4935.876603084952;4947.678400903929;5331.866703332447;4780.378550788375;5206.947930520798;5221.608379371486;5226.766319281394;5228.085085110167;5091.8247037428955;5300.535251108253;5327.9467205329;5338.225957236256;5351.759170837244;5202.479752024768;5242;5242.554382877498;5243.408265338256;5244.868878278074 06:44:20 INFO - PROCESS | 1639 | |5;yui.html;705.6627690526452;712.4410009796067;730.6018220070063;775.8952541406912;839.5844057191689;14309.640584144516;14962.700745985101;15200.695986080273;15992.360305587792;16047.35810567576;14721.705565888677;14891.404343826234;14896.404143834261;14909.776353354708;14916.701665966673;661.6856992928344;686.6189264957947;859.2824991132412;868.7046404222563;878.3412440669498;858.0347109501811;861.1732736572898;862.9870551941726;873.5151990645189;875.9824803503926;931.6413180925325;933.2813733425293;934.2385955446324;940.5908429833011;941.4868896451417;918.0406475233364;925.0379605210572;925.9814803703888;926.3422969691527;926.4163576946548;680.6970897950414;683.2006552333781;690.433844247717;691.2223748283182;691.4364792693989;13310.800337994939;14527.70944581113;15896.523104306889;16071.437499687454;16358.75461868061;14586.34361453737;14816.407343706347;14819.629509262248;14915.477958271409;15241.695166096726;11686.744584945527;11744.530218791324;11744.588939387166;11800.350980696057;12230.021598272117;3303.438415469376;3725.497057897176;3739.439084137374;3774.8490060397553;3916.2167566486814;81758.59120704418;98482;98947.50526247395;99235.50382248114;101004;10613.734656633645;11210.27133236337;11285.887141128484;11387.715307117389;11651.951324380847;23859.761402386102;23950.52098958011;24146.51706965886;24196.879015605162;24202.87898560496;7915.082951745333;7915.841683166304;7941.6029198541;7953.602319883983;8115.715949941724;4287.442632457823;4297.09760950204;4357.564243575681;4391.692581519263;4486.618637415856;3606.549181352331;3624.9637503624626;3701.0747313171705;3703.851845926187;3722.4602432647116;667.1227336053172;671.3152584364019;674.6660403100497;717.5120917775962;720.86900907779;669.9464042876558;670.9396154346034;710.6840830587133;721.942244620429;730.7113690092384;9537.713868583953;10221.284510084222;10276.691699249035;10361.067503924533;10426.68719938403;685.715428097334;693.6150436507787;702.2977022977022;725.793148952546;726.1177669131924;4606.744917499235;5024.799008039638;5039.521245481674;5078.695278283315;5105.744712764347;101607;106061;106273;107596.46201769098;110675;73246;73308.63345683344;73550;79469.41061178745;82251.7662235055;61103.38896610977;64825.67587162034;68583.999999999;72174.91737623939;73598.6320068407;8481.703140390056;10554.947225263824;10735.785284294427;10788;10868.130549555859;36004.639953600126;44767.88080298019;44836.77581612071;48509.5149048505;48584;47881.76059119682;61872.38127618846;64533.67733161492;64823;65060;9884.456354900549;10631;12927.870721292667;13064.673383165116;13134.802977955529;11679.416029198675;14641.414343426144;14837.92581037088;14989.971182703928;15175.924120379328;13178.143420677625;13293.534726284533;14102.576922692335;14111.576652700436;14116.647083822987;12216.816747748613;13363.465461381436;13777.173369597856;13962.790558141432;14000.089994150349;13208.603741887759;13280.667983300495;13933.303334833015;13971.510997115052;13985.930070349583;11061.85664462637;11183.161262905478;11238;11407.600733974272;11453.770924581628;6832.760853370109;9514.571844266886;10185.33795303303;10394.792104157723;10678.077290076471 06:44:20 INFO - PROCESS | 1639 | __end_tp_report 06:44:20 INFO - PROCESS | 1639 | __start_cc_report 06:44:20 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,398 06:44:20 INFO - PROCESS | 1639 | __end_cc_report 06:44:20 INFO - PROCESS | 1639 | __startTimestamp1486046660263__endTimestamp 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | ------- Summary: start ------- 06:44:20 INFO - PROCESS | 1639 | Number of tests: 6 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5616.22 Median:5841.09 stddev:681.37 (11.7%) stddev-sans-first:648.64 06:44:20 INFO - PROCESS | 1639 | Values: 2874.9 3354.4 3359.7 3373.8 3378.4 5525.8 5571.7 5601.9 5664.5 5746.9 4909.6 4955.2 5518.8 5607.6 5632.9 4326.9 5074.1 5156.7 5181.9 5283.2 5183.8 5222.8 5236.1 5259.2 5281.2 5059.9 5170.1 5192.8 5232.5 5244.8 5020.4 5168.8 5181.5 5239.6 5276.7 5027.4 5061.8 5065.2 5152.8 5208.8 4391.9 5453.5 5540.3 5554.7 5590.4 6194.1 6225.2 6239.3 6242.7 6277.3 6050.9 6073.5 6083.8 6126.1 6144.2 4170.6 4895.0 5654.3 5660.2 5689.9 6196.3 6201.3 6210.0 6216.2 6228.8 4401.3 5086.8 5915.4 5997.2 6000.5 6070.9 6148.8 6163.4 6176.7 6188.7 6106.5 6109.1 6112.3 6115.6 6150.3 5254.7 6089.0 6101.6 6110.5 6112.1 4192.8 4921.8 4928.2 4930.7 4947.4 4368.9 4409.5 4415.3 4450.2 4470.1 5799.8 5804.9 5817.5 5821.4 5847.2 5721.5 6150.5 6154.0 6185.7 6217.4 4893.6 5877.2 5886.4 5894.4 5919.1 5425.4 5436.5 5436.7 5463.3 5465.8 6114.2 6205.6 6226.2 6372.4 6373.2 5654.6 6134.7 6213.4 6236.4 6243.8 5937.3 6276.8 6277.2 6331.9 6335.2 5967.7 6071.5 6191.4 6204.1 6206.7 5399.0 5551.1 6161.7 6264.1 6270.7 6086.8 6094.1 6098.0 6109.8 6110.9 5858.6 6089.3 6105.8 6111.0 6138.3 5035.7 5529.3 5784.8 5841.1 6024.4 6101.2 6110.1 6130.5 6134.1 6143.8 5692.8 5752.1 5882.8 5950.5 5978.1 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:64915.23 Median:35852.00 stddev:135016.45 (376.6%) stddev-sans-first:135308.48 06:44:20 INFO - PROCESS | 1639 | Values: 460.4 476.4 487.4 499.1 512.3 40136.4 42704.4 42779.0 42872.9 42986.0 43403.3 43507.6 43781.0 43800.3 43896.0 8362.3 8567.0 9405.8 9615.5 11304.4 11234.5 11425.9 11483.0 11495.8 11522.5 10157.1 11479.6 11523.1 11530.5 11552.1 10071.4 10827.4 10976.5 11429.5 11532.8 11426.8 11518.3 11520.8 11527.3 11531.8 29539.4 36289.5 36407.0 36576.5 36596.6 3546.0 4039.8 4074.9 4160.3 4247.9 31161.4 31416.0 31465.7 31474.7 32255.0 3087.4 3190.6 3235.4 3265.4 3292.8 397539.0 454210.0 463546.0 465051.0 466025.0 53033.5 64344.0 64588.4 64628.4 64785.0 67471.0 67597.0 67695.7 67870.7 67912.0 16330.8 16596.3 16596.8 16598.6 16627.5 11228.7 11573.8 12512.5 12629.4 12749.1 15623.0 18009.5 18467.4 18492.9 18522.4 7439.8 7539.0 7562.6 7589.0 7594.4 843.9 856.9 858.3 860.8 864.6 29984.6 30231.2 30413.7 30976.7 31068.0 1008.6 1038.4 1042.7 1075.9 1186.7 14064.9 14717.6 15360.5 16043.2 16179.6 565729.0 567991.0 574006.0 574113.0 574322.0 60017.0 60655.7 60781.2 60843.7 60966.0 1359.6 1408.5 1420.9 1430.7 1433.8 27495.0 29610.0 30092.5 30682.0 30688.8 52251.0 52352.7 52650.0 52699.5 52726.5 488845.0 490730.0 491740.0 496704.0 497442.0 26100.3 26868.7 26895.7 27014.8 41399.2 40706.4 40752.8 42106.8 43350.6 43627.8 35988.8 36050.6 36197.9 37042.1 37490.0 35656.8 35800.0 36201.8 36662.8 36695.8 34854.7 35516.8 35904.0 35932.8 36198.6 26512.6 26736.3 27714.3 28854.1 30119.0 23502.2 23859.0 24120.9 24522.9 24693.4 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:42097.54 Median:30132.37 stddev:121940.74 (404.7%) stddev-sans-first:122242.58 06:44:20 INFO - PROCESS | 1639 | Values: 378.1 393.5 395.4 400.5 428.5 13863.7 18879.9 19885.8 20915.0 21752.0 21577.7 21953.1 22499.0 22538.9 22768.2 5861.9 6111.4 6135.9 6144.3 6150.6 5845.3 6031.2 6038.0 6122.8 6137.2 6028.0 6035.8 6037.2 6047.3 6055.0 6005.2 6006.4 6558.4 7788.4 7852.2 7463.5 7687.6 7716.2 7748.8 7759.2 29694.9 30081.5 30183.2 30194.2 30674.4 2600.9 2873.3 2973.0 2987.1 3000.9 13684.7 16801.0 17024.8 17200.4 18366.4 1714.9 1903.3 2016.1 2045.0 2065.6 144120.0 146949.0 147026.3 152504.2 152699.0 24259.6 24309.8 24573.3 25290.0 25451.9 12124.3 12722.4 41073.0 41075.0 41152.8 8264.8 8293.5 8410.2 8502.5 8568.7 8560.6 8681.4 8688.0 8818.0 8866.9 4089.5 4121.8 4161.5 4211.9 4226.7 1782.3 1934.9 2052.6 2092.4 2109.3 796.7 957.7 964.6 966.6 984.8 21056.3 21544.7 21661.9 21884.5 21936.0 958.1 971.9 999.4 1000.3 1007.2 10522.3 10859.9 11141.8 11226.8 11251.8 689331.0 694447.0 740741.0 757007.0 758968.0 159510.2 159946.0 160544.2 161093.0 163913.4 967.1 978.5 1020.0 1044.9 1050.7 17812.1 19701.9 19977.9 20259.8 20325.7 47121.0 51250.0 57501.0 58433.0 61133.0 87846.6 89103.6 90252.0 90733.5 91112.0 6881.9 8130.6 8259.1 8302.5 8664.8 9420.6 12394.6 13267.1 13422.1 13467.1 11107.0 11192.6 11519.0 11573.5 11667.3 11059.2 11072.4 11220.0 11554.4 11718.2 11488.2 11563.1 11811.5 11867.6 11905.6 7908.4 8438.0 8550.8 8554.9 8633.8 9580.6 9630.5 11167.6 11288.2 11606.2 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5261.23 Median:5168.30 stddev:2822.21 (54.6%) stddev-sans-first:2813.75 06:44:20 INFO - PROCESS | 1639 | Values: 1215.9 1227.6 1417.4 1429.5 1442.0 7400.0 7677.4 7906.6 8360.1 8390.0 7849.0 8056.2 8116.6 8232.7 8258.5 2211.9 2249.4 2259.2 2259.2 2260.0 2044.6 2102.1 2246.5 2272.5 2297.2 2138.5 2223.1 2230.7 2237.2 2237.4 2262.6 2267.1 2292.9 2295.4 2299.5 2245.4 2288.4 2311.1 2316.5 2321.2 6951.7 8163.8 8360.0 8371.9 8381.8 3834.7 4266.6 4513.6 4980.2 5160.5 3000.3 3089.9 3096.4 3155.7 3300.8 1057.1 1122.0 1136.8 1139.0 1142.9 8322.6 10517.1 10554.6 10565.2 10582.9 4361.7 4561.5 4613.7 4618.9 4645.2 9138.3 9294.0 9294.7 9297.7 9300.7 5110.4 5176.1 5183.1 5207.3 5213.8 3768.9 4623.9 4718.8 4748.3 4766.1 3784.7 3789.1 3838.1 3878.3 3892.9 1177.8 1226.8 1277.8 1283.5 1293.6 758.7 761.7 788.3 807.1 835.2 5657.5 5670.8 5739.9 5744.4 5778.9 1001.4 1004.1 1011.9 1028.1 1069.5 3829.0 3830.2 3844.7 3894.3 3947.5 8083.6 9339.8 9815.5 9824.0 9831.3 9239.7 9648.5 9667.2 9749.6 9774.4 7526.7 7723.4 7725.6 7725.7 7728.7 4343.8 5414.1 5616.8 5654.4 5941.6 7316.3 7395.7 9050.7 9052.9 9076.5 8211.3 8271.3 8344.0 8455.1 8463.9 6949.8 7070.7 7082.7 7084.1 7087.5 7418.5 7576.2 7689.3 7694.3 7707.4 6442.9 6978.2 7043.3 7076.0 7149.1 6949.9 6963.2 6965.6 7063.1 7073.6 6307.3 6325.1 6384.9 6406.5 6427.8 4844.2 4887.9 4969.0 4969.7 5089.1 4947.6 5022.4 5063.5 5138.3 5155.4 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4371.74 Median:4829.79 stddev:1320.27 (27.3%) stddev-sans-first:1288.63 06:44:20 INFO - PROCESS | 1639 | Values: 328.4 352.9 366.1 367.9 375.3 5100.0 5149.4 5288.9 5399.4 5437.7 5145.9 5299.8 5340.3 5348.3 5360.7 3969.2 3973.5 4200.6 4224.5 4226.7 3375.1 3845.3 4258.7 4273.2 4274.6 3447.5 3634.4 3662.9 4002.4 4026.2 3826.3 4044.1 4051.6 4059.7 4060.6 3512.3 3516.2 3529.6 3531.5 3537.8 4887.5 5058.4 5131.4 5175.7 5230.0 3920.0 3962.7 4097.7 4609.2 4665.9 5017.5 5533.8 5650.7 5658.2 5681.7 3523.6 3734.2 3843.7 3865.4 3873.3 5807.7 5912.0 5940.2 5947.7 5961.6 3169.4 3381.1 3452.0 3906.8 3914.4 3350.6 3642.9 3764.5 3771.7 3866.3 4594.9 4610.9 4612.8 4621.8 4673.5 4535.0 5729.8 5730.4 5750.5 5782.3 1164.4 1211.7 1215.4 1229.0 1279.7 753.7 846.8 860.9 880.7 881.4 4300.5 4323.0 4488.6 4598.2 4704.9 5516.7 5530.7 5574.5 5578.2 5598.9 4412.5 4555.7 4664.7 4839.6 5055.7 2668.9 2766.8 2820.2 2882.9 2899.9 4902.5 5083.6 5128.6 5190.9 5198.5 4650.0 4780.6 4832.8 5200.8 5461.2 4634.0 5139.4 5256.4 5631.5 5804.4 4789.3 4826.8 4968.0 5573.0 5618.9 4942.1 5109.6 5429.5 5468.9 5486.2 5029.7 5113.1 5340.9 5486.7 5742.7 3701.4 3710.6 3711.9 3718.3 3750.9 4558.7 4675.9 5478.4 5538.1 5582.5 4552.5 4763.0 5107.5 5186.9 5336.3 4277.9 4596.6 4935.9 4947.7 5331.9 4780.4 5206.9 5221.6 5226.8 5228.1 5091.8 5300.5 5327.9 5338.2 5351.8 5202.5 5242.0 5242.6 5243.4 5244.9 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:19433.45 Median:3845.53 stddev:27191.29 (707.1%) stddev-sans-first:27231.20 06:44:20 INFO - PROCESS | 1639 | Values: 705.7 712.4 730.6 775.9 839.6 14309.6 14962.7 15200.7 15992.4 16047.4 14721.7 14891.4 14896.4 14909.8 14916.7 661.7 686.6 859.3 868.7 878.3 858.0 861.2 863.0 873.5 876.0 931.6 933.3 934.2 940.6 941.5 918.0 925.0 926.0 926.3 926.4 680.7 683.2 690.4 691.2 691.4 13310.8 14527.7 15896.5 16071.4 16358.8 14586.3 14816.4 14819.6 14915.5 15241.7 11686.7 11744.5 11744.6 11800.4 12230.0 3303.4 3725.5 3739.4 3774.8 3916.2 81758.6 98482.0 98947.5 99235.5 101004.0 10613.7 11210.3 11285.9 11387.7 11652.0 23859.8 23950.5 24146.5 24196.9 24202.9 7915.1 7915.8 7941.6 7953.6 8115.7 4287.4 4297.1 4357.6 4391.7 4486.6 3606.5 3625.0 3701.1 3703.9 3722.5 667.1 671.3 674.7 717.5 720.9 669.9 670.9 710.7 721.9 730.7 9537.7 10221.3 10276.7 10361.1 10426.7 685.7 693.6 702.3 725.8 726.1 4606.7 5024.8 5039.5 5078.7 5105.7 101607.0 106061.0 106273.0 107596.5 110675.0 73246.0 73308.6 73550.0 79469.4 82251.8 61103.4 64825.7 68584.0 72174.9 73598.6 8481.7 10554.9 10735.8 10788.0 10868.1 36004.6 44767.9 44836.8 48509.5 48584.0 47881.8 61872.4 64533.7 64823.0 65060.0 9884.5 10631.0 12927.9 13064.7 13134.8 11679.4 14641.4 14837.9 14990.0 15175.9 13178.1 13293.5 14102.6 14111.6 14116.6 12216.8 13363.5 13777.2 13962.8 14000.1 13208.6 13280.7 13933.3 13971.5 13985.9 11061.9 11183.2 11238.0 11407.6 11453.8 6832.8 9514.6 10185.3 10394.8 10678.1 06:44:20 INFO - PROCESS | 1639 | -------- Summary: end -------- 06:44:20 INFO - PROCESS | 1639 | 06:44:20 INFO - TEST-INFO | 1639: exit 0 06:44:20 INFO - TEST-OK | dromaeo_css | took 1115483ms 06:44:20 INFO - TEST-START | kraken 06:44:20 INFO - operating with platform_type : mac_ 06:44:20 INFO - Initialising browser for kraken test... 06:44:20 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp7s6d4o/profile http://localhost:49243/getInfo.html) 06:44:23 INFO - PROCESS | 1652 | JavaScript error: chrome://webcompat/content/lib/ua_overrider.jsm, line 109: NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS: Component returned failure code: 0x804b0050 (NS_ERROR_INSUFFICIENT_DOMAIN_LEVELS) [nsIEffectiveTLDService.getBaseDomain] 06:44:23 INFO - PROCESS | 1652 | 2017-02-02 06:44:23.976 plugin-container[1653:9874] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 06:44:23 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:44:23 INFO - PROCESS | 1652 | 2017-02-02 06:44:23.978 plugin-container[1653:9874] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0f, name = 'com.apple.CFPasteboardClient' 06:44:23 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:44:23 INFO - PROCESS | 1652 | 2017-02-02 06:44:23.979 plugin-container[1653:9874] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:44:24 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 06:44:24 INFO - PROCESS | 1652 | colorDepth:24 06:44:24 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 06:44:24 INFO - PROCESS | 1652 | __metrics 06:44:24 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3281: TypeError: this.tabs is undefined 06:44:24 INFO - PROCESS | 1652 | 1486046664161 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < forceQuit@jar:file:///var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp7s6d4o/profile/extensions/talos-powers@TalosPowersService.js:238:7 < whenDone@jar:file:///var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp7s6d4o/profile/extensions/talos-powers@TalosPowersService.js:223:9 < promise callback*forceQuit@jar:file:///var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp7s6d4o/profile/extensions/talos-powers@TalosPowersService.js:225:7 < receiveMessage@jar:file:///var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp7s6d4o/profile/extensions/talos-powers@TalosPowersService.js:55:9 06:44:24 INFO - TEST-INFO | 1652: exit 0 06:44:24 INFO - Browser initialized. 06:44:24 INFO - Running cycle 1/1 for kraken test... 06:44:24 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/tmp7s6d4o/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:44:27 INFO - PROCESS | 1654 | 2017-02-02 06:44:27.313 plugin-container[1655:9995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 06:44:27 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:44:27 INFO - PROCESS | 1654 | 2017-02-02 06:44:27.313 plugin-container[1655:9995] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 06:44:27 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:44:27 INFO - PROCESS | 1654 | 2017-02-02 06:44:27.313 plugin-container[1655:9995] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:44:37 INFO - PROCESS | 1654 | RSS: Main: 233984000 06:44:37 INFO - PROCESS | 1654 | 06:44:40 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 06:44:41 INFO - PROCESS | 1654 | RSS: Main: 247599104 06:44:41 INFO - PROCESS | 1654 | 06:44:43 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 06:44:44 INFO - PROCESS | 1654 | RSS: Main: 256471040 06:44:44 INFO - PROCESS | 1654 | 06:44:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 06:44:48 INFO - PROCESS | 1654 | RSS: Main: 258392064 06:44:48 INFO - PROCESS | 1654 | 06:44:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 06:44:50 INFO - PROCESS | 1654 | RSS: Main: 260886528 06:44:50 INFO - PROCESS | 1654 | 06:44:52 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:44:53 INFO - PROCESS | 1654 | RSS: Main: 261050368 06:44:53 INFO - PROCESS | 1654 | 06:44:56 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 06:44:57 INFO - PROCESS | 1654 | RSS: Main: 266543104 06:44:57 INFO - PROCESS | 1654 | 06:45:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 06:45:00 INFO - PROCESS | 1654 | RSS: Main: 265838592 06:45:00 INFO - PROCESS | 1654 | 06:45:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 06:45:04 INFO - PROCESS | 1654 | RSS: Main: 265785344 06:45:04 INFO - PROCESS | 1654 | 06:45:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:45:06 INFO - PROCESS | 1654 | RSS: Main: 265314304 06:45:06 INFO - PROCESS | 1654 | 06:45:08 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:45:09 INFO - PROCESS | 1654 | RSS: Main: 265297920 06:45:09 INFO - PROCESS | 1654 | 06:45:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:45:12 INFO - PROCESS | 1654 | RSS: Main: 265347072 06:45:12 INFO - PROCESS | 1654 | 06:45:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:45:15 INFO - PROCESS | 1654 | RSS: Main: 265633792 06:45:15 INFO - PROCESS | 1654 | 06:45:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:45:19 INFO - PROCESS | 1654 | RSS: Main: 265428992 06:45:19 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 06:45:21 INFO - PROCESS | 1654 | RSS: Main: 267370496 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | __start_tp_report 06:45:21 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 06:45:21 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 06:45:21 INFO - PROCESS | 1654 | |i|pagename|runs| 06:45:21 INFO - PROCESS | 1654 | |0;ai-astar;107;117;118;116;118;117;117;123;117;120 06:45:21 INFO - PROCESS | 1654 | |1;audio-beat-detection;145;130;110;149;112;112;114;114;133;114 06:45:21 INFO - PROCESS | 1654 | |2;audio-dft;197;198;207;213;200;200;201;198;204;197 06:45:21 INFO - PROCESS | 1654 | |3;audio-fft;85;58;61;88;60;77;56;85;63;63 06:45:21 INFO - PROCESS | 1654 | |4;audio-oscillator;85;77;115;105;103;74;81;71;69;85 06:45:21 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;111;93;138;133;99;100;90;94;120;86 06:45:21 INFO - PROCESS | 1654 | |6;imaging-darkroom;97;97;94;104;152;110;96;96;123;104 06:45:21 INFO - PROCESS | 1654 | |7;imaging-desaturate;96;92;121;89;128;90;115;148;104;91 06:45:21 INFO - PROCESS | 1654 | |8;json-parse-financial;63;66;71;93;101;87;65;64;65;63 06:45:21 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;68;76;84;80;79;73;74;96;69;81 06:45:21 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;96;87;89;73;111;94;85;85;91;91 06:45:21 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;142;142;140;140;143;146;151;156;189;140 06:45:21 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;180;181;179;178;187;239;221;176;216;197 06:45:21 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;68;68;71;67;66;85;88;91;73;70 06:45:21 INFO - PROCESS | 1654 | __end_tp_report 06:45:21 INFO - PROCESS | 1654 | __start_cc_report 06:45:21 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,932 06:45:21 INFO - PROCESS | 1654 | __end_cc_report 06:45:21 INFO - PROCESS | 1654 | __startTimestamp1486046721708__endTimestamp 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | ------- Summary: start ------- 06:45:21 INFO - PROCESS | 1654 | Number of tests: 14 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:117.00 Median:117.50 stddev:4.06 (3.5%) stddev-sans-first:2.15 06:45:21 INFO - PROCESS | 1654 | Values: 107.0 117.0 118.0 116.0 118.0 117.0 117.0 123.0 117.0 120.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:122.00 stddev:14.77 (12.1%) stddev-sans-first:13.41 06:45:21 INFO - PROCESS | 1654 | Values: 145.0 130.0 110.0 149.0 112.0 112.0 114.0 114.0 133.0 114.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:201.50 Median:200.50 stddev:5.15 (2.6%) stddev-sans-first:5.20 06:45:21 INFO - PROCESS | 1654 | Values: 197.0 198.0 207.0 213.0 200.0 200.0 201.0 198.0 204.0 197.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:69.60 Median:70.00 stddev:12.65 (18.1%) stddev-sans-first:12.13 06:45:21 INFO - PROCESS | 1654 | Values: 85.0 58.0 61.0 88.0 60.0 77.0 56.0 85.0 63.0 63.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:75.50 stddev:15.83 (21.0%) stddev-sans-first:16.78 06:45:21 INFO - PROCESS | 1654 | Values: 85.0 77.0 115.0 105.0 103.0 74.0 81.0 71.0 69.0 85.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:106.40 Median:88.00 stddev:18.34 (20.8%) stddev-sans-first:19.37 06:45:21 INFO - PROCESS | 1654 | Values: 111.0 93.0 138.0 133.0 99.0 100.0 90.0 94.0 120.0 86.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:95.00 stddev:18.01 (19.0%) stddev-sans-first:18.71 06:45:21 INFO - PROCESS | 1654 | Values: 97.0 97.0 94.0 104.0 152.0 110.0 96.0 96.0 123.0 104.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:107.40 Median:89.50 stddev:20.01 (22.4%) stddev-sans-first:20.80 06:45:21 INFO - PROCESS | 1654 | Values: 96.0 92.0 121.0 89.0 128.0 90.0 115.0 148.0 104.0 91.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:73.80 Median:65.50 stddev:14.28 (21.8%) stddev-sans-first:14.60 06:45:21 INFO - PROCESS | 1654 | Values: 63.0 66.0 71.0 93.0 101.0 87.0 65.0 64.0 65.0 63.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:78.00 Median:79.50 stddev:8.16 (10.3%) stddev-sans-first:7.82 06:45:21 INFO - PROCESS | 1654 | Values: 68.0 76.0 84.0 80.0 79.0 73.0 74.0 96.0 69.0 81.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:90.20 Median:90.00 stddev:9.68 (10.8%) stddev-sans-first:10.04 06:45:21 INFO - PROCESS | 1654 | Values: 96.0 87.0 89.0 73.0 111.0 94.0 85.0 85.0 91.0 91.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:144.50 stddev:15.05 (10.4%) stddev-sans-first:15.76 06:45:21 INFO - PROCESS | 1654 | Values: 142.0 142.0 140.0 140.0 143.0 146.0 151.0 156.0 189.0 140.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.40 Median:192.00 stddev:22.23 (11.6%) stddev-sans-first:22.87 06:45:21 INFO - PROCESS | 1654 | Values: 180.0 181.0 179.0 178.0 187.0 239.0 221.0 176.0 216.0 197.0 06:45:21 INFO - PROCESS | 1654 | 06:45:21 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:72.00 stddev:9.50 (13.2%) stddev-sans-first:9.76 06:45:21 INFO - PROCESS | 1654 | Values: 68.0 68.0 71.0 67.0 66.0 85.0 88.0 91.0 73.0 70.0 06:45:21 INFO - PROCESS | 1654 | -------- Summary: end -------- 06:45:21 INFO - PROCESS | 1654 | 06:45:22 INFO - TEST-INFO | 1654: exit 0 06:45:22 INFO - TEST-OK | kraken | took 61672ms 06:45:22 INFO - SUITE-END | took 1177s 06:45:22 INFO - Completed test suite (00:19:37) 06:45:22 INFO - javascript benchmark 06:45:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7305.258755783003, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2874.9425011499784, 3354.4297469430194, 3359.748018898582, 3373.7975721456714, 3378.3750006248847, 5525.778968841241, 5571.721413929307, 5601.887962240753, 5664.5468362531, 5746.885062298751, 4909.582685471739, 4955.207166853304, 5518.806841760529, 5607.551395888329, 5632.915506267409, 4326.913461730764, 5074.137396642571, 5156.742162891859, 5181.922271165936, 5283.233931079991, 5183.766730497136, 5222.791088356461, 5236.109861323565, 5259.237410575464, 5281.18141688037, 5059.949400505985, 5170.146925757245, 5192.792288308463, 5232.529072383485, 5244.79020839166, 5020.447750747415, 5168.8449346519665, 5181.5077567631015, 5239.633225674204, 5276.657017293883, 5027.4469808321055, 5061.822836200752, 5065.189569668835, 5152.845414637567, 5208.791648334062, 4391.912161756779, 5453.536449401806, 5540.279763630702, 5554.666719996813, 5590.440955904418, 6194.1018552310115, 6225.159603453566, 6239.2824825145235, 6242.687865606701, 6277.278113017016, 6050.878982420371, 6073.51411887048, 6083.8174854754425, 6126.111713801519, 6144.156752974134, 4170.582941705866, 4894.951050489521, 5654.293213348332, 5660.235868157787, 5689.857753556153, 6196.256449226032, 6201.286852011986, 6210.037444196157, 6216.191895053614, 6228.813135605939, 4401.317795741666, 5086.796528138833, 5915.40845915405, 5997.160397544348, 6000.549958753111, 6070.878582428327, 6148.831721972811, 6163.445289923929, 6176.66028368444, 6188.721507532172, 6106.45041946218, 6109.114178444101, 6112.255355561532, 6115.6330620162935, 6150.261968563801, 5254.737263136828, 6089.0, 6101.633901965896, 6110.480609148272, 6112.144299798032, 4192.790360481964, 4921.827736029293, 4928.162212423897, 4930.6795058321095, 4947.381577302837, 4368.912621747547, 4409.470863496401, 4415.315626077963, 4450.154470650566, 4470.083632855257, 5799.797007104731, 5804.854878628068, 5817.476427121579, 5821.388754180834, 5847.152162936435, 5721.542276617944, 6150.50795936324, 6154.015357542772, 6185.721642526097, 6217.378262173836, 4893.584045356185, 5877.177195192677, 5886.440788125121, 5894.4400281974, 5919.052951527735, 5425.430329815313, 5436.483534064258, 5436.70098144598, 5463.344398672185, 5465.836024919259, 6114.2357205349335, 6205.627662340274, 6226.221722284715, 6372.394622510854, 6373.235211774524, 5654.604177707521, 6134.681512609229, 6213.440790328892, 6236.438720515175, 6243.8126856194385, 5937.3468918418075, 6276.843078923063, 6277.246730392382, 6331.90502142459, 6335.176427064452, 5967.731452084753, 6071.5446341523675, 6191.442770150528, 6204.13142160089, 6206.658633775005, 5399.0281749285505, 5551.139573366135, 6161.6919154041225, 6264.123022776906, 6270.655113968775, 6086.817395478142, 6094.116353128683, 6097.969510152421, 6109.7861574844665, 6110.869489144522, 5858.5898987070495, 6089.293075788369, 6105.823680184288, 6110.953680641429, 6138.296437970319, 5035.672681275705, 5529.253550770594, 5784.768609255582, 5841.094630332305, 6024.3975602439405, 6101.206843110367, 6110.1445797587485, 6130.478909292582, 6134.110553969738, 6143.754249829957, 5692.772289108555, 5752.079667253387, 5882.823515294548, 5950.494207992369, 5978.103284507359], "value": 5574.224534768695, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [460.3807878403548, 476.38546275304856, 487.3883276488007, 499.11335153121195, 512.2879197914898, 40136.39795403071, 42704.359434608465, 42779.0, 42872.9281767956, 42986.0, 43403.34894976578, 43507.56492435075, 43781.0, 43800.342994855106, 43896.0, 8362.289205417546, 8566.957165214166, 9405.764855878606, 9615.51922403877, 11304.434778261095, 11234.49444774987, 11425.942870285679, 11483.0, 11495.827562586526, 11522.539098436053, 10157.136643385322, 11479.59821406251, 11523.07815374768, 11530.538778448852, 11552.133589980744, 10071.395716257048, 10827.350358978447, 10976.506057227443, 11429.5428182873, 11532.769344613062, 11426.828597571042, 11518.308901465938, 11520.769584608344, 11527.308361498253, 11531.827022594667, 29539.40921181586, 36289.45565816515, 36407.0, 36576.45135322946, 36596.63403365986, 3545.964540354589, 4039.7980100994832, 4074.8573799916862, 4160.313548264533, 4247.851325203603, 31161.376772464424, 31416.0, 31465.68534314673, 31474.685253147632, 32255.0, 3087.3516561522106, 3190.6490286068515, 3235.352929414103, 3265.379577880219, 3292.8024318540965, 397538.9999999971, 454210.0, 463546.0, 465051.0, 466025.0, 53033.46966530362, 64344.0, 64588.35411645917, 64628.35371646223, 64785.0, 67471.0, 67597.0, 67695.66152169208, 67870.66064669743, 67912.0, 16330.75503867443, 16596.336146554244, 16596.751048734277, 16598.58503537397, 16627.50117496477, 11228.66314010581, 11573.76852462946, 12512.49950002008, 12629.43167557462, 12749.107562470537, 15623.0, 18009.54976125581, 18467.35364262271, 18492.89042657445, 18522.444326670222, 7439.7768066957, 7538.962305188439, 7562.584057876869, 7588.962055189799, 7594.354479869163, 843.8818565400849, 856.9160012584086, 858.2704701004096, 860.819227962135, 864.5504337744463, 29984.55023174654, 30231.244218894702, 30413.69586304153, 30976.69023309783, 31068.0, 1008.621766837436, 1038.3562191441354, 1042.6559235452282, 1075.93544387337, 1186.7211205366732, 14064.929675351557, 14717.55847324582, 15360.539183824503, 16043.197840107949, 16179.595510112105, 565729.0, 567991.0, 574006.0, 574113.0, 574322.0, 60017.0, 60655.69672151611, 60781.17656470313, 60843.695781521696, 60966.0, 1359.6261028217123, 1408.478862820763, 1420.850810664886, 1430.6780974280703, 1433.849445808175, 27495.0, 29609.9636512721, 30092.54861177128, 30682.0, 30688.77244910268, 52251.0, 52352.69118272074, 52650.0, 52699.47300527099, 52726.47273527369, 488845.0, 490730.0, 491740.0, 496704.0, 497442.0, 26100.34749131211, 26868.731312687403, 26895.731042690106, 27014.784334704593, 41399.1720165601, 40706.38940415837, 40752.79623601863, 42106.789466052476, 43350.56649433466, 43627.781861090494, 35988.82005589955, 36050.63949360473, 36197.91406257711, 37042.07394815151, 37490.0, 35656.82171589125, 35800.0, 36201.818990904874, 36662.8166859164, 36695.81652091722, 34854.651453486156, 35516.82241588776, 35904.0, 35932.820335898155, 36198.63801362058, 26512.602310964965, 26736.331591710365, 27714.3071423208, 28854.12448190861, 30119.0, 23502.17742379077, 23859.045638174972, 24120.87939560291, 24522.87738561296, 24693.382665433506], "value": 19969.034233495404, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.13973210945096, 393.45310019073474, 395.436502983249, 400.49537582645877, 428.5136370219802, 13863.653408664763, 18879.867207967498, 19885.801141988595, 20915.0, 21752.0, 21577.67633485495, 21953.121875125016, 22499.0, 22538.88730556345, 22768.203112891053, 5861.8827623447505, 6111.449969502743, 6135.938640613582, 6144.293406258271, 6150.630962142263, 5845.269341332333, 6031.185789918349, 6038.0, 6122.755089796403, 6137.17148184996, 6028.0, 6035.788747393842, 6037.154798328215, 6047.304559975615, 6054.969725151368, 6005.159277701126, 6006.369331220225, 6558.442532384729, 7788.415868809805, 7852.21477852216, 7463.477556571042, 7687.577183254919, 7716.189800071024, 7748.7675369739, 7759.224077592252, 29694.851525742448, 30081.548776768366, 30183.189008659552, 30194.24514387158, 30674.38651226963, 2600.9479810403686, 2873.324768679359, 2973.0, 2987.1337312179444, 3000.939981200386, 13684.726305473934, 16800.991940483487, 17024.829751702448, 17200.39798607055, 18366.449006529827, 1714.9485515434558, 1903.3433465454395, 2016.1070499318548, 2044.9591008179902, 2065.5868826234664, 144120.0, 146949.0, 147026.26486867605, 152504.237478813, 152699.0, 24259.636105458434, 24309.7569024311, 24573.262802115965, 25289.988400463775, 25451.87274063618, 12124.333161676017, 12722.427490762939, 41073.0, 41075.0, 41152.79423602923, 8264.83470330602, 8293.465708843883, 8410.201030902055, 8502.532360720095, 8568.657253709907, 8560.571971401405, 8681.435706679045, 8688.0, 8817.955910220408, 8866.93596768392, 4089.5297040840087, 4121.773302468393, 4161.500619925629, 4211.873643790691, 4226.661867050629, 1782.287085165944, 1934.8742331748392, 2052.640787862118, 2092.3618296419736, 2109.303929703194, 796.665400531778, 957.7366224288237, 964.5948701545368, 966.5602151021268, 984.7538115471132, 21056.26303079385, 21544.676829847565, 21661.91690415473, 21884.45288867759, 21936.0, 958.065885761391, 971.9173870220969, 999.4053538144792, 1000.314784372707, 1007.219404961161, 10522.263441559171, 10859.945700271448, 11141.836042007733, 11226.754649070219, 11251.83122253167, 689331.0, 694447.0, 740741.0, 757007.0, 758968.0, 159510.20244898932, 159946.0, 160544.19727901285, 161093.0, 163913.3608663922, 967.066780556766, 978.4569563891966, 1019.9694009179885, 1044.9111825494765, 1050.658535975811, 17812.109394530482, 19701.90149049303, 19977.900110499937, 20259.79740202579, 20325.695114572998, 47121.0, 51250.0, 57501.0, 58433.0, 61133.0, 87846.56076719576, 89103.55448222718, 90252.0, 90733.54633226791, 91112.0, 6881.862362752817, 8130.634121464666, 8259.114149022487, 8302.54336011525, 8664.78338041554, 9420.57607407682, 12394.566190183301, 13267.137636053625, 13422.060455768393, 13467.057305988486, 11107.0, 11192.608258710907, 11519.020883224684, 11573.537058517564, 11667.299962002307, 11059.225854190452, 11072.391018493738, 11220.0462960646, 11554.42227888619, 11718.23831450953, 11488.19582629208, 11563.074954003827, 11811.527538898348, 11867.584634538096, 11905.583304584297, 7908.446408751563, 8438.029626593021, 8550.78623034429, 8554.871676924975, 8633.784155396166, 9580.568874400808, 9630.518474076129, 11167.553297867995, 11288.199154232669, 11606.187566870238], "value": 10732.213160625724, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1215.8601760797508, 1227.5703503773675, 1417.4471955937186, 1429.5425463851566, 1441.9639509012277, 7399.963000184992, 7677.4241931855195, 7906.565138917357, 8360.122187170351, 8390.035145958216, 7849.0, 8056.154103819088, 8116.634751436185, 8232.670693172266, 8258.463199892018, 2211.889405529725, 2249.3701763506247, 2259.197984715427, 2259.243153543565, 2260.0394832196334, 2044.631966246075, 2102.127617038927, 2246.4608493961414, 2272.545490901818, 2297.1844995026777, 2138.4974530985205, 2223.0774228695072, 2230.6765518999737, 2237.2169740590743, 2237.407087121914, 2262.570111678784, 2267.1384873748034, 2292.873891935943, 2295.403195169268, 2299.4596269876565, 2245.438640339915, 2288.359259407352, 2311.1217737259735, 2316.525112351964, 2321.199186280747, 6951.6871740771785, 8163.795905102361, 8360.0, 8371.916280837175, 8381.832363352698, 3834.731568790186, 4266.5946735060115, 4513.645906228151, 4980.203167493184, 5160.509751573594, 3000.339925216449, 3089.8609562569736, 3096.427160975204, 3155.7002084801907, 3300.8349582520777, 1057.1173070486154, 1121.9551217951273, 1136.8067428537086, 1138.971525711864, 1142.9028532574741, 8322.62548185333, 10517.053465188093, 10554.577816887391, 10565.207609429246, 10582.9470852646, 4361.738295702205, 4561.498235194126, 4613.746243956548, 4618.930716039262, 4645.187092258841, 9138.268938484905, 9294.024127466653, 9294.674686385944, 9297.674581389754, 9300.72097837066, 5110.4123025852505, 5176.094183517945, 5183.144781111075, 5207.3230480037355, 5213.817516386908, 3768.9459244711697, 4623.930641040387, 4718.787654555554, 4748.264019077049, 4766.094442055998, 3784.6972242220554, 3789.0716774390453, 3838.136419305634, 3878.26313000529, 3892.902677433087, 1177.7879981603394, 1226.7975783995719, 1277.8083287506947, 1283.4609464025132, 1293.5666551705285, 758.6699785593174, 761.6610608279374, 788.2984144111745, 807.1000834070087, 835.2315869400167, 5657.547396208293, 5670.829875103754, 5739.913901291484, 5744.396838331999, 5778.94221057784, 1001.4441984698562, 1004.0963133180196, 1011.858339832424, 1028.0798685176726, 1069.5400977579716, 3828.980855095707, 3830.195658911604, 3844.7116466265143, 3894.3184942634925, 3947.4670919426085, 8083.595820209083, 9339.766505837273, 9815.460149691835, 9824.01759824012, 9831.31180817336, 9239.72280831576, 9648.517574121266, 9667.226621870233, 9749.561269742879, 9774.364666296668, 7526.661300241502, 7723.382129429632, 7725.613719314012, 7725.652345644459, 7728.729494467667, 4343.847965321325, 5414.052540805422, 5616.803411880564, 5654.434556544313, 5941.613795103304, 7316.268373162854, 7395.7041718330665, 9050.683226087056, 9052.864207036768, 9076.455412675261, 8211.260986511243, 8271.33829293667, 8344.0, 8455.069942306465, 8463.873041904499, 6949.826254343682, 7070.717171313296, 7082.716691332289, 7084.079069720903, 7087.503874728925, 7418.480706350504, 7576.204498527575, 7689.307962283421, 7694.269044440769, 7707.383409327353, 6442.935570644234, 6978.151727422354, 7043.2604576518725, 7075.964620176866, 7149.070620819468, 6949.895751563629, 6963.234044255027, 6965.616891071039, 7063.081799366049, 7073.610951397723, 6307.274663413767, 6325.05124231369, 6384.872302554016, 6406.519511036784, 6427.775027874002, 4844.2007068833245, 4887.853364399074, 4969.00619876019, 4969.726665033458, 5089.13484707608, 4947.579455746302, 5022.422421421583, 5063.493650634907, 5138.306328645585, 5155.355580552431], "value": 4292.362481148373, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.4334522947915, 352.8641473032883, 366.10669965284706, 367.8859553538405, 375.30755755630867, 5100.0, 5149.356330458692, 5288.867778305545, 5399.3790714067845, 5437.728113594319, 5145.871353216172, 5299.814506492264, 5340.279062326585, 5348.277982472365, 5360.731963401824, 3969.2458432897656, 3973.483447151866, 4200.62194402503, 4224.493060832704, 4226.72526285792, 3375.0549846043145, 3845.3078445879732, 4258.679809259124, 4273.226610956449, 4274.615284624399, 3447.4828775683723, 3634.4184930411006, 3662.890113296592, 4002.3584434273384, 4026.1545075534027, 3826.2730081284744, 4044.090079732037, 4051.6150965658217, 4059.6549293310104, 4060.593940605945, 3512.3150985557827, 3516.244007538366, 3529.6117427083, 3531.5055892175114, 3537.8407971641336, 4887.511248875119, 5058.392992840846, 5131.358580177478, 5175.715335656538, 5230.0, 3919.960800391988, 3962.722609417342, 4097.660406768504, 4609.216433206363, 4665.88335291617, 5017.473165317626, 5533.806316778933, 5650.660960342392, 5658.151277308396, 5681.659100453986, 3523.5947865995226, 3734.1917223921937, 3843.730938834283, 3865.40086286626, 3873.341531939577, 5807.709614519299, 5912.0, 5940.198073260098, 5947.732352044125, 5961.552883533709, 3169.429502689492, 3381.0871064812363, 3451.9633416284514, 3906.824192911326, 3914.3541315683165, 3350.564426624523, 3642.852501462031, 3764.4917936078277, 3771.698264138862, 3866.284737323604, 4594.88512787183, 4610.907781844345, 4612.769361531977, 4621.768911554409, 4673.532646735299, 4535.0, 5729.799457019068, 5730.389854391707, 5750.453706897838, 5782.306123265151, 1164.4280221700806, 1211.7091897944606, 1215.4469716279248, 1228.9738068712547, 1279.6544932868073, 753.6608526163249, 846.817934144162, 860.8880845490171, 880.7449384626881, 881.3698205782767, 4300.462442194726, 4322.956770432318, 4488.5511448855505, 4598.218302888517, 4704.929426058612, 5516.696976358447, 5530.672638566795, 5574.498295153456, 5578.191162281528, 5598.860028499239, 4412.492563355191, 4555.703879247838, 4664.743439110882, 4839.644899428165, 5055.6713813602, 2668.9332766680986, 2766.7964435470635, 2820.2373516552157, 2882.855857207131, 2899.941521344736, 4902.48523904992, 5083.644794435655, 5128.640995130379, 5190.870228244248, 5198.532132108128, 4649.95350046502, 4780.641451891122, 4832.758362081881, 5200.76596553156, 5461.2354270402175, 4633.976830115827, 5139.408967968731, 5256.4493474425135, 5631.493165615032, 5804.448577385142, 4789.329493870791, 4826.831060912851, 4967.9815637793445, 5573.024720673785, 5618.892915156816, 4942.0841703393735, 5109.616778741535, 5429.484199001088, 5468.890622187454, 5486.177073439016, 5029.698218106925, 5113.139579617514, 5340.919886201787, 5486.670799752028, 5742.655440673573, 3701.3707669695677, 3710.6289371062676, 3711.9072023199637, 3718.2749363873786, 3750.868719594801, 4558.703684260512, 4675.9064818702755, 5478.424765399575, 5538.113901775697, 5582.525485333792, 4552.476465206543, 4762.95237047639, 5107.463716309734, 5186.870328241674, 5336.279602253646, 4277.893052673708, 4596.563326483979, 4935.876603084952, 4947.678400903929, 5331.866703332447, 4780.378550788375, 5206.947930520798, 5221.608379371486, 5226.766319281394, 5228.085085110167, 5091.8247037428955, 5300.535251108253, 5327.9467205329, 5338.225957236256, 5351.759170837244, 5202.479752024768, 5242.0, 5242.554382877498, 5243.408265338256, 5244.868878278074], "value": 3963.7199082472, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.6627690526452, 712.4410009796067, 730.6018220070063, 775.8952541406912, 839.5844057191689, 14309.640584144516, 14962.700745985101, 15200.695986080273, 15992.360305587792, 16047.35810567576, 14721.705565888677, 14891.404343826234, 14896.404143834261, 14909.776353354708, 14916.701665966673, 661.6856992928344, 686.6189264957947, 859.2824991132412, 868.7046404222563, 878.3412440669498, 858.0347109501811, 861.1732736572898, 862.9870551941726, 873.5151990645189, 875.9824803503926, 931.6413180925325, 933.2813733425293, 934.2385955446324, 940.5908429833011, 941.4868896451417, 918.0406475233364, 925.0379605210572, 925.9814803703888, 926.3422969691527, 926.4163576946548, 680.6970897950414, 683.2006552333781, 690.433844247717, 691.2223748283182, 691.4364792693989, 13310.800337994939, 14527.70944581113, 15896.523104306889, 16071.437499687454, 16358.75461868061, 14586.34361453737, 14816.407343706347, 14819.629509262248, 14915.477958271409, 15241.695166096726, 11686.744584945527, 11744.530218791324, 11744.588939387166, 11800.350980696057, 12230.021598272117, 3303.438415469376, 3725.497057897176, 3739.439084137374, 3774.8490060397553, 3916.2167566486814, 81758.59120704418, 98482.0, 98947.50526247395, 99235.50382248114, 101004.0, 10613.734656633645, 11210.27133236337, 11285.887141128484, 11387.715307117389, 11651.951324380847, 23859.761402386102, 23950.52098958011, 24146.51706965886, 24196.879015605162, 24202.87898560496, 7915.082951745333, 7915.841683166304, 7941.6029198541, 7953.602319883983, 8115.715949941724, 4287.442632457823, 4297.09760950204, 4357.564243575681, 4391.692581519263, 4486.618637415856, 3606.549181352331, 3624.9637503624626, 3701.0747313171705, 3703.851845926187, 3722.4602432647116, 667.1227336053172, 671.3152584364019, 674.6660403100497, 717.5120917775962, 720.86900907779, 669.9464042876558, 670.9396154346034, 710.6840830587133, 721.942244620429, 730.7113690092384, 9537.713868583953, 10221.284510084222, 10276.691699249035, 10361.067503924533, 10426.68719938403, 685.715428097334, 693.6150436507787, 702.2977022977022, 725.793148952546, 726.1177669131924, 4606.744917499235, 5024.799008039638, 5039.521245481674, 5078.695278283315, 5105.744712764347, 101607.0, 106061.0, 106273.0, 107596.46201769098, 110675.0, 73246.0, 73308.63345683344, 73550.0, 79469.41061178745, 82251.7662235055, 61103.38896610977, 64825.67587162034, 68583.999999999, 72174.91737623939, 73598.6320068407, 8481.703140390056, 10554.947225263824, 10735.785284294427, 10788.0, 10868.130549555859, 36004.639953600126, 44767.88080298019, 44836.77581612071, 48509.5149048505, 48584.0, 47881.76059119682, 61872.38127618846, 64533.67733161492, 64823.0, 65060.0, 9884.456354900549, 10631.0, 12927.870721292667, 13064.673383165116, 13134.802977955529, 11679.416029198675, 14641.414343426144, 14837.92581037088, 14989.971182703928, 15175.924120379328, 13178.143420677625, 13293.534726284533, 14102.576922692335, 14111.576652700436, 14116.647083822987, 12216.816747748613, 13363.465461381436, 13777.173369597856, 13962.790558141432, 14000.089994150349, 13208.603741887759, 13280.667983300495, 13933.303334833015, 13971.510997115052, 13985.930070349583, 11061.85664462637, 11183.161262905478, 11238.0, 11407.600733974272, 11453.770924581628, 6832.760853370109, 9514.571844266886, 10185.33795303303, 10394.792104157723, 10678.077290076471], "value": 7477.066087299656, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1580.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 118.0, 116.0, 118.0, 117.0, 117.0, 123.0, 117.0, 120.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 130.0, 110.0, 149.0, 112.0, 112.0, 114.0, 114.0, 133.0, 114.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 198.0, 207.0, 213.0, 200.0, 200.0, 201.0, 198.0, 204.0, 197.0], "value": 201.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 61.0, 88.0, 60.0, 77.0, 56.0, 85.0, 63.0, 63.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 77.0, 115.0, 105.0, 103.0, 74.0, 81.0, 71.0, 69.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 93.0, 138.0, 133.0, 99.0, 100.0, 90.0, 94.0, 120.0, 86.0], "value": 106.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 94.0, 104.0, 152.0, 110.0, 96.0, 96.0, 123.0, 104.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 92.0, 121.0, 89.0, 128.0, 90.0, 115.0, 148.0, 104.0, 91.0], "value": 107.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 71.0, 93.0, 101.0, 87.0, 65.0, 64.0, 65.0, 63.0], "value": 73.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 84.0, 80.0, 79.0, 73.0, 74.0, 96.0, 69.0, 81.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 87.0, 89.0, 73.0, 111.0, 94.0, 85.0, 85.0, 91.0, 91.0], "value": 90.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 142.0, 140.0, 140.0, 143.0, 146.0, 151.0, 156.0, 189.0, 140.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 181.0, 179.0, 178.0, 187.0, 239.0, 221.0, 176.0, 216.0, 197.0], "value": 195.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 71.0, 67.0, 66.0, 85.0, 88.0, 91.0, 73.0, 70.0], "value": 74.7, "unit": "score"}]}]} 06:45:22 INFO - Return code: 0 06:45:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:45:23 ERROR - # TBPL SUCCESS # 06:45:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:45:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:45:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:45:23 INFO - 2017-02-02 06:45:23.024 screenresolution[1659:10374] starting screenresolution argv=screenresolution get 06:45:23 INFO - 2017-02-02 06:45:23.027 screenresolution[1659:10374] Display 0: 1600x1200x32@60 06:45:23 INFO - 2017-02-02 06:45:23.033 screenresolution[1660:10377] starting screenresolution argv=screenresolution list 06:45:23 INFO - Available Modes on Display 0 06:45:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:45:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:45:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:45:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:45:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:45:23 INFO - Intel Iris: 06:45:23 INFO - Chipset Model: Intel Iris 06:45:23 INFO - Type: GPU 06:45:23 INFO - Bus: Built-In 06:45:23 INFO - VRAM (Dynamic, Max): 1536 MB 06:45:23 INFO - Vendor: Intel (0x8086) 06:45:23 INFO - Device ID: 0x0a2e 06:45:23 INFO - Revision ID: 0x0009 06:45:23 INFO - Displays: 06:45:23 INFO - Display: 06:45:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:45:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:45:23 INFO - Display Serial Number: 1600x1200 60 06:45:23 INFO - Main Display: Yes 06:45:23 INFO - Mirror: Off 06:45:23 INFO - Online: Yes 06:45:23 INFO - Rotation: Supported 06:45:23 INFO - Return code: 0 06:45:23 INFO - Running post-action listener: _resource_record_post_action 06:45:23 INFO - [mozharness: 2017-02-02 14:45:23.275666Z] Finished run-tests step (success) 06:45:23 INFO - Running post-run listener: _resource_record_post_run 06:45:23 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 38959616; Write bytes: 555329536; Read time: 252; Write time: 1692 06:45:23 INFO - TinderboxPrint: CPU usage
54.6% 06:45:23 INFO - TinderboxPrint: I/O read bytes / time
38,959,616 / 252 06:45:23 INFO - TinderboxPrint: I/O write bytes / time
555,329,536 / 1,692 06:45:23 INFO - TinderboxPrint: CPU idle
2,181.0 (45.4%) 06:45:23 INFO - TinderboxPrint: CPU system
967.9 (20.2%) 06:45:23 INFO - TinderboxPrint: CPU user
1,652.8 (34.4%) 06:45:23 INFO - TinderboxPrint: Swap in / out
290,942,976 / 0 06:45:23 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 253647360; Write bytes: 253054976; Read time: 18358; Write time: 551 06:45:23 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 35260928; Write bytes: 300771328; Read time: 222; Write time: 1134 06:45:23 INFO - Running post-run listener: _upload_blobber_files 06:45:23 INFO - Blob upload gear active. 06:45:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:45:23 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 06:45:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:45:24 INFO - (blobuploader) - INFO - Open directory for files ... 06:45:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:45:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:45:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:24 INFO - (blobuploader) - INFO - Done attempting. 06:45:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:45:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:45:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:25 INFO - (blobuploader) - INFO - Done attempting. 06:45:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:45:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:45:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:26 INFO - (blobuploader) - INFO - Done attempting. 06:45:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:45:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:45:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:45:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:45:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:45:29 INFO - (blobuploader) - INFO - Done attempting. 06:45:29 INFO - (blobuploader) - INFO - Iteration through files over. 06:45:29 INFO - Return code: 0 06:45:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:45:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:45:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6d7ed6ea3e3fed9c4995e0388213f99a185a18b2d9475a80e6f2fa01586bdd1294caacadee4c755e141a4827dc98c01053a27b72afd4e50801452437ca31f6a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6193f0217a5f13c299efbbafabc3a32ba51dfcf7b7a5e27db75cf7c5c3d405dc3efd6689bf9666f7b16748e88f9802463f6469eae8ff3e290d002afb75088f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12572837799a937dfe0f7d4a6501355c0bc4ef63f56cbed479a4b35883d6f320813fdfeabd6561ca728fea4525b2a6b02c3a0bcbfa0b1145534daed401a37885", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb7911552fdf7ca5a9e2509a5f460959628e4eee6e26ea381abcef6be526e3f0a659978b48be38abca65f1eeecc97b34b21883c38b481d93d166dd16251a168"} 06:45:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:45:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:45:29 INFO - Contents: 06:45:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6d7ed6ea3e3fed9c4995e0388213f99a185a18b2d9475a80e6f2fa01586bdd1294caacadee4c755e141a4827dc98c01053a27b72afd4e50801452437ca31f6a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6193f0217a5f13c299efbbafabc3a32ba51dfcf7b7a5e27db75cf7c5c3d405dc3efd6689bf9666f7b16748e88f9802463f6469eae8ff3e290d002afb75088f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12572837799a937dfe0f7d4a6501355c0bc4ef63f56cbed479a4b35883d6f320813fdfeabd6561ca728fea4525b2a6b02c3a0bcbfa0b1145534daed401a37885", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb7911552fdf7ca5a9e2509a5f460959628e4eee6e26ea381abcef6be526e3f0a659978b48be38abca65f1eeecc97b34b21883c38b481d93d166dd16251a168"} 06:45:29 INFO - Running post-run listener: copy_logs_to_upload_dir 06:45:29 INFO - Copying logs to upload dir... 06:45:29 INFO - mkdir: /builds/slave/test/build/upload/logs 06:45:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.620503 ========= master_lag: 0.88 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-02-02 06:45:30.164262) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 06:45:30.168741) ========= 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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6d7ed6ea3e3fed9c4995e0388213f99a185a18b2d9475a80e6f2fa01586bdd1294caacadee4c755e141a4827dc98c01053a27b72afd4e50801452437ca31f6a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6193f0217a5f13c299efbbafabc3a32ba51dfcf7b7a5e27db75cf7c5c3d405dc3efd6689bf9666f7b16748e88f9802463f6469eae8ff3e290d002afb75088f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12572837799a937dfe0f7d4a6501355c0bc4ef63f56cbed479a4b35883d6f320813fdfeabd6561ca728fea4525b2a6b02c3a0bcbfa0b1145534daed401a37885", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb7911552fdf7ca5a9e2509a5f460959628e4eee6e26ea381abcef6be526e3f0a659978b48be38abca65f1eeecc97b34b21883c38b481d93d166dd16251a168"} build_url:https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/clIzfmG9SJuqnvEVZPsWNA/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c6d7ed6ea3e3fed9c4995e0388213f99a185a18b2d9475a80e6f2fa01586bdd1294caacadee4c755e141a4827dc98c01053a27b72afd4e50801452437ca31f6a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d6193f0217a5f13c299efbbafabc3a32ba51dfcf7b7a5e27db75cf7c5c3d405dc3efd6689bf9666f7b16748e88f9802463f6469eae8ff3e290d002afb75088f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/12572837799a937dfe0f7d4a6501355c0bc4ef63f56cbed479a4b35883d6f320813fdfeabd6561ca728fea4525b2a6b02c3a0bcbfa0b1145534daed401a37885", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7cb7911552fdf7ca5a9e2509a5f460959628e4eee6e26ea381abcef6be526e3f0a659978b48be38abca65f1eeecc97b34b21883c38b481d93d166dd16251a168"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 06:45:30.208475) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:45:30.208826) ========= 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.mT1OUCNHSR/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.mnTR0xtAX4/Listeners TMPDIR=/var/folders/05/kvspgyq10sb_rylg685dcdw800000w/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.005214 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 06:45:30.260435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 06:45:30.260743) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 06:45:30.261077) ========= ========= Total master_lag: 1.13 =========