builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0236 starttime: 1493803496.04 results: success (0) buildid: 20170503011121 builduid: 475dfeb79ada4388a6e975446d7047d7 revision: 604acb6a6aece0d9f296dbb7ca091f45713c0c9d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:56.044930) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:56.045363) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:56.045674) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:56.074298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:56.074647) ========= 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.cAQyOGUfWc/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.c28yd7tBGP/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-03 02:24:56-- 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% 6.69M=0.002s 2017-05-03 02:24:56 (6.69 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139583 ========= master_lag: 0.69 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:56.901725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:56.902099) ========= 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.cAQyOGUfWc/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.c28yd7tBGP/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.022826 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:56.954445) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-03 02:24:56.954961) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 604acb6a6aece0d9f296dbb7ca091f45713c0c9d --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 604acb6a6aece0d9f296dbb7ca091f45713c0c9d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cAQyOGUfWc/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.c28yd7tBGP/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-03 02:24:57,075 truncating revision to first 12 chars 2017-05-03 02:24:57,075 Setting DEBUG logging. 2017-05-03 02:24:57,075 attempt 1/10 2017-05-03 02:24:57,075 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/604acb6a6aec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-03 02:24:58,513 unpacking tar archive at: mozilla-central-604acb6a6aec/testing/mozharness/ program finished with exit code 0 elapsedTime=2.581700 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-03 02:24:59.562602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:59.562965) ========= script_repo_revision: 604acb6a6aece0d9f296dbb7ca091f45713c0c9d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:59.563392) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:59.563734) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-03 02:24:59.584201) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-03 02:24:59.584532) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.cAQyOGUfWc/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.c28yd7tBGP/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:24:59 INFO - MultiFileLogger online at 20170503 02:24:59 in /builds/slave/test 02:24:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 02:24:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:24:59 INFO - {'append_to_log': False, 02:24:59 INFO - 'base_work_dir': '/builds/slave/test', 02:24:59 INFO - 'blob_upload_branch': 'Firefox', 02:24:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:24:59 INFO - 'branch': 'Firefox', 02:24:59 INFO - 'buildbot_json_path': 'buildprops.json', 02:24:59 INFO - 'config_files': ('talos/mac_config.py',), 02:24:59 INFO - 'default_actions': ('clobber', 02:24:59 INFO - 'read-buildbot-config', 02:24:59 INFO - 'download-and-extract', 02:24:59 INFO - 'populate-webroot', 02:24:59 INFO - 'create-virtualenv', 02:24:59 INFO - 'install', 02:24:59 INFO - 'run-tests'), 02:24:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:24:59 INFO - 'download_minidump_stackwalk': True, 02:24:59 INFO - 'download_symbols': 'ondemand', 02:24:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:24:59 INFO - 'tooltool.py': '/tools/tooltool.py', 02:24:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:24:59 INFO - '/tools/misc-python/virtualenv.py')}, 02:24:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:24:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:24:59 INFO - 'gecko_profile': False, 02:24:59 INFO - 'gecko_profile_interval': 0, 02:24:59 INFO - 'installer_path': 'installer.exe', 02:24:59 INFO - 'log_level': 'info', 02:24:59 INFO - 'log_name': 'talos', 02:24:59 INFO - 'log_to_console': True, 02:24:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:24:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:24:59 INFO - 'opt_config_files': (), 02:24:59 INFO - 'pip_index': False, 02:24:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:24:59 INFO - 'cmd': ('bash', 02:24:59 INFO - '-c', 02:24:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:24:59 INFO - 'enabled': True, 02:24:59 INFO - 'halt_on_failure': False, 02:24:59 INFO - 'name': 'check_screen_resolution'},), 02:24:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:24:59 INFO - 'cmd': ('bash', 02:24:59 INFO - '-c', 02:24:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:24:59 INFO - 'enabled': True, 02:24:59 INFO - 'halt_on_failure': False, 02:24:59 INFO - 'name': 'check_screen_resolution'},), 02:24:59 INFO - 'run_cmd_checks_enabled': True, 02:24:59 INFO - 'suite': 'dromaeojs', 02:24:59 INFO - 'system_bits': '32', 02:24:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:24:59 INFO - 'title': 't-yosemite-r7-0236', 02:24:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:24:59 INFO - 'use_talos_json': True, 02:24:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:24:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:24:59 INFO - 'work_dir': 'build'} 02:24:59 INFO - [mozharness: 2017-05-03 09:24:59.824733Z] Running clobber step. 02:24:59 INFO - Running pre-action listener: _resource_record_pre_action 02:24:59 INFO - Running main action method: clobber 02:24:59 INFO - rmtree: /builds/slave/test/build 02:24:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:25:01 INFO - Running post-action listener: _resource_record_post_action 02:25:01 INFO - [mozharness: 2017-05-03 09:25:01.614975Z] Finished clobber step (success) 02:25:01 INFO - [mozharness: 2017-05-03 09:25:01.615091Z] Running read-buildbot-config step. 02:25:01 INFO - Running pre-action listener: _resource_record_pre_action 02:25:01 INFO - Running main action method: read_buildbot_config 02:25:01 INFO - Using buildbot properties: 02:25:01 INFO - { 02:25:01 INFO - "project": "", 02:25:01 INFO - "product": "firefox", 02:25:01 INFO - "script_repo_revision": "production", 02:25:01 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:25:01 INFO - "repository": "", 02:25:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:25:01 INFO - "buildid": "20170503011121", 02:25:01 INFO - "pgo_build": "False", 02:25:01 INFO - "basedir": "/builds/slave/test", 02:25:01 INFO - "buildnumber": 32, 02:25:01 INFO - "slavename": "t-yosemite-r7-0236", 02:25:01 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 02:25:01 INFO - "platform": "yosemite_r7", 02:25:01 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:25:01 INFO - "branch": "mozilla-central", 02:25:01 INFO - "repo_path": "mozilla-central", 02:25:01 INFO - "revision": "604acb6a6aece0d9f296dbb7ca091f45713c0c9d", 02:25:01 INFO - "stage_platform": "macosx64", 02:25:01 INFO - "builduid": "475dfeb79ada4388a6e975446d7047d7", 02:25:01 INFO - "slavebuilddir": "test" 02:25:01 INFO - } 02:25:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 02:25:01 INFO - Running post-action listener: _resource_record_post_action 02:25:01 INFO - [mozharness: 2017-05-03 09:25:01.618374Z] Finished read-buildbot-config step (success) 02:25:01 INFO - [mozharness: 2017-05-03 09:25:01.618476Z] Running download-and-extract step. 02:25:01 INFO - Running pre-action listener: _resource_record_pre_action 02:25:01 INFO - Running main action method: download_and_extract 02:25:01 INFO - mkdir: /builds/slave/test/build/tests 02:25:01 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')]} 02:25:01 INFO - trying https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 02:25:01 INFO - Downloading https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:25:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 02:25:03 INFO - Downloaded 1325 bytes. 02:25:03 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 02:25:03 INFO - Using the following test package requirements: 02:25:03 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 02:25:03 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:25:03 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 02:25:03 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 02:25:03 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'jsshell-mac.zip'], 02:25:03 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 02:25:03 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 02:25:03 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 02:25:03 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 02:25:03 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 02:25:03 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 02:25:03 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 02:25:03 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:25:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:25:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:25:03 INFO - Fetch https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:25:04 INFO - Content-Length response header: 52699688 02:25:04 INFO - Bytes received: 52699688 02:25:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:25:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 02:25:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:25:21 INFO - Fetch https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 02:25:23 INFO - Content-Length response header: 52699688 02:25:23 INFO - Bytes received: 52699688 02:25:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 02:25:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:25:39 INFO - Fetch https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 02:25:40 INFO - Content-Length response header: 14408811 02:25:40 INFO - Bytes received: 14408811 02:25:40 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')]} 02:25:40 INFO - trying https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:25:40 INFO - Downloading https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 02:25:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 02:25:42 INFO - Downloaded 71203800 bytes. 02:25:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:25:42 INFO - mkdir: /builds/slave/test/properties 02:25:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:25:42 INFO - Writing to file /builds/slave/test/properties/build_url 02:25:42 INFO - Contents: 02:25:42 INFO - build_url:https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 02:25:43 INFO - Running post-action listener: _resource_record_post_action 02:25:43 INFO - Running post-action listener: set_extra_try_arguments 02:25:43 INFO - [mozharness: 2017-05-03 09:25:43.266923Z] Finished download-and-extract step (success) 02:25:43 INFO - [mozharness: 2017-05-03 09:25:43.267028Z] Running populate-webroot step. 02:25:43 INFO - Running pre-action listener: _resource_record_pre_action 02:25:43 INFO - Running main action method: populate_webroot 02:25:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:25:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:25:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:25:43 INFO - '--tppagecycles', 02:25:43 INFO - '7'], 02:25:43 INFO - 'tests': ['tsvgm']}}, 02:25:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:25:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:25:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:25:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:25:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:25:43 INFO - 'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:25:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:25:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:25:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:25:43 INFO - 'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['damp', 'tps']}, 02:25:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:25:43 INFO - 'tests': ['damp', 'tps']}, 02:25:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['dromaeo_dom']}, 02:25:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:25:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 02:25:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 02:25:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['a11yr', 02:25:43 INFO - 'ts_paint', 02:25:43 INFO - 'tpaint', 02:25:43 INFO - 'sessionrestore', 02:25:43 INFO - 'sessionrestore_no_auto_restore', 02:25:43 INFO - 'tabpaint']}, 02:25:43 INFO - 'other-e10s': {'tests': ['a11yr', 02:25:43 INFO - 'ts_paint', 02:25:43 INFO - 'tpaint', 02:25:43 INFO - 'sessionrestore', 02:25:43 INFO - 'sessionrestore_no_auto_restore', 02:25:43 INFO - 'tabpaint']}, 02:25:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:25:43 INFO - 'ts_paint', 02:25:43 INFO - 'tpaint', 02:25:43 INFO - 'sessionrestore', 02:25:43 INFO - 'sessionrestore_no_auto_restore', 02:25:43 INFO - 'tabpaint']}, 02:25:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:25:43 INFO - 'ts_paint', 02:25:43 INFO - 'tpaint', 02:25:43 INFO - 'sessionrestore', 02:25:43 INFO - 'sessionrestore_no_auto_restore', 02:25:43 INFO - 'tabpaint']}, 02:25:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['a11yr', 02:25:43 INFO - 'ts_paint', 02:25:43 INFO - 'tpaint', 02:25:43 INFO - 'sessionrestore', 02:25:43 INFO - 'sessionrestore_no_auto_restore', 02:25:43 INFO - 'tabpaint']}, 02:25:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['a11yr', 02:25:43 INFO - 'ts_paint', 02:25:43 INFO - 'tpaint', 02:25:43 INFO - 'sessionrestore', 02:25:43 INFO - 'sessionrestore_no_auto_restore', 02:25:43 INFO - 'tabpaint']}, 02:25:43 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 02:25:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 02:25:43 INFO - 'bloom_basic_ref']}, 02:25:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['tsvgx', 02:25:43 INFO - 'tsvgr_opacity', 02:25:43 INFO - 'tart', 02:25:43 INFO - 'tscrollx', 02:25:43 INFO - 'cart', 02:25:43 INFO - 'tsvg_static']}, 02:25:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:25:43 INFO - 'tsvgr_opacity', 02:25:43 INFO - 'tart', 02:25:43 INFO - 'tscrollx', 02:25:43 INFO - 'cart', 02:25:43 INFO - 'tsvg_static']}, 02:25:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:25:43 INFO - 'talos_options': ['--disable-e10s'], 02:25:43 INFO - 'tests': ['tp5o']}, 02:25:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:25:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:25:43 INFO - 'talos_options': ['--disable-e10s', 02:25:43 INFO - '--xperf_path', 02:25:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:25:43 INFO - 'tests': ['tp5n']}, 02:25:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:25:43 INFO - 'talos_options': ['--xperf_path', 02:25:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:25:43 INFO - 'tests': ['tp5n']}}, 02:25:43 INFO - 'talos.zip': {'path': '', 02:25:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:25:43 INFO - Running post-action listener: _resource_record_post_action 02:25:43 INFO - [mozharness: 2017-05-03 09:25:43.285761Z] Finished populate-webroot step (success) 02:25:43 INFO - [mozharness: 2017-05-03 09:25:43.285901Z] Running create-virtualenv step. 02:25:43 INFO - Running pre-action listener: _resource_record_pre_action 02:25:43 INFO - Running main action method: create_virtualenv 02:25:43 INFO - Creating virtualenv /builds/slave/test/build/venv 02:25:43 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 02:25:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:25:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:25:43 INFO - Using real prefix '/tools/python27' 02:25:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:25:44 INFO - Installing distribute.............................................................................................................................................................................................done. 02:25:46 INFO - Installing pip.................done. 02:25:46 INFO - Return code: 0 02:25:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:25:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:25:47 INFO - Reading from file tmpfile_stdout 02:25:47 INFO - Output received: 02:25:47 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) 02:25:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25: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')]} 02:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:47 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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:25:47 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 02:25:47 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 02:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:47 INFO - 'HOME': '/Users/cltbld', 02:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:47 INFO - 'LOGNAME': 'cltbld', 02:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:47 INFO - 'MOZ_NO_REMOTE': '1', 02:25:47 INFO - 'NO_EM_RESTART': '1', 02:25:47 INFO - 'PAGER': '/bin/cat', 02:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:47 INFO - 'PWD': '/builds/slave/test', 02:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:47 INFO - 'SHELL': '/bin/bash', 02:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:25:47 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:47 INFO - 'USER': 'cltbld', 02:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:47 INFO - 'XPC_FLAGS': '0x0', 02:25:47 INFO - 'XPC_SERVICE_NAME': '0', 02:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:47 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 02:25:47 INFO - Cleaning up... 02:25:47 INFO - Return code: 0 02:25:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25: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')]} 02:25:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:47 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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:25:47 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 02:25:47 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 02:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:47 INFO - 'HOME': '/Users/cltbld', 02:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:47 INFO - 'LOGNAME': 'cltbld', 02:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:47 INFO - 'MOZ_NO_REMOTE': '1', 02:25:47 INFO - 'NO_EM_RESTART': '1', 02:25:47 INFO - 'PAGER': '/bin/cat', 02:25:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:47 INFO - 'PWD': '/builds/slave/test', 02:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:47 INFO - 'SHELL': '/bin/bash', 02:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:25:47 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:47 INFO - 'USER': 'cltbld', 02:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:47 INFO - 'XPC_FLAGS': '0x0', 02:25:47 INFO - 'XPC_SERVICE_NAME': '0', 02:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:47 INFO - Downloading/unpacking psutil>=3.1.1 02:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:47 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 02:25:47 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 02:25:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:25:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:25:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:25:50 INFO - Installing collected packages: psutil 02:25:50 INFO - Running setup.py install for psutil 02:25:50 INFO - building 'psutil._psutil_osx' extension 02:25:50 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 02:25:50 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 02:25:50 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 02:25:50 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 02:25:50 INFO - building 'psutil._psutil_posix' extension 02:25:50 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 02:25:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:25:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:25:50 INFO - ^ 02:25:50 INFO - 1 warning generated. 02:25:50 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 02:25:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:25:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:25:50 INFO - Successfully installed psutil 02:25:50 INFO - Cleaning up... 02:25:51 INFO - Return code: 0 02:25:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:25:51 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')]} 02:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:51 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')]} 02:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:25:51 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 02:25:51 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 02:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:51 INFO - 'HOME': '/Users/cltbld', 02:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:51 INFO - 'LOGNAME': 'cltbld', 02:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:51 INFO - 'MOZ_NO_REMOTE': '1', 02:25:51 INFO - 'NO_EM_RESTART': '1', 02:25:51 INFO - 'PAGER': '/bin/cat', 02:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:51 INFO - 'PWD': '/builds/slave/test', 02:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:51 INFO - 'SHELL': '/bin/bash', 02:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:25:51 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:51 INFO - 'USER': 'cltbld', 02:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:51 INFO - 'XPC_FLAGS': '0x0', 02:25:51 INFO - 'XPC_SERVICE_NAME': '0', 02:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:52 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 02:25:52 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 02:25:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:25:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:25:54 INFO - Installing collected packages: mozsystemmonitor 02:25:54 INFO - Running setup.py install for mozsystemmonitor 02:25:54 INFO - Successfully installed mozsystemmonitor 02:25:54 INFO - Cleaning up... 02:25:54 INFO - Return code: 0 02:25:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:25: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')]} 02:25:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25: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')]} 02:25:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25: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', '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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:25: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:25: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 jsonschema==2.5.1 02:25:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:25:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:54 INFO - 'HOME': '/Users/cltbld', 02:25:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:54 INFO - 'LOGNAME': 'cltbld', 02:25:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:54 INFO - 'MOZ_NO_REMOTE': '1', 02:25:54 INFO - 'NO_EM_RESTART': '1', 02:25:54 INFO - 'PAGER': '/bin/cat', 02:25:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:54 INFO - 'PWD': '/builds/slave/test', 02:25:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:54 INFO - 'SHELL': '/bin/bash', 02:25:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:25:54 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:25:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:54 INFO - 'USER': 'cltbld', 02:25:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:54 INFO - 'XPC_FLAGS': '0x0', 02:25:54 INFO - 'XPC_SERVICE_NAME': '0', 02:25:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:54 INFO - Downloading/unpacking jsonschema==2.5.1 02:25: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 02:25: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 02:25: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 02:25: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 02:25: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 02:25: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 02:25:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:25:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:25:55 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 02:25:55 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 02:25:55 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 02:25:55 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 02:25:55 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 02:25:55 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 02:25:56 INFO - Downloading functools32-3.2.3-2.tar.gz 02:25:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:25:56 INFO - warning: no files found matching '*.txt' 02:25:56 INFO - no previously-included directories found matching 'build' 02:25:56 INFO - no previously-included directories found matching 'dist' 02:25:56 INFO - no previously-included directories found matching '.git*' 02:25:56 INFO - Installing collected packages: jsonschema, functools32 02:25:56 INFO - Running setup.py install for functools32 02:25:56 INFO - warning: no files found matching '*.txt' 02:25:56 INFO - no previously-included directories found matching 'build' 02:25:56 INFO - no previously-included directories found matching 'dist' 02:25:56 INFO - no previously-included directories found matching '.git*' 02:25:56 INFO - Successfully installed jsonschema functools32 02:25:56 INFO - Cleaning up... 02:25:56 INFO - Return code: 0 02:25:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:25:56 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')]} 02:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:56 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')]} 02:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:25:56 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 02:25:56 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 02:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:56 INFO - 'HOME': '/Users/cltbld', 02:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:56 INFO - 'LOGNAME': 'cltbld', 02:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:56 INFO - 'MOZ_NO_REMOTE': '1', 02:25:56 INFO - 'NO_EM_RESTART': '1', 02:25:56 INFO - 'PAGER': '/bin/cat', 02:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:56 INFO - 'PWD': '/builds/slave/test', 02:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:56 INFO - 'SHELL': '/bin/bash', 02:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:25:56 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:56 INFO - 'USER': 'cltbld', 02:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:56 INFO - 'XPC_FLAGS': '0x0', 02:25:56 INFO - 'XPC_SERVICE_NAME': '0', 02:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:25:56 INFO - Cleaning up... 02:25:56 INFO - Return code: 0 02:25:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:25:56 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')]} 02:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:56 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')]} 02:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:25:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:25:56 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 02:25:56 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 02:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:25:56 INFO - 'HOME': '/Users/cltbld', 02:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:25:56 INFO - 'LOGNAME': 'cltbld', 02:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:25:56 INFO - 'MOZ_NO_REMOTE': '1', 02:25:56 INFO - 'NO_EM_RESTART': '1', 02:25:56 INFO - 'PAGER': '/bin/cat', 02:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:25:56 INFO - 'PWD': '/builds/slave/test', 02:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:25:56 INFO - 'SHELL': '/bin/bash', 02:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:25:56 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:25:56 INFO - 'USER': 'cltbld', 02:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:25:56 INFO - 'XPC_FLAGS': '0x0', 02:25:56 INFO - 'XPC_SERVICE_NAME': '0', 02:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:25:56 INFO - Downloading/unpacking blobuploader==1.2.4 02:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:56 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 02:25:56 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 02:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:25:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:25:58 INFO - Downloading blobuploader-1.2.4.tar.gz 02:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:25:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:25:58 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 02:25:58 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 02:25:58 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 02:25:58 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 02:25:58 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 02:25:58 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 02:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:25:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:25:58 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 02:25:58 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 02:25:58 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 02:25:58 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 02:25:58 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 02:25:58 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 02:25:59 INFO - Downloading docopt-0.6.1.tar.gz 02:25:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:25:59 INFO - Installing collected packages: blobuploader, requests, docopt 02:25:59 INFO - Running setup.py install for blobuploader 02:25:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:25:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:25:59 INFO - Running setup.py install for requests 02:25:59 INFO - Running setup.py install for docopt 02:25:59 INFO - Successfully installed blobuploader requests docopt 02:25:59 INFO - Cleaning up... 02:26:00 INFO - Return code: 0 02:26:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26:00 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')]} 02:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:00 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')]} 02:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:00 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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:26:00 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 02:26:00 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 02:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:00 INFO - 'HOME': '/Users/cltbld', 02:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:00 INFO - 'LOGNAME': 'cltbld', 02:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:00 INFO - 'MOZ_NO_REMOTE': '1', 02:26:00 INFO - 'NO_EM_RESTART': '1', 02:26:00 INFO - 'PAGER': '/bin/cat', 02:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:00 INFO - 'PWD': '/builds/slave/test', 02:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:00 INFO - 'SHELL': '/bin/bash', 02:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:26:00 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:00 INFO - 'USER': 'cltbld', 02:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:00 INFO - 'XPC_FLAGS': '0x0', 02:26:00 INFO - 'XPC_SERVICE_NAME': '0', 02:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:26:00 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-CHGch7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:26:00 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-1Nzr0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:26:00 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-kclx6O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:26:00 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Om5JLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:26:00 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-nlcbF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:26:00 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-r8VRc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:26:00 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-e1W2kB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-j30jSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-yKDekR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-s8n1mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-MZ1uDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TJXG6p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-tukhLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-50jLgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-KTd6MO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-muuvhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:26:01 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-2AsKnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:26:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:26:02 INFO - Running setup.py install for manifestparser 02:26:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:26:02 INFO - Running setup.py install for mozcrash 02:26:02 INFO - Running setup.py install for mozdebug 02:26:02 INFO - Running setup.py install for mozdevice 02:26:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:26:02 INFO - Running setup.py install for mozfile 02:26:02 INFO - Running setup.py install for mozhttpd 02:26:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:26:02 INFO - Running setup.py install for mozinfo 02:26:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for mozInstall 02:26:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for mozleak 02:26:03 INFO - Running setup.py install for mozlog 02:26:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for moznetwork 02:26:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:26:03 INFO - Running setup.py install for mozprocess 02:26:03 INFO - Running setup.py install for mozprofile 02:26:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:26:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:26:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:26:04 INFO - Running setup.py install for mozrunner 02:26:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:26:04 INFO - Running setup.py install for mozscreenshot 02:26:04 INFO - Running setup.py install for moztest 02:26:04 INFO - Running setup.py install for mozversion 02:26:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:26:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:26:04 INFO - Cleaning up... 02:26:04 INFO - Return code: 0 02:26:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26:04 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')]} 02:26:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:04 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')]} 02:26:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:04 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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:26:04 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 02:26:04 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 02:26:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:26:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:04 INFO - 'HOME': '/Users/cltbld', 02:26:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:04 INFO - 'LOGNAME': 'cltbld', 02:26:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:04 INFO - 'MOZ_NO_REMOTE': '1', 02:26:04 INFO - 'NO_EM_RESTART': '1', 02:26:04 INFO - 'PAGER': '/bin/cat', 02:26:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:04 INFO - 'PWD': '/builds/slave/test', 02:26:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:04 INFO - 'SHELL': '/bin/bash', 02:26:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:26:04 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:26:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:04 INFO - 'USER': 'cltbld', 02:26:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:04 INFO - 'XPC_FLAGS': '0x0', 02:26:04 INFO - 'XPC_SERVICE_NAME': '0', 02:26:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:26:04 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-AubLxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-xJjLXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-FzchHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-D76zA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:26:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-EI3dK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-3w_sLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-RZOqyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-AWU2IC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-IVwQfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-7tjiXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:26:05 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)) 02:26:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:26:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-Hrfb2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:26:06 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)) 02:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:26:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-2opDlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:26:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:26:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-2G7OBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:26:06 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)) 02:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:26:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-JpS92Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:26:06 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)) 02:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-MPvGRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:26:06 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)) 02:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:26:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-UGNJfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:26:06 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)) 02:26:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:26:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-AZuEUN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:26:06 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)) 02:26:06 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)) 02:26:06 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)) 02:26:06 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)) 02:26:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:26:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:26:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:26: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 02:26: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 02:26: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 02:26: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 02:26: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 02:26: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 02:26:07 INFO - Downloading blessings-1.6.tar.gz 02:26:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:26:08 INFO - Installing collected packages: blessings 02:26:08 INFO - Running setup.py install for blessings 02:26:08 INFO - Successfully installed blessings 02:26:08 INFO - Cleaning up... 02:26:08 INFO - Return code: 0 02:26:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:26:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:26:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:26:08 INFO - Reading from file tmpfile_stdout 02:26:08 INFO - Current package versions: 02:26:08 INFO - blessings == 1.6 02:26:08 INFO - blobuploader == 1.2.4 02:26:08 INFO - docopt == 0.6.1 02:26:08 INFO - functools32 == 3.2.3-2 02:26:08 INFO - jsonschema == 2.5.1 02:26:08 INFO - manifestparser == 1.1 02:26:08 INFO - mozInstall == 1.12 02:26:08 INFO - mozcrash == 1.0 02:26:08 INFO - mozdebug == 0.1 02:26:08 INFO - mozdevice == 0.50 02:26:08 INFO - mozfile == 1.2 02:26:08 INFO - mozhttpd == 0.7 02:26:08 INFO - mozinfo == 0.9 02:26:08 INFO - mozleak == 0.1 02:26:08 INFO - mozlog == 3.4 02:26:08 INFO - moznetwork == 0.27 02:26:08 INFO - mozprocess == 0.25 02:26:08 INFO - mozprofile == 0.28 02:26:08 INFO - mozrunner == 6.13 02:26:08 INFO - mozscreenshot == 0.1 02:26:08 INFO - mozsystemmonitor == 0.3 02:26:08 INFO - moztest == 0.8 02:26:08 INFO - mozversion == 1.4 02:26:08 INFO - psutil == 3.1.1 02:26:08 INFO - requests == 1.2.3 02:26:08 INFO - wsgiref == 0.1.2 02:26:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:26:08 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')]} 02:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:08 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')]} 02:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:08 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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:26:08 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 02:26:08 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 02:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:08 INFO - 'HOME': '/Users/cltbld', 02:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:08 INFO - 'LOGNAME': 'cltbld', 02:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:08 INFO - 'MOZ_NO_REMOTE': '1', 02:26:08 INFO - 'NO_EM_RESTART': '1', 02:26:08 INFO - 'PAGER': '/bin/cat', 02:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:08 INFO - 'PWD': '/builds/slave/test', 02:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:08 INFO - 'SHELL': '/bin/bash', 02:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:26:08 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:08 INFO - 'USER': 'cltbld', 02:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:08 INFO - 'XPC_FLAGS': '0x0', 02:26:08 INFO - 'XPC_SERVICE_NAME': '0', 02:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 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)) 02:26:08 INFO - Cleaning up... 02:26:08 INFO - Return code: 0 02:26:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:26:08 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')]} 02:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:26:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:08 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')]} 02:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:26:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:26:08 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 0x10af9fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10afa2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbb624d87f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, '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.cAQyOGUfWc/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.c28yd7tBGP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 02:26:08 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 02:26:08 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 02:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:08 INFO - 'HOME': '/Users/cltbld', 02:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:08 INFO - 'LOGNAME': 'cltbld', 02:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:08 INFO - 'MOZ_NO_REMOTE': '1', 02:26:08 INFO - 'NO_EM_RESTART': '1', 02:26:08 INFO - 'PAGER': '/bin/cat', 02:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:08 INFO - 'PWD': '/builds/slave/test', 02:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:08 INFO - 'SHELL': '/bin/bash', 02:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:26:08 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:08 INFO - 'USER': 'cltbld', 02:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:08 INFO - 'XPC_FLAGS': '0x0', 02:26:08 INFO - 'XPC_SERVICE_NAME': '0', 02:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:26:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:26:09 INFO - Cleaning up... 02:26:09 INFO - Return code: 0 02:26:09 INFO - Running post-action listener: _resource_record_post_action 02:26:09 INFO - Running post-action listener: _start_resource_monitoring 02:26:09 INFO - Starting resource monitoring. 02:26:09 INFO - [mozharness: 2017-05-03 09:26:09.174391Z] Finished create-virtualenv step (success) 02:26:09 INFO - [mozharness: 2017-05-03 09:26:09.175368Z] Running install step. 02:26:09 INFO - Running pre-action listener: _resource_record_pre_action 02:26:09 INFO - Running main action method: install 02:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:26:09 INFO - Reading from file tmpfile_stdout 02:26:09 INFO - Detecting whether we're running mozinstall >=1.0... 02:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:26:09 INFO - Reading from file tmpfile_stdout 02:26:09 INFO - Output received: 02:26:09 INFO - Usage: mozinstall [options] installer 02:26:09 INFO - Options: 02:26:09 INFO - -h, --help show this help message and exit 02:26:09 INFO - -d DEST, --destination=DEST 02:26:09 INFO - Directory to install application into. [default: 02:26:09 INFO - "/builds/slave/test"] 02:26:09 INFO - --app=APP Application being installed. [default: firefox] 02:26:09 INFO - mkdir: /builds/slave/test/build/application 02:26:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:26:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:26:28 INFO - Reading from file tmpfile_stdout 02:26:28 INFO - Output received: 02:26:28 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:26:28 INFO - Running post-action listener: _resource_record_post_action 02:26:28 INFO - [mozharness: 2017-05-03 09:26:28.881567Z] Finished install step (success) 02:26:28 INFO - [mozharness: 2017-05-03 09:26:28.881682Z] Running run-tests step. 02:26:28 INFO - Running pre-action listener: _resource_record_pre_action 02:26:28 INFO - Running main action method: run_tests 02:26:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:26:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:26:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:26:28 INFO - 2017-05-03 02:26:28.894 screenresolution[2345:31101] starting screenresolution argv=screenresolution get 02:26:28 INFO - 2017-05-03 02:26:28.896 screenresolution[2345:31101] Display 0: 1600x1200x32@60 02:26:28 INFO - 2017-05-03 02:26:28.901 screenresolution[2346:31103] starting screenresolution argv=screenresolution list 02:26:28 INFO - Available Modes on Display 0 02:26:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:26:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:26:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:26:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:26:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:26:29 INFO - Intel Iris: 02:26:29 INFO - Chipset Model: Intel Iris 02:26:29 INFO - Type: GPU 02:26:29 INFO - Bus: Built-In 02:26:29 INFO - VRAM (Dynamic, Max): 1536 MB 02:26:29 INFO - Vendor: Intel (0x8086) 02:26:29 INFO - Device ID: 0x0a2e 02:26:29 INFO - Revision ID: 0x0009 02:26:29 INFO - Displays: 02:26:29 INFO - Display: 02:26:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:26:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:26:29 INFO - Display Serial Number: 1600x1200 60 02:26:29 INFO - Main Display: Yes 02:26:29 INFO - Mirror: Off 02:26:29 INFO - Online: Yes 02:26:29 INFO - Rotation: Supported 02:26:29 INFO - Return code: 0 02:26:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:26:29 INFO - Python 2.7.3 02:26:29 INFO - Return code: 0 02:26:29 INFO - grabbing minidump binary from tooltool 02:26: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')]} 02:26:29 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 0x10ad7dd30>, '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 0x10aca3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ad72be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:26:29 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 02:26:29 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 02:26:29 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 02:26:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:26:29 INFO - Return code: 0 02:26:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:26:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:26:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:26:29 INFO - ENV: RUST_BACKTRACE is now 1 02:26:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:26:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:26:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:26:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:26:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cAQyOGUfWc/Render', 02:26:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:26:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:26:29 INFO - 'HOME': '/Users/cltbld', 02:26:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:26:29 INFO - 'LOGNAME': 'cltbld', 02:26:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:26:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:26:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:26:29 INFO - 'MOZ_NO_REMOTE': '1', 02:26:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:26:29 INFO - 'NO_EM_RESTART': '1', 02:26:29 INFO - 'PAGER': '/bin/cat', 02:26:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:26:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:26:29 INFO - 'PWD': '/builds/slave/test', 02:26:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:26:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:26:29 INFO - 'RUST_BACKTRACE': '1', 02:26:29 INFO - 'SHELL': '/bin/bash', 02:26:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.c28yd7tBGP/Listeners', 02:26:29 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 02:26:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:26:29 INFO - 'USER': 'cltbld', 02:26:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:26:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:26:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:26:29 INFO - 'XPC_FLAGS': '0x0', 02:26:29 INFO - 'XPC_SERVICE_NAME': '0', 02:26:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:26:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:26:29 INFO - mozversion application_buildid: 20170503011121 02:26:29 INFO - mozversion application_changeset: 604acb6a6aece0d9f296dbb7ca091f45713c0c9d 02:26:29 INFO - mozversion application_display_name: Nightly 02:26:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:26:29 INFO - mozversion application_name: Firefox 02:26:29 INFO - mozversion application_remotingname: firefox 02:26:29 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:26:29 INFO - mozversion application_vendor: Mozilla 02:26:29 INFO - mozversion application_version: 55.0a1 02:26:29 INFO - mozversion platform_buildid: 20170503011121 02:26:29 INFO - mozversion platform_changeset: 604acb6a6aece0d9f296dbb7ca091f45713c0c9d 02:26:29 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:26:29 INFO - mozversion platform_version: 55.0a1 02:26:29 INFO - using testdate: 1493803589 02:26:29 INFO - actual date: 1493803589 02:26:29 INFO - starting webserver on 'localhost:58816' 02:26:29 INFO - SUITE-START | Running 2 tests 02:26:29 INFO - TEST-START | dromaeo_css 02:26:29 INFO - operating with platform_type : mac_ 02:26:29 INFO - Initialising browser for dromaeo_css test... 02:26:29 INFO - TEST-INFO | started process 2356 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpprLiNJ/profile http://localhost:58816/getInfo.html) 02:26:32 INFO - PID 2356 | __metrics Screen width/height:1600/1200 02:26:32 INFO - PID 2356 | colorDepth:24 02:26:32 INFO - PID 2356 | Browser inner width/height: 1024/691 02:26:32 INFO - PID 2356 | __metrics 02:26:32 INFO - PID 2356 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:26:32 INFO - PID 2356 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:26:33 INFO - TEST-INFO | 2356: exit 0 02:26:33 INFO - Browser initialized. 02:26:33 INFO - Running cycle 1/1 for dromaeo_css test... 02:26:33 INFO - TEST-INFO | started process 2358 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpprLiNJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:26:45 INFO - PID 2358 | RSS: Main: 240754688 02:26:45 INFO - PID 2358 | 02:29:50 INFO - PID 2358 | Cycle 1(1): loaded http://localhost:58816/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58816/tests/dromaeo/cssquery-ext.html) 02:29:52 INFO - PID 2358 | RSS: Main: 447909888 02:29:52 INFO - PID 2358 | 02:32:54 INFO - PID 2358 | Cycle 1(1): loaded http://localhost:58816/tests/dromaeo/cssquery-ext.html (next: http://localhost:58816/tests/dromaeo/cssquery-jquery.html) 02:32:55 INFO - PID 2358 | RSS: Main: 368689152 02:32:55 INFO - PID 2358 | 02:35:57 INFO - PID 2358 | Cycle 1(1): loaded http://localhost:58816/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58816/tests/dromaeo/cssquery-mootools.html) 02:35:58 INFO - PID 2358 | RSS: Main: 335097856 02:35:58 INFO - PID 2358 | 02:39:00 INFO - PID 2358 | Cycle 1(1): loaded http://localhost:58816/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58816/tests/dromaeo/cssquery-prototype.html) 02:39:01 INFO - PID 2358 | RSS: Main: 331087872 02:39:01 INFO - PID 2358 | 02:42:04 INFO - PID 2358 | Cycle 1(1): loaded http://localhost:58816/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58816/tests/dromaeo/cssquery-yui.html) 02:42:05 INFO - PID 2358 | RSS: Main: 333758464 02:42:05 INFO - PID 2358 | 02:45:07 INFO - PID 2358 | Cycle 1(1): loaded http://localhost:58816/tests/dromaeo/cssquery-yui.html (next: http://localhost:58816/tests/dromaeo/cssquery-dojo.html) 02:45:08 INFO - PID 2358 | RSS: Main: 372842496 02:45:08 INFO - PID 2358 | 02:45:08 INFO - PID 2358 | __start_tp_report 02:45:08 INFO - PID 2358 | _x_x_mozilla_page_load 02:45:08 INFO - PID 2358 | _x_x_mozilla_page_load_details 02:45:08 INFO - PID 2358 | |i|pagename|runs| 02:45:08 INFO - PID 2358 | |0;dojo.html;3521.137321356268;3542.256126213497;3565.946510802338;3581.086822860171;3614.367485690005;6091.756329746805;6137.223770008885;6209.192804935357;6227.972384556549;6241.531885108612;6129.2365380683905;6141.363270730603;6220.346863579315;6223.782167624134;6239.937600623993;4612;4954.702717836942;5290.497402746733;5305.283786688786;5338.759755810988;5535.142052981794;5575.386707462176;5585.720713964285;5595.720213989304;5631.8592035199035;5352.036633405985;5439.673619582838;5451.073317536028;5479.506844383985;5498.3127109111365;5446.2375267462585;5456.536194423479;5458.099413596752;5461.5357694596005;5466.207399927028;5435.347758269033;5493.807716729935;5522.806701765419;5548.389677135512;5558.388577256498;6205.6085907000415;6245.063240513914;6262.843428914268;6265.279492858334;6270.905936410957;6557.836054098638;6609.1408116944485;6648.900266496006;6657.434118099967;6659.800205993828;6513.1532900722605;6517.543771935966;6535.705893234815;6629.270780214172;6702.229243636995;6188.845278868064;6256.998880179195;6270.31026587075;6276.278228003746;6283.59156654816;6854.34883686059;6855.143107111611;6868.244493105754;6869.278725733826;6885.758998435131;6324.683765811783;6372.267189273293;6397.840053998687;6500.9349906501275;6515.413612774873;6607.504437167232;6672.899906501407;6706.53054286205;6716.798496045127;6716.83207919806;6463.159789227464;6555.115059466911;6555.311692272338;6555.639439830759;6568.638724870082;6338.271098823602;6614.437772789271;6617.2059352877;6618.768343107968;6623.86752264952;5221.503957124067;5222.529972302511;5223.4515375884985;5229.607779416559;5248.186531087687;4618.95381046192;4624.630029597624;4694;4740.834070807574;4750.334953106568;5171.4311425743135;6159.076138579249;6212.658303793244;6219.346968568326;6228.34602366754;5621.662700237999;6637.203535575762;6641.568298060611;6656.667166641649;6684.097646817715;6266.717997690115;6267.968660156761;6283;6283.622982621057;6293.30773614902;5837.824865254049;5856.619319744288;5918.201042859159;5944.494717948936;5968.612040217372;6739.966300168468;6794;6795.660216989229;6798.490113241625;6916.135483064617;6557.836054098686;6583.3087525811015;6584.967075164594;6639.070530125883;6652.135222421054;6950;6964.059851919926;6969.233384327618;6969.7909062728195;6989.965050174716;6627.039079333374;6637.236717777324;6642.103316052464;6644.235912870082;6651.235107962452;6373.521985850987;6547.247066587403;6677.632730199885;6698.196216454056;6723.193216814014;6355.809325720235;6360.650164241011;6367.649779261999;6370.490360771035;6389.201349831271;6326.8734625306315;6332.588381755171;6353.491720662429;6458.870822583616;6498.902516462352;6349.460295874902;6417.871642567029;6421.197350331208;6429.196350456193;6432.2602900667025;6527.771527996498;6545.443637290884;6546.0508226305965;6551.6069035858;6557.540972131905;6199.442050215322;6223.564350495421;6231.158793562992;6254.906176407449;6302.495800335871 02:45:08 INFO - PID 2358 | |1;ext.html;671.2314400011985;803.232912568497;956.3926906414429;1105.3975583307097;1116.9273997190178;31001.534976975334;33666.66333336672;33707.831460842695;33741.9877403678;33776.49335259967;32458.863939762112;34112;36181.457278140784;36479.81760091202;36603.4509482358;5849.239598852165;5938.435848594377;8001.6799328026955;8146.714864979727;8148.470349427298;8022.478538894982;8114.985626796651;8141.104478507359;8143.714969976052;8163.9591802041205;8180.713675021376;8216.260536551708;8225.917740822575;8227.917720822805;8228.794280142984;5906.557008224358;5995.940040599626;8133.633986470623;8187.304079153219;8188.877166842532;7855.6464959076975;7931.087924888654;7978.840423191504;7981.680732770682;7998.12020677725;33900.49149262763;34186.82906585476;34218.65781342205;34284.657153428394;34342.82828585866;2953.9852300738576;3539.610642829287;3539.6460353964653;3545.3263879862743;3555.484454754072;28282.717172828216;28406.289842754097;28434.289142771388;28441;28473.288167795763;3114.7073964304786;3143.5441860930036;3178.253110519026;3193.217661672905;3209.871605135792;280367;286519.56740216166;286649.26701465854;286787;286864;36847;36990;37002.074948126516;45899.77050114728;45908.770456147504;37989.860304190915;38007.04982375463;38064.85805425842;46551.30173047407;47017.76491117591;13337.999650026286;13428.462861485626;13436.99222558312;13488.662783430296;13526;11113.888861111447;11126.443677816238;11129.998300153025;11138.33170009802;11145.888541114486;11525.88474115248;11545.826812597817;11580.247283926687;11614.825777613343;11663.475143618558;4217.283061879488;4663.647542212788;5041.294218809369;5077.720725360141;5102.719350435762;971.2958105373548;985.6796541124163;1010.661428421487;1018.8267994440963;1053.5733028123623;23620.291391258288;23731.932063056858;23747.40631484227;23749.287521374386;23824.64263036056;1107.141964977133;1107.4075369677184;1144.8339990701302;1158;1169.9883001170049;12039.277643341253;12145.295684816216;12171.208871423329;12176.147669663216;12219.633410997685;306780;307766;307944;308290;309623;48503.514964850605;48613.513864861605;48619;48628.75685621549;48775.26837097447;1498.7452133137174;1507.17105591924;1532.9846701533065;1545.7217700813737;1558.290977605187;23349;23368.415789605395;23392;23436.312737452918;23628.645570316803;36040.4593931086;36087.45868811917;36114;36155.457668135525;36165;267561;268116;268788;268793;268902;27929.581056283765;27949;30951.226219344695;31859;31929;39678;40218.39672404975;40219.3967090488;40336.1932761349;40467.190656187304;26456.338591534597;27001.594976074983;27457.725422746316;27916.860415697793;29244;21527.677084843428;28280.434391311646;28499;28805;28924.42151157007;32871;33231.50152747663;33923.32153356968;33977.66022339745;33980.49029264612;27697;28158;28870.566941495475;29022.12933611995;29090;19315;21459.570808584052;21526.89236553807;22837.429064273525;22857.42856428536 02:45:08 INFO - PID 2358 | |2;jquery.html;431.6719293337063;438.0528550592616;440.693717866083;443.5453659998501;460.917034933712;20844.583108337843;21507;21837.563248735034;22628.77371226287;22967.770322296772;22869.313920582408;22869.542609147848;23171.536569268603;23592.410189745264;23892.761072389272;5939.821805345847;5992.730327135289;6175.320714721377;6177.969110154466;6185.195924529805;6049.274087109553;6176.382361763833;6177.135201071854;6178.135061091429;6178.969105154468;5951.464368206861;6113.449789518942;6139.140520327158;6150.84622884427;6203.193584833965;6154.5999510031925;6177.289611694623;6204.6587437690905;6204.6587437690905;6205.472534834545;5364.7317634118135;5437.728113594305;5514.200440936042;5517.475839795213;5532.612717109804;27485.86257068702;28111;28133.437331253466;28146.577801333;28174.43651126966;2155.7844215578316;2222.411061068818;2228.4985878177445;2229.0749339024287;2232.162938897913;17551.648967020716;17579.648407031917;17851.642967140713;17968.101594920332;18091.638167236713;2117.110813458336;2127.5638494108825;2175.641019231825;2177.4338671945407;2179.8147157491476;155627;157288.21355893262;160459;161325.19337403355;165743.9999999988;12333.136680432284;12390.344848269602;12490.562830300896;12627.684307892376;12651.430685618985;16651.25069371881;16651.41720039793;16667.83332166687;16670.499885003468;16675.24961376741;6672.99905014242;8186.386021048445;8948.955255223813;8956.104389560991;9109.225715814098;8503.574821258911;8527.360447966428;8530.274926631306;8692.130786921383;8724.127587241224;6603.834904127435;7470.663820128108;7531.736389226351;7659.042619672541;7674.693012279558;2105.757837848667;2107.3993911735083;2119.841011924081;2127.489402543378;2276.931692049241;940.4921342474987;961.624966263158;967.8820961789145;1003.7139415266614;1011.6509804117568;20393;20635.380938571867;21128.471788205417;21236.469088272606;21351;977.1449981266392;981.6417007792104;1010.5048526222098;1012.8379459286479;1013.4881884648205;9528.142467177988;10569.312994655476;10726.624568140078;10736;10764.461776911123;546115;548928;556083;566466;567496;119553.40223299002;125191;125414;125954.3702281501;127567;1122.5734220995969;1123.752774389633;1168.0830548019765;1171.3616079236836;1172.8299396587447;17839.821601783817;17974.640507190044;17982.730259046388;17994.5501362467;18368.816311836712;56372.8725425481;56494;56819.86360272689;56827.43172568387;57915;59736.402635973085;73438;75922.86115708158;76442.12020918143;76784.61607691926;12797.360131993177;13244.735105297646;13483.662908427368;13507.594772156852;13536.052476326562;11871.643850684493;13618.659533511742;14194.432222710975;14400.639984000483;14611.561653150422;10033.548490317808;11556.364399958084;11794.46924888365;12061.216020958609;12140.514379424723;9350.27020001302;11030.393328367018;11385.658430247106;11485.19603627738;11642.76714465723;11990.340531270864;12124.636260912186;12147.75704485923;12295.016398688262;12337.568185113478;10955.56177752881;12835.550755723505;12889.162204456681;13145.751153640389;13246;11729.47217375203;11902.404879755804;12012.519499220283;12044.397780111134;12095.818562721743 02:45:08 INFO - PID 2358 | |3;mootools.html;1189.280485306389;1263.9936800315998;1391.5616580777057;1435.906666066705;1453.7746649269363;9771.804563908727;10195;11482.885171148297;11745.236559623618;11762.7647447051;9489.6204151834;11301.604443844468;11410.030147437457;11432;11445.2560583562;2488.5769419198737;2534.733852945442;2536.226450932465;2537.606670966003;2538.581134112869;1778.9288428462846;2183.366823621148;2474.6782918220606;2561.8462892226435;2567.6020216866414;2484.751524847519;2497.912573059943;2517.7859881909967;2519.0931264744677;2524.255344673326;2477.1206221791276;2481.950360992779;2496.10140349474;2500.8374455660323;2508.347829564308;2222.20000799712;2273.601734933004;2408.397900524869;2440.2069327468644;2444.5110977804334;11473.254238474556;11537.365444900528;11637.534498620045;11665.825012624817;11667.008304294146;6380.1705778249;6615.17310336208;6626.56927299724;6626.834329141761;6634.93365066348;3987.134421397505;4119.876403707864;4220.177065472234;4284.914301713949;4305.117450922553;1067.4982758103679;1246.9314187719672;1259.689922480619;1297.935103244834;1306.144475368635;16222.594435139097;16275.674486510203;16344.264508097163;16364.590885227964;16369.918150409172;5694.857628559319;5842.152887831241;5894.469497745224;5984.251968503937;5991.2510936132985;12983.091183617056;13090.280034598189;13398.928085753116;13407;13444.865551344557;6679.833004174934;6692.531522793358;6696.46428285736;6696.631685257357;6701.832454188684;5259.684418934876;5896.823095307148;6058.394160583906;6097.451229389376;6182.567220294536;3924.862629807943;3987.960120398759;4326.41593384889;4383.715058521186;4409.382686423904;1230.6955823613625;1257.2330878164312;1278.2458349573797;1278.6483716977905;1280.0399700224832;683.8837397642412;799.3165843204093;805.4603415711488;809.1301850510724;811.0308181722785;6176.722047507873;6574.638394888231;6605.86788264232;6823.430610959507;6840.897386539206;815.4984684419159;907.7458311672742;950.5437390052813;962.9470379129075;973.7176218896441;4159.812808423628;4261.231588890638;4264.637505811978;4374.234508960919;4436.733795972252;14285.928570357082;15067.4726384576;15069.020513666577;15084.698306034037;15089.849101509064;13913.652158695912;14674.046186997812;14683.045602035834;14690.632734181518;14692.191929443983;6926.757563485355;8223.54770487617;8460.238578527838;8483.787905302417;8489.95755021221;5640.60515763909;6461.450776684034;6711.89253773122;7060.293970602898;7094.219635840157;12829.294388808705;13298.869596084442;13679;13680.315984200552;13680.384382702603;12122.515099395925;13643;13753.724925501234;14105.858941410455;14181;6862.764702353625;9164.404313719588;9175.54122293896;9200.125988031126;9327.58025888823;8529.95735021321;9416.482093484925;9897.406155630686;10188.79622407533;10406.531706073094;7378.262173782578;8670.956645216986;8863.955680221557;8902.020777714575;9058.320625952949;8687.609057592297;8832.160944710242;8869.246114080117;8903.821923561363;8985.146411091197;7732.110807257012;7875;7905.288524032865;7912.4856884302335;7915.485493442908;5874.911876321944;5896.233489646319;5995.400459953969;6067.696615169136;6097.969510152421;4832.371791667061;5969.582319160747;6139.557204056954;6212.0371342441995;6295.653739044396 02:45:08 INFO - PID 2358 | |4;prototype.html;319.80971322063374;394.1820722001847;400.42339031794216;402.2317373816012;403.94546736190625;5208.479152084795;5853.853653658655;6022.54830887683;6098.14625952367;6106.419890110433;4601.79291931863;5027.698338099707;5884.6763428011445;5963.34403215647;6005.969970150154;4123.9793801030955;4251.128518653684;4472.977635111828;4580.175568397687;4589.25608268859;4548.8862778430475;4578.542145785428;4584.510034238869;4590.472095709003;4602.194615942213;4460.687751857371;4516.367708520794;4538.704984176018;4545.772711364435;4590.173768721629;4026.5570787213237;4218.915621687564;4530.478994915594;4530.841420550265;4551.954480455186;4000.6199411055904;4007.5591684914634;4029.959700403017;4033.1530378620523;4060.776657283848;5697.312279272582;5804.564657650651;5815.505682017034;5838.2118414013985;5840.678762668052;4640.466346370143;5328.5470734987575;5377.193420986844;5395.21769343443;5424.647397919162;6335;6355.459785918202;6367.554271201017;6381.266154392258;6414.679266036679;4385.868423947255;4508.504064552896;4538.7503687297185;4548.795304211319;4565.2467342888385;5751.482366586985;6357.968210158966;6559.672016399209;6592.340765923465;6615.5369124161325;3827.61723827615;4686.367340409001;4690.202665546865;4725.605946245797;4739.075880203327;3889.416587511896;3959.980200098981;4054.533728621246;4553.567411096007;4798.448178459452;4741.5495527924795;5096.541311282002;5247.239150323182;5349.368442624959;5415.1335786274;5585.832425027255;5614.438556144353;6368.5860419072615;6401.135846660734;6425.871482570323;1583.7070142023763;1621.4081860121207;1628.2510045379022;1639.7458393948957;1650.5378494021454;1062.697131317571;1111.855458790352;1138.5673444091192;1146.621614867092;1157.3518829455531;5908.497777688945;5926.614770039933;5928.970355148197;5935.901858156255;5950.9107363389585;5710.057840456361;5981.252343457068;6437.2918978912285;6445.713600455738;6480.449161821204;5329.680219186784;5784.768609255667;5847.152162936351;5879.911801322984;5946.959282125611;2846;2878.928026799305;3348.5814273215847;3388.9322213556084;3415.658434156614;6501.707423165969;6681.398674119353;6709.429698475684;6746.055552222692;6752.324767523307;5855.443732845373;5862.237909071793;6559.672016399161;6601.768938087144;6605.30644282353;5532.446755324436;6498.415142637185;6730.697118629673;6744.763933262312;6775.966120169368;5882.23530940975;6153.5692501526255;6536.477081833537;6545.836354091186;6558.819412505794;6174.598651087665;6294;6339.683015849097;6355.237371515448;6449.7097630605795;5499.395066542597;6143.600665956698;6615.460728510224;6804.693788779616;6939.826504337432;4119;4629.652776041743;4662.300654901792;4683.15703173432;4704.012157446974;6533.118029066015;6537.967310163419;6543.967280163569;6578.309277525982;6590.868182636416;4872;5150.244619128748;6032.155498230339;6349.619022858643;6383.93616063852;5141.71720555373;5851.327097383685;5935.584509084322;6295.653739044214;6369.777057802955;5903.586748927534;6150.200473938359;6258.874822503615;6282.497400207881;6292.622442653273;6212.596181248205;6263.467605253604;6325.746970121328;6362;6362.331955144643;4764.404449443819;6246.656433896179;6260.467860231749;6267.9971204607045;6278.560500764903 02:45:08 INFO - PID 2358 | |5;yui.html;826.9958650206748;846.775604464817;848.3340577646545;849.8272384109928;864.1833467373331;16187;16461.50615481536;16821.915890420532;16850.410235641724;16881.662366752673;15812.651520831778;15829.891907566474;15881.682366352667;15978;16028.919855400707;993.8857031441387;1004.4475538453821;1009.1876039787968;1032.5405194688328;1052.6263176572322;1039.2569312941246;1043.2488608202082;1043.2905624175557;1044.2116202267277;1044.7544826965657;1060.48566445274;1100.515773059855;1106.5739690219284;1107.6898468428853;1107.7507560798797;1088.9618863339745;1091.2633972068822;1091.9672409827717;1094.879563248046;1097.1716354152604;803.8914746509205;805.5247404031591;806.0850934189737;807.3621838747382;807.648672827322;16675.583110422216;16827.83172168275;17033.148342582823;17227.82772172287;17426.47720568385;17171.656566868594;17246.965182089116;17638.470845874643;17659.55851103733;17768.46694599164;14102;14117;14234.715305693931;14386;14421.351039203157;3498.4052711039185;3513.9121521962156;3516.43737002081;3522.1370764162943;3529.6470352964757;86572.13427865767;133480.3325983364;134930;136813;137510;9806.803863922682;11336.829947550794;13055.738885222243;13341.798603230553;13342;24434.38914027128;25042;25344.619830702555;25714.100006499684;25753.8712306441;8025.277725004661;8121.512709237465;8125.1062383137805;8129.105798362176;8134.023917129983;3873.6707379872464;3894.0264933766557;3902.180542086194;3950.2692001979726;3956.7032472564674;4356.934645980313;4434.5343738907595;4441.289393697058;4446.799894004713;4453.866384008485;785.2932360875258;932.2448816458555;953.1755031897497;966.2511553546058;967.0426277984744;659.515256286629;680.2789043613785;681.8840797064511;682.7678589279669;687.7524091327118;9985.301028927905;10304.639337623297;11788.764224715458;11909.464074116686;11928.224665396721;514.4675261104738;668.8963210702349;673.0678010954863;685.9211190713032;690.3890057299226;5427.9185812212845;5513.724313784295;5604.663720176803;5639.577031722637;5764.308283006066;80956.07015579616;94112.5883111754;110759;111045.33431998527;115918.42040789741;84897;86378;88499.55750221208;99072.50463747635;103345.48327258315;79700.0074998118;79721.20278797254;79726;80002.39995200063;84467.57766211253;12052.75894482123;12816.871831281567;12914.418851151533;12916.418761155583;12946.676333091747;28559.714402855705;38743.45026198921;38948;39358.60641393664;40663.796681017586;67731.66134169298;68722.62554748978;70122.64938675275;70537;71766.92349614656;15941.760873587138;16482.505524834272;16629.667406652043;16670.83329166741;16999;18088.186031628346;18507.537311567317;18641.90679046596;18646.53383665365;18678.53303667419;16509.761767867698;16744.832551674815;17116.315347385964;17319.047452389736;17351.913240433718;16451.67096658084;16653.41713040038;17125.57186070358;17163.570910727332;17467.38864139749;16443.260053297392;16850.410235641695;16877.831221687626;16878.071706056286;17074.402395916084;11261.099112070851;11271.887281127083;11673.416329183337;11691.707707307385;11934.343611101473;11405.49112895239;12828.10203285761;12860.807087893501;13771.517996870061;13996.790048149081 02:45:08 INFO - PID 2358 | __end_tp_report 02:45:08 INFO - PID 2358 | __start_cc_report 02:45:08 INFO - PID 2358 | _x_x_mozilla_cycle_collect,2191 02:45:08 INFO - PID 2358 | __end_cc_report 02:45:08 INFO - PID 2358 | __startTimestamp1493804708114__endTimestamp 02:45:08 INFO - PID 2358 | 02:45:08 INFO - PID 2358 | ------- Summary: start ------- 02:45:08 INFO - PID 2358 | Number of tests: 6 02:45:08 INFO - PID 2358 | 02:45:08 INFO - PID 2358 | [#0] dojo.html Cycles:165 Average:6119.81 Median:6326.87 stddev:717.06 (11.3%) stddev-sans-first:689.67 02:45:08 INFO - PID 2358 | Values: 3521.1 3542.3 3565.9 3581.1 3614.4 6091.8 6137.2 6209.2 6228.0 6241.5 6129.2 6141.4 6220.3 6223.8 6239.9 4612.0 4954.7 5290.5 5305.3 5338.8 5535.1 5575.4 5585.7 5595.7 5631.9 5352.0 5439.7 5451.1 5479.5 5498.3 5446.2 5456.5 5458.1 5461.5 5466.2 5435.3 5493.8 5522.8 5548.4 5558.4 6205.6 6245.1 6262.8 6265.3 6270.9 6557.8 6609.1 6648.9 6657.4 6659.8 6513.2 6517.5 6535.7 6629.3 6702.2 6188.8 6257.0 6270.3 6276.3 6283.6 6854.3 6855.1 6868.2 6869.3 6885.8 6324.7 6372.3 6397.8 6500.9 6515.4 6607.5 6672.9 6706.5 6716.8 6716.8 6463.2 6555.1 6555.3 6555.6 6568.6 6338.3 6614.4 6617.2 6618.8 6623.9 5221.5 5222.5 5223.5 5229.6 5248.2 4619.0 4624.6 4694.0 4740.8 4750.3 5171.4 6159.1 6212.7 6219.3 6228.3 5621.7 6637.2 6641.6 6656.7 6684.1 6266.7 6268.0 6283.0 6283.6 6293.3 5837.8 5856.6 5918.2 5944.5 5968.6 6740.0 6794.0 6795.7 6798.5 6916.1 6557.8 6583.3 6585.0 6639.1 6652.1 6950.0 6964.1 6969.2 6969.8 6990.0 6627.0 6637.2 6642.1 6644.2 6651.2 6373.5 6547.2 6677.6 6698.2 6723.2 6355.8 6360.7 6367.6 6370.5 6389.2 6326.9 6332.6 6353.5 6458.9 6498.9 6349.5 6417.9 6421.2 6429.2 6432.3 6527.8 6545.4 6546.1 6551.6 6557.5 6199.4 6223.6 6231.2 6254.9 6302.5 02:45:08 INFO - PID 2358 | 02:45:08 INFO - PID 2358 | [#1] ext.html Cycles:180 Average:42190.61 Median:3129.13 stddev:75536.30 (2414.0%) stddev-sans-first:75683.88 02:45:08 INFO - PID 2358 | Values: 671.2 803.2 956.4 1105.4 1116.9 31001.5 33666.7 33707.8 33742.0 33776.5 32458.9 34112.0 36181.5 36479.8 36603.5 5849.2 5938.4 8001.7 8146.7 8148.5 8022.5 8115.0 8141.1 8143.7 8164.0 8180.7 8216.3 8225.9 8227.9 8228.8 5906.6 5995.9 8133.6 8187.3 8188.9 7855.6 7931.1 7978.8 7981.7 7998.1 33900.5 34186.8 34218.7 34284.7 34342.8 2954.0 3539.6 3539.6 3545.3 3555.5 28282.7 28406.3 28434.3 28441.0 28473.3 3114.7 3143.5 3178.3 3193.2 3209.9 280367.0 286519.6 286649.3 286787.0 286864.0 36847.0 36990.0 37002.1 45899.8 45908.8 37989.9 38007.0 38064.9 46551.3 47017.8 13338.0 13428.5 13437.0 13488.7 13526.0 11113.9 11126.4 11130.0 11138.3 11145.9 11525.9 11545.8 11580.2 11614.8 11663.5 4217.3 4663.6 5041.3 5077.7 5102.7 971.3 985.7 1010.7 1018.8 1053.6 23620.3 23731.9 23747.4 23749.3 23824.6 1107.1 1107.4 1144.8 1158.0 1170.0 12039.3 12145.3 12171.2 12176.1 12219.6 306780.0 307766.0 307944.0 308290.0 309623.0 48503.5 48613.5 48619.0 48628.8 48775.3 1498.7 1507.2 1533.0 1545.7 1558.3 23349.0 23368.4 23392.0 23436.3 23628.6 36040.5 36087.5 36114.0 36155.5 36165.0 267561.0 268116.0 268788.0 268793.0 268902.0 27929.6 27949.0 30951.2 31859.0 31929.0 39678.0 40218.4 40219.4 40336.2 40467.2 26456.3 27001.6 27457.7 27916.9 29244.0 21527.7 28280.4 28499.0 28805.0 28924.4 32871.0 33231.5 33923.3 33977.7 33980.5 27697.0 28158.0 28870.6 29022.1 29090.0 19315.0 21459.6 21526.9 22837.4 22857.4 02:45:08 INFO - PID 2358 | 02:45:08 INFO - PID 2358 | [#2] jquery.html Cycles:180 Average:35878.38 Median:11831.39 stddev:94343.61 (797.4%) stddev-sans-first:94570.73 02:45:08 INFO - PID 2358 | Values: 431.7 438.1 440.7 443.5 460.9 20844.6 21507.0 21837.6 22628.8 22967.8 22869.3 22869.5 23171.5 23592.4 23892.8 5939.8 5992.7 6175.3 6178.0 6185.2 6049.3 6176.4 6177.1 6178.1 6179.0 5951.5 6113.4 6139.1 6150.8 6203.2 6154.6 6177.3 6204.7 6204.7 6205.5 5364.7 5437.7 5514.2 5517.5 5532.6 27485.9 28111.0 28133.4 28146.6 28174.4 2155.8 2222.4 2228.5 2229.1 2232.2 17551.6 17579.6 17851.6 17968.1 18091.6 2117.1 2127.6 2175.6 2177.4 2179.8 155627.0 157288.2 160459.0 161325.2 165744.0 12333.1 12390.3 12490.6 12627.7 12651.4 16651.3 16651.4 16667.8 16670.5 16675.2 6673.0 8186.4 8949.0 8956.1 9109.2 8503.6 8527.4 8530.3 8692.1 8724.1 6603.8 7470.7 7531.7 7659.0 7674.7 2105.8 2107.4 2119.8 2127.5 2276.9 940.5 961.6 967.9 1003.7 1011.7 20393.0 20635.4 21128.5 21236.5 21351.0 977.1 981.6 1010.5 1012.8 1013.5 9528.1 10569.3 10726.6 10736.0 10764.5 546115.0 548928.0 556083.0 566466.0 567496.0 119553.4 125191.0 125414.0 125954.4 127567.0 1122.6 1123.8 1168.1 1171.4 1172.8 17839.8 17974.6 17982.7 17994.6 18368.8 56372.9 56494.0 56819.9 56827.4 57915.0 59736.4 73438.0 75922.9 76442.1 76784.6 12797.4 13244.7 13483.7 13507.6 13536.1 11871.6 13618.7 14194.4 14400.6 14611.6 10033.5 11556.4 11794.5 12061.2 12140.5 9350.3 11030.4 11385.7 11485.2 11642.8 11990.3 12124.6 12147.8 12295.0 12337.6 10955.6 12835.6 12889.2 13145.8 13246.0 11729.5 11902.4 12012.5 12044.4 12095.8 02:45:08 INFO - PID 2358 | 02:45:08 INFO - PID 2358 | [#3] mootools.html Cycles:180 Average:7001.20 Median:4240.70 stddev:4461.39 (105.2%) stddev-sans-first:4452.53 02:45:08 INFO - PID 2358 | Values: 1189.3 1264.0 1391.6 1435.9 1453.8 9771.8 10195.0 11482.9 11745.2 11762.8 9489.6 11301.6 11410.0 11432.0 11445.3 2488.6 2534.7 2536.2 2537.6 2538.6 1778.9 2183.4 2474.7 2561.8 2567.6 2484.8 2497.9 2517.8 2519.1 2524.3 2477.1 2482.0 2496.1 2500.8 2508.3 2222.2 2273.6 2408.4 2440.2 2444.5 11473.3 11537.4 11637.5 11665.8 11667.0 6380.2 6615.2 6626.6 6626.8 6634.9 3987.1 4119.9 4220.2 4284.9 4305.1 1067.5 1246.9 1259.7 1297.9 1306.1 16222.6 16275.7 16344.3 16364.6 16369.9 5694.9 5842.2 5894.5 5984.3 5991.3 12983.1 13090.3 13398.9 13407.0 13444.9 6679.8 6692.5 6696.5 6696.6 6701.8 5259.7 5896.8 6058.4 6097.5 6182.6 3924.9 3988.0 4326.4 4383.7 4409.4 1230.7 1257.2 1278.2 1278.6 1280.0 683.9 799.3 805.5 809.1 811.0 6176.7 6574.6 6605.9 6823.4 6840.9 815.5 907.7 950.5 962.9 973.7 4159.8 4261.2 4264.6 4374.2 4436.7 14285.9 15067.5 15069.0 15084.7 15089.8 13913.7 14674.0 14683.0 14690.6 14692.2 6926.8 8223.5 8460.2 8483.8 8490.0 5640.6 6461.5 6711.9 7060.3 7094.2 12829.3 13298.9 13679.0 13680.3 13680.4 12122.5 13643.0 13753.7 14105.9 14181.0 6862.8 9164.4 9175.5 9200.1 9327.6 8530.0 9416.5 9897.4 10188.8 10406.5 7378.3 8671.0 8864.0 8902.0 9058.3 8687.6 8832.2 8869.2 8903.8 8985.1 7732.1 7875.0 7905.3 7912.5 7915.5 5874.9 5896.2 5995.4 6067.7 6098.0 4832.4 5969.6 6139.6 6212.0 6295.7 02:45:08 INFO - PID 2358 | 02:45:08 INFO - PID 2358 | [#4] prototype.html Cycles:180 Average:5153.50 Median:5794.67 stddev:1551.52 (26.8%) stddev-sans-first:1512.86 02:45:08 INFO - PID 2358 | Values: 319.8 394.2 400.4 402.2 403.9 5208.5 5853.9 6022.5 6098.1 6106.4 4601.8 5027.7 5884.7 5963.3 6006.0 4124.0 4251.1 4473.0 4580.2 4589.3 4548.9 4578.5 4584.5 4590.5 4602.2 4460.7 4516.4 4538.7 4545.8 4590.2 4026.6 4218.9 4530.5 4530.8 4552.0 4000.6 4007.6 4030.0 4033.2 4060.8 5697.3 5804.6 5815.5 5838.2 5840.7 4640.5 5328.5 5377.2 5395.2 5424.6 6335.0 6355.5 6367.6 6381.3 6414.7 4385.9 4508.5 4538.8 4548.8 4565.2 5751.5 6358.0 6559.7 6592.3 6615.5 3827.6 4686.4 4690.2 4725.6 4739.1 3889.4 3960.0 4054.5 4553.6 4798.4 4741.5 5096.5 5247.2 5349.4 5415.1 5585.8 5614.4 6368.6 6401.1 6425.9 1583.7 1621.4 1628.3 1639.7 1650.5 1062.7 1111.9 1138.6 1146.6 1157.4 5908.5 5926.6 5929.0 5935.9 5950.9 5710.1 5981.3 6437.3 6445.7 6480.4 5329.7 5784.8 5847.2 5879.9 5947.0 2846.0 2878.9 3348.6 3388.9 3415.7 6501.7 6681.4 6709.4 6746.1 6752.3 5855.4 5862.2 6559.7 6601.8 6605.3 5532.4 6498.4 6730.7 6744.8 6776.0 5882.2 6153.6 6536.5 6545.8 6558.8 6174.6 6294.0 6339.7 6355.2 6449.7 5499.4 6143.6 6615.5 6804.7 6939.8 4119.0 4629.7 4662.3 4683.2 4704.0 6533.1 6538.0 6544.0 6578.3 6590.9 4872.0 5150.2 6032.2 6349.6 6383.9 5141.7 5851.3 5935.6 6295.7 6369.8 5903.6 6150.2 6258.9 6282.5 6292.6 6212.6 6263.5 6325.7 6362.0 6362.3 4764.4 6246.7 6260.5 6268.0 6278.6 02:45:08 INFO - PID 2358 | 02:45:08 INFO - PID 2358 | [#5] yui.html Cycles:180 Average:22094.46 Median:17446.93 stddev:31303.02 (179.4%) stddev-sans-first:31350.10 02:45:08 INFO - PID 2358 | Values: 827.0 846.8 848.3 849.8 864.2 16187.0 16461.5 16821.9 16850.4 16881.7 15812.7 15829.9 15881.7 15978.0 16028.9 993.9 1004.4 1009.2 1032.5 1052.6 1039.3 1043.2 1043.3 1044.2 1044.8 1060.5 1100.5 1106.6 1107.7 1107.8 1089.0 1091.3 1092.0 1094.9 1097.2 803.9 805.5 806.1 807.4 807.6 16675.6 16827.8 17033.1 17227.8 17426.5 17171.7 17247.0 17638.5 17659.6 17768.5 14102.0 14117.0 14234.7 14386.0 14421.4 3498.4 3513.9 3516.4 3522.1 3529.6 86572.1 133480.3 134930.0 136813.0 137510.0 9806.8 11336.8 13055.7 13341.8 13342.0 24434.4 25042.0 25344.6 25714.1 25753.9 8025.3 8121.5 8125.1 8129.1 8134.0 3873.7 3894.0 3902.2 3950.3 3956.7 4356.9 4434.5 4441.3 4446.8 4453.9 785.3 932.2 953.2 966.3 967.0 659.5 680.3 681.9 682.8 687.8 9985.3 10304.6 11788.8 11909.5 11928.2 514.5 668.9 673.1 685.9 690.4 5427.9 5513.7 5604.7 5639.6 5764.3 80956.1 94112.6 110759.0 111045.3 115918.4 84897.0 86378.0 88499.6 99072.5 103345.5 79700.0 79721.2 79726.0 80002.4 84467.6 12052.8 12816.9 12914.4 12916.4 12946.7 28559.7 38743.5 38948.0 39358.6 40663.8 67731.7 68722.6 70122.6 70537.0 71766.9 15941.8 16482.5 16629.7 16670.8 16999.0 18088.2 18507.5 18641.9 18646.5 18678.5 16509.8 16744.8 17116.3 17319.0 17351.9 16451.7 16653.4 17125.6 17163.6 17467.4 16443.3 16850.4 16877.8 16878.1 17074.4 11261.1 11271.9 11673.4 11691.7 11934.3 11405.5 12828.1 12860.8 13771.5 13996.8 02:45:08 INFO - PID 2358 | -------- Summary: end -------- 02:45:08 INFO - PID 2358 | 02:45:08 INFO - TEST-INFO | 2358: exit 0 02:45:08 INFO - TEST-OK | dromaeo_css | took 1119367ms 02:45:08 INFO - TEST-START | kraken 02:45:08 INFO - operating with platform_type : mac_ 02:45:08 INFO - Initialising browser for kraken test... 02:45:08 INFO - TEST-INFO | started process 2365 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmp8g22Gs/profile http://localhost:58816/getInfo.html) 02:45:11 INFO - PID 2365 | __metrics Screen width/height:1600/1200 02:45:11 INFO - PID 2365 | colorDepth:24 02:45:11 INFO - PID 2365 | Browser inner width/height: 1024/691 02:45:11 INFO - PID 2365 | __metrics 02:45:11 INFO - PID 2365 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:45:11 INFO - PID 2365 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:45:12 INFO - TEST-INFO | 2365: exit 0 02:45:12 INFO - Browser initialized. 02:45:12 INFO - Running cycle 1/1 for kraken test... 02:45:12 INFO - TEST-INFO | started process 2366 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmp8g22Gs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:45:24 INFO - PID 2366 | RSS: Main: 240443392 02:45:24 INFO - PID 2366 | 02:45:27 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58816/tests/kraken/driver.html?testName=audio-beat-detection) 02:45:28 INFO - PID 2366 | RSS: Main: 310521856 02:45:28 INFO - PID 2366 | 02:45:30 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58816/tests/kraken/driver.html?testName=audio-dft) 02:45:31 INFO - PID 2366 | RSS: Main: 292728832 02:45:31 INFO - PID 2366 | 02:45:34 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58816/tests/kraken/driver.html?testName=audio-fft) 02:45:35 INFO - PID 2366 | RSS: Main: 384151552 02:45:35 INFO - PID 2366 | 02:45:37 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58816/tests/kraken/driver.html?testName=audio-oscillator) 02:45:37 INFO - PID 2366 | RSS: Main: 287784960 02:45:37 INFO - PID 2366 | 02:45:39 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58816/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:45:40 INFO - PID 2366 | RSS: Main: 289779712 02:45:40 INFO - PID 2366 | 02:45:43 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58816/tests/kraken/driver.html?testName=imaging-darkroom) 02:45:43 INFO - PID 2366 | RSS: Main: 354893824 02:45:43 INFO - PID 2366 | 02:45:46 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58816/tests/kraken/driver.html?testName=imaging-desaturate) 02:45:47 INFO - PID 2366 | RSS: Main: 375558144 02:45:47 INFO - PID 2366 | 02:45:50 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58816/tests/kraken/driver.html?testName=json-parse-financial) 02:45:50 INFO - PID 2366 | RSS: Main: 387690496 02:45:50 INFO - PID 2366 | 02:45:52 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58816/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:45:52 INFO - PID 2366 | RSS: Main: 332484608 02:45:52 INFO - PID 2366 | 02:45:54 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58816/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:45:55 INFO - PID 2366 | RSS: Main: 332513280 02:45:55 INFO - PID 2366 | 02:45:57 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58816/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:45:57 INFO - PID 2366 | RSS: Main: 334643200 02:45:57 INFO - PID 2366 | 02:46:00 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58816/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:46:01 INFO - PID 2366 | RSS: Main: 352509952 02:46:01 INFO - PID 2366 | 02:46:04 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58816/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:46:05 INFO - PID 2366 | RSS: Main: 344973312 02:46:05 INFO - PID 2366 | 02:46:06 INFO - PID 2366 | Cycle 1(1): loaded http://localhost:58816/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58816/tests/kraken/driver.html?testName=ai-astar) 02:46:07 INFO - PID 2366 | RSS: Main: 388575232 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | __start_tp_report 02:46:07 INFO - PID 2366 | _x_x_mozilla_page_load 02:46:07 INFO - PID 2366 | _x_x_mozilla_page_load_details 02:46:07 INFO - PID 2366 | |i|pagename|runs| 02:46:07 INFO - PID 2366 | |0;ai-astar;112;110;110;109;107;104;110;110;109;110 02:46:07 INFO - PID 2366 | |1;audio-beat-detection;139;110;108;148;110;104;86;111;153;125 02:46:07 INFO - PID 2366 | |2;audio-dft;186;189;184;183;187;183;184;182;183;181 02:46:07 INFO - PID 2366 | |3;audio-fft;79;57;57;87;58;59;58;56;80;55 02:46:07 INFO - PID 2366 | |4;audio-oscillator;93;67;84;68;68;67;85;68;68;90 02:46:07 INFO - PID 2366 | |5;imaging-gaussian-blur;92;88;93;88;89;88;88;88;88;88 02:46:07 INFO - PID 2366 | |6;imaging-darkroom;95;94;94;93;95;94;94;93;94;94 02:46:07 INFO - PID 2366 | |7;imaging-desaturate;87;86;86;87;85;86;84;83;86;85 02:46:07 INFO - PID 2366 | |8;json-parse-financial;49;48;49;50;50;49;50;49;50;50 02:46:07 INFO - PID 2366 | |9;json-stringify-tinderbox;53;55;60;66;66;62;57;58;55;57 02:46:07 INFO - PID 2366 | |10;stanford-crypto-aes;84;81;82;83;83;83;80;82;81;81 02:46:07 INFO - PID 2366 | |11;stanford-crypto-ccm;135;136;138;134;135;146;135;134;174;154 02:46:07 INFO - PID 2366 | |12;stanford-crypto-pbkdf2;183;185;185;184;183;187;181;183;192;219 02:46:07 INFO - PID 2366 | |13;stanford-crypto-sha256-iterative;67;67;67;67;68;68;67;68;68;67 02:46:07 INFO - PID 2366 | __end_tp_report 02:46:07 INFO - PID 2366 | __start_cc_report 02:46:07 INFO - PID 2366 | _x_x_mozilla_cycle_collect,2114 02:46:07 INFO - PID 2366 | __end_cc_report 02:46:07 INFO - PID 2366 | __startTimestamp1493804767216__endTimestamp 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | ------- Summary: start ------- 02:46:07 INFO - PID 2366 | Number of tests: 14 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:2.18 (2.0%) stddev-sans-first:2.05 02:46:07 INFO - PID 2366 | Values: 112.0 110.0 110.0 109.0 107.0 104.0 110.0 110.0 109.0 110.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#1] audio-beat-detection Cycles:10 Average:119.40 Median:132.00 stddev:21.32 (16.2%) stddev-sans-first:21.41 02:46:07 INFO - PID 2366 | Values: 139.0 110.0 108.0 148.0 110.0 104.0 86.0 111.0 153.0 125.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.00 stddev:2.44 (1.3%) stddev-sans-first:2.50 02:46:07 INFO - PID 2366 | Values: 186.0 189.0 184.0 183.0 187.0 183.0 184.0 182.0 183.0 181.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#3] audio-fft Cycles:10 Average:64.60 Median:58.50 stddev:12.23 (20.9%) stddev-sans-first:11.81 02:46:07 INFO - PID 2366 | Values: 79.0 57.0 57.0 87.0 58.0 59.0 58.0 56.0 80.0 55.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#4] audio-oscillator Cycles:10 Average:75.80 Median:76.00 stddev:10.79 (14.2%) stddev-sans-first:9.48 02:46:07 INFO - PID 2366 | Values: 93.0 67.0 84.0 68.0 68.0 67.0 85.0 68.0 68.0 90.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:88.00 stddev:1.89 (2.1%) stddev-sans-first:1.66 02:46:07 INFO - PID 2366 | Values: 92.0 88.0 93.0 88.0 89.0 88.0 88.0 88.0 88.0 88.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#6] imaging-darkroom Cycles:10 Average:94.00 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 02:46:07 INFO - PID 2366 | Values: 95.0 94.0 94.0 93.0 95.0 94.0 94.0 93.0 94.0 94.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:1.27 (1.5%) stddev-sans-first:1.22 02:46:07 INFO - PID 2366 | Values: 87.0 86.0 86.0 87.0 85.0 86.0 84.0 83.0 86.0 85.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:50.00 stddev:0.70 (1.4%) stddev-sans-first:0.73 02:46:07 INFO - PID 2366 | Values: 49.0 48.0 49.0 50.0 50.0 49.0 50.0 49.0 50.0 50.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:4.53 (7.7%) stddev-sans-first:4.28 02:46:07 INFO - PID 2366 | Values: 53.0 55.0 60.0 66.0 66.0 62.0 57.0 58.0 55.0 57.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:82.50 stddev:1.25 (1.5%) stddev-sans-first:1.09 02:46:07 INFO - PID 2366 | Values: 84.0 81.0 82.0 83.0 83.0 83.0 80.0 82.0 81.0 81.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#11] stanford-crypto-ccm Cycles:10 Average:142.10 Median:137.00 stddev:12.96 (9.5%) stddev-sans-first:13.49 02:46:07 INFO - PID 2366 | Values: 135.0 136.0 138.0 134.0 135.0 146.0 135.0 134.0 174.0 154.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:185.00 stddev:11.23 (6.1%) stddev-sans-first:11.76 02:46:07 INFO - PID 2366 | Values: 183.0 185.0 185.0 184.0 183.0 187.0 181.0 183.0 192.0 219.0 02:46:07 INFO - PID 2366 | 02:46:07 INFO - PID 2366 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 02:46:07 INFO - PID 2366 | Values: 67.0 67.0 67.0 67.0 68.0 68.0 67.0 68.0 68.0 67.0 02:46:07 INFO - PID 2366 | -------- Summary: end -------- 02:46:07 INFO - PID 2366 | 02:46:08 INFO - TEST-INFO | 2366: exit 0 02:46:08 INFO - TEST-OK | kraken | took 59434ms 02:46:08 INFO - SUITE-END | took 1178s 02:46:08 INFO - Completed test suite (00:19:38) 02:46:08 INFO - javascript benchmark 02:46:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7774.185678840156, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3521.137321356268, 3542.256126213497, 3565.946510802338, 3581.086822860171, 3614.367485690005, 6091.756329746805, 6137.223770008885, 6209.192804935357, 6227.972384556549, 6241.531885108612, 6129.2365380683905, 6141.363270730603, 6220.346863579315, 6223.782167624134, 6239.937600623993, 4612.0, 4954.702717836942, 5290.497402746733, 5305.283786688786, 5338.759755810988, 5535.142052981794, 5575.386707462176, 5585.720713964285, 5595.720213989304, 5631.8592035199035, 5352.036633405985, 5439.673619582838, 5451.073317536028, 5479.506844383985, 5498.3127109111365, 5446.2375267462585, 5456.536194423479, 5458.099413596752, 5461.5357694596005, 5466.207399927028, 5435.347758269033, 5493.807716729935, 5522.806701765419, 5548.389677135512, 5558.388577256498, 6205.6085907000415, 6245.063240513914, 6262.843428914268, 6265.279492858334, 6270.905936410957, 6557.836054098638, 6609.1408116944485, 6648.900266496006, 6657.434118099967, 6659.800205993828, 6513.1532900722605, 6517.543771935966, 6535.705893234815, 6629.270780214172, 6702.229243636995, 6188.845278868064, 6256.998880179195, 6270.31026587075, 6276.278228003746, 6283.59156654816, 6854.34883686059, 6855.143107111611, 6868.244493105754, 6869.278725733826, 6885.758998435131, 6324.683765811783, 6372.267189273293, 6397.840053998687, 6500.9349906501275, 6515.413612774873, 6607.504437167232, 6672.899906501407, 6706.53054286205, 6716.798496045127, 6716.83207919806, 6463.159789227464, 6555.115059466911, 6555.311692272338, 6555.639439830759, 6568.638724870082, 6338.271098823602, 6614.437772789271, 6617.2059352877, 6618.768343107968, 6623.86752264952, 5221.503957124067, 5222.529972302511, 5223.4515375884985, 5229.607779416559, 5248.186531087687, 4618.95381046192, 4624.630029597624, 4694.0, 4740.834070807574, 4750.334953106568, 5171.4311425743135, 6159.076138579249, 6212.658303793244, 6219.346968568326, 6228.34602366754, 5621.662700237999, 6637.203535575762, 6641.568298060611, 6656.667166641649, 6684.097646817715, 6266.717997690115, 6267.968660156761, 6283.0, 6283.622982621057, 6293.30773614902, 5837.824865254049, 5856.619319744288, 5918.201042859159, 5944.494717948936, 5968.612040217372, 6739.966300168468, 6794.0, 6795.660216989229, 6798.490113241625, 6916.135483064617, 6557.836054098686, 6583.3087525811015, 6584.967075164594, 6639.070530125883, 6652.135222421054, 6950.0, 6964.059851919926, 6969.233384327618, 6969.7909062728195, 6989.965050174716, 6627.039079333374, 6637.236717777324, 6642.103316052464, 6644.235912870082, 6651.235107962452, 6373.521985850987, 6547.247066587403, 6677.632730199885, 6698.196216454056, 6723.193216814014, 6355.809325720235, 6360.650164241011, 6367.649779261999, 6370.490360771035, 6389.201349831271, 6326.8734625306315, 6332.588381755171, 6353.491720662429, 6458.870822583616, 6498.902516462352, 6349.460295874902, 6417.871642567029, 6421.197350331208, 6429.196350456193, 6432.2602900667025, 6527.771527996498, 6545.443637290884, 6546.0508226305965, 6551.6069035858, 6557.540972131905, 6199.442050215322, 6223.564350495421, 6231.158793562992, 6254.906176407449, 6302.495800335871], "value": 6071.68857479544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [671.2314400011985, 803.232912568497, 956.3926906414429, 1105.3975583307097, 1116.9273997190178, 31001.534976975334, 33666.66333336672, 33707.831460842695, 33741.9877403678, 33776.49335259967, 32458.863939762112, 34112.0, 36181.457278140784, 36479.81760091202, 36603.4509482358, 5849.239598852165, 5938.435848594377, 8001.6799328026955, 8146.714864979727, 8148.470349427298, 8022.478538894982, 8114.985626796651, 8141.104478507359, 8143.714969976052, 8163.9591802041205, 8180.713675021376, 8216.260536551708, 8225.917740822575, 8227.917720822805, 8228.794280142984, 5906.557008224358, 5995.940040599626, 8133.633986470623, 8187.304079153219, 8188.877166842532, 7855.6464959076975, 7931.087924888654, 7978.840423191504, 7981.680732770682, 7998.12020677725, 33900.49149262763, 34186.82906585476, 34218.65781342205, 34284.657153428394, 34342.82828585866, 2953.9852300738576, 3539.610642829287, 3539.6460353964653, 3545.3263879862743, 3555.484454754072, 28282.717172828216, 28406.289842754097, 28434.289142771388, 28441.0, 28473.288167795763, 3114.7073964304786, 3143.5441860930036, 3178.253110519026, 3193.217661672905, 3209.871605135792, 280367.0, 286519.56740216166, 286649.26701465854, 286787.0, 286864.0, 36847.0, 36990.0, 37002.074948126516, 45899.77050114728, 45908.770456147504, 37989.860304190915, 38007.04982375463, 38064.85805425842, 46551.30173047407, 47017.76491117591, 13337.999650026286, 13428.462861485626, 13436.99222558312, 13488.662783430296, 13526.0, 11113.888861111447, 11126.443677816238, 11129.998300153025, 11138.33170009802, 11145.888541114486, 11525.88474115248, 11545.826812597817, 11580.247283926687, 11614.825777613343, 11663.475143618558, 4217.283061879488, 4663.647542212788, 5041.294218809369, 5077.720725360141, 5102.719350435762, 971.2958105373548, 985.6796541124163, 1010.661428421487, 1018.8267994440963, 1053.5733028123623, 23620.291391258288, 23731.932063056858, 23747.40631484227, 23749.287521374386, 23824.64263036056, 1107.141964977133, 1107.4075369677184, 1144.8339990701302, 1158.0, 1169.9883001170049, 12039.277643341253, 12145.295684816216, 12171.208871423329, 12176.147669663216, 12219.633410997685, 306780.0, 307766.0, 307944.0, 308290.0, 309623.0, 48503.514964850605, 48613.513864861605, 48619.0, 48628.75685621549, 48775.26837097447, 1498.7452133137174, 1507.17105591924, 1532.9846701533065, 1545.7217700813737, 1558.290977605187, 23349.0, 23368.415789605395, 23392.0, 23436.312737452918, 23628.645570316803, 36040.4593931086, 36087.45868811917, 36114.0, 36155.457668135525, 36165.0, 267561.0, 268116.0, 268788.0, 268793.0, 268902.0, 27929.581056283765, 27949.0, 30951.226219344695, 31859.0, 31929.0, 39678.0, 40218.39672404975, 40219.3967090488, 40336.1932761349, 40467.190656187304, 26456.338591534597, 27001.594976074983, 27457.725422746316, 27916.860415697793, 29244.0, 21527.677084843428, 28280.434391311646, 28499.0, 28805.0, 28924.42151157007, 32871.0, 33231.50152747663, 33923.32153356968, 33977.66022339745, 33980.49029264612, 27697.0, 28158.0, 28870.566941495475, 29022.12933611995, 29090.0, 19315.0, 21459.570808584052, 21526.89236553807, 22837.429064273525, 22857.42856428536], "value": 16280.48409746316, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.6719293337063, 438.0528550592616, 440.693717866083, 443.5453659998501, 460.917034933712, 20844.583108337843, 21507.0, 21837.563248735034, 22628.77371226287, 22967.770322296772, 22869.313920582408, 22869.542609147848, 23171.536569268603, 23592.410189745264, 23892.761072389272, 5939.821805345847, 5992.730327135289, 6175.320714721377, 6177.969110154466, 6185.195924529805, 6049.274087109553, 6176.382361763833, 6177.135201071854, 6178.135061091429, 6178.969105154468, 5951.464368206861, 6113.449789518942, 6139.140520327158, 6150.84622884427, 6203.193584833965, 6154.5999510031925, 6177.289611694623, 6204.6587437690905, 6204.6587437690905, 6205.472534834545, 5364.7317634118135, 5437.728113594305, 5514.200440936042, 5517.475839795213, 5532.612717109804, 27485.86257068702, 28111.0, 28133.437331253466, 28146.577801333, 28174.43651126966, 2155.7844215578316, 2222.411061068818, 2228.4985878177445, 2229.0749339024287, 2232.162938897913, 17551.648967020716, 17579.648407031917, 17851.642967140713, 17968.101594920332, 18091.638167236713, 2117.110813458336, 2127.5638494108825, 2175.641019231825, 2177.4338671945407, 2179.8147157491476, 155627.0, 157288.21355893262, 160459.0, 161325.19337403355, 165743.9999999988, 12333.136680432284, 12390.344848269602, 12490.562830300896, 12627.684307892376, 12651.430685618985, 16651.25069371881, 16651.41720039793, 16667.83332166687, 16670.499885003468, 16675.24961376741, 6672.99905014242, 8186.386021048445, 8948.955255223813, 8956.104389560991, 9109.225715814098, 8503.574821258911, 8527.360447966428, 8530.274926631306, 8692.130786921383, 8724.127587241224, 6603.834904127435, 7470.663820128108, 7531.736389226351, 7659.042619672541, 7674.693012279558, 2105.757837848667, 2107.3993911735083, 2119.841011924081, 2127.489402543378, 2276.931692049241, 940.4921342474987, 961.624966263158, 967.8820961789145, 1003.7139415266614, 1011.6509804117568, 20393.0, 20635.380938571867, 21128.471788205417, 21236.469088272606, 21351.0, 977.1449981266392, 981.6417007792104, 1010.5048526222098, 1012.8379459286479, 1013.4881884648205, 9528.142467177988, 10569.312994655476, 10726.624568140078, 10736.0, 10764.461776911123, 546115.0, 548928.0, 556083.0, 566466.0, 567496.0, 119553.40223299002, 125191.0, 125414.0, 125954.3702281501, 127567.0, 1122.5734220995969, 1123.752774389633, 1168.0830548019765, 1171.3616079236836, 1172.8299396587447, 17839.821601783817, 17974.640507190044, 17982.730259046388, 17994.5501362467, 18368.816311836712, 56372.8725425481, 56494.0, 56819.86360272689, 56827.43172568387, 57915.0, 59736.402635973085, 73438.0, 75922.86115708158, 76442.12020918143, 76784.61607691926, 12797.360131993177, 13244.735105297646, 13483.662908427368, 13507.594772156852, 13536.052476326562, 11871.643850684493, 13618.659533511742, 14194.432222710975, 14400.639984000483, 14611.561653150422, 10033.548490317808, 11556.364399958084, 11794.46924888365, 12061.216020958609, 12140.514379424723, 9350.27020001302, 11030.393328367018, 11385.658430247106, 11485.19603627738, 11642.76714465723, 11990.340531270864, 12124.636260912186, 12147.75704485923, 12295.016398688262, 12337.568185113478, 10955.56177752881, 12835.550755723505, 12889.162204456681, 13145.751153640389, 13246.0, 11729.47217375203, 11902.404879755804, 12012.519499220283, 12044.397780111134, 12095.818562721743], "value": 10618.086972810006, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1189.280485306389, 1263.9936800315998, 1391.5616580777057, 1435.906666066705, 1453.7746649269363, 9771.804563908727, 10195.0, 11482.885171148297, 11745.236559623618, 11762.7647447051, 9489.6204151834, 11301.604443844468, 11410.030147437457, 11432.0, 11445.2560583562, 2488.5769419198737, 2534.733852945442, 2536.226450932465, 2537.606670966003, 2538.581134112869, 1778.9288428462846, 2183.366823621148, 2474.6782918220606, 2561.8462892226435, 2567.6020216866414, 2484.751524847519, 2497.912573059943, 2517.7859881909967, 2519.0931264744677, 2524.255344673326, 2477.1206221791276, 2481.950360992779, 2496.10140349474, 2500.8374455660323, 2508.347829564308, 2222.20000799712, 2273.601734933004, 2408.397900524869, 2440.2069327468644, 2444.5110977804334, 11473.254238474556, 11537.365444900528, 11637.534498620045, 11665.825012624817, 11667.008304294146, 6380.1705778249, 6615.17310336208, 6626.56927299724, 6626.834329141761, 6634.93365066348, 3987.134421397505, 4119.876403707864, 4220.177065472234, 4284.914301713949, 4305.117450922553, 1067.4982758103679, 1246.9314187719672, 1259.689922480619, 1297.935103244834, 1306.144475368635, 16222.594435139097, 16275.674486510203, 16344.264508097163, 16364.590885227964, 16369.918150409172, 5694.857628559319, 5842.152887831241, 5894.469497745224, 5984.251968503937, 5991.2510936132985, 12983.091183617056, 13090.280034598189, 13398.928085753116, 13407.0, 13444.865551344557, 6679.833004174934, 6692.531522793358, 6696.46428285736, 6696.631685257357, 6701.832454188684, 5259.684418934876, 5896.823095307148, 6058.394160583906, 6097.451229389376, 6182.567220294536, 3924.862629807943, 3987.960120398759, 4326.41593384889, 4383.715058521186, 4409.382686423904, 1230.6955823613625, 1257.2330878164312, 1278.2458349573797, 1278.6483716977905, 1280.0399700224832, 683.8837397642412, 799.3165843204093, 805.4603415711488, 809.1301850510724, 811.0308181722785, 6176.722047507873, 6574.638394888231, 6605.86788264232, 6823.430610959507, 6840.897386539206, 815.4984684419159, 907.7458311672742, 950.5437390052813, 962.9470379129075, 973.7176218896441, 4159.812808423628, 4261.231588890638, 4264.637505811978, 4374.234508960919, 4436.733795972252, 14285.928570357082, 15067.4726384576, 15069.020513666577, 15084.698306034037, 15089.849101509064, 13913.652158695912, 14674.046186997812, 14683.045602035834, 14690.632734181518, 14692.191929443983, 6926.757563485355, 8223.54770487617, 8460.238578527838, 8483.787905302417, 8489.95755021221, 5640.60515763909, 6461.450776684034, 6711.89253773122, 7060.293970602898, 7094.219635840157, 12829.294388808705, 13298.869596084442, 13679.0, 13680.315984200552, 13680.384382702603, 12122.515099395925, 13643.0, 13753.724925501234, 14105.858941410455, 14181.0, 6862.764702353625, 9164.404313719588, 9175.54122293896, 9200.125988031126, 9327.58025888823, 8529.95735021321, 9416.482093484925, 9897.406155630686, 10188.79622407533, 10406.531706073094, 7378.262173782578, 8670.956645216986, 8863.955680221557, 8902.020777714575, 9058.320625952949, 8687.609057592297, 8832.160944710242, 8869.246114080117, 8903.821923561363, 8985.146411091197, 7732.110807257012, 7875.0, 7905.288524032865, 7912.4856884302335, 7915.485493442908, 5874.911876321944, 5896.233489646319, 5995.400459953969, 6067.696615169136, 6097.969510152421, 4832.371791667061, 5969.582319160747, 6139.557204056954, 6212.0371342441995, 6295.653739044396], "value": 5295.305311507151, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.80971322063374, 394.1820722001847, 400.42339031794216, 402.2317373816012, 403.94546736190625, 5208.479152084795, 5853.853653658655, 6022.54830887683, 6098.14625952367, 6106.419890110433, 4601.79291931863, 5027.698338099707, 5884.6763428011445, 5963.34403215647, 6005.969970150154, 4123.9793801030955, 4251.128518653684, 4472.977635111828, 4580.175568397687, 4589.25608268859, 4548.8862778430475, 4578.542145785428, 4584.510034238869, 4590.472095709003, 4602.194615942213, 4460.687751857371, 4516.367708520794, 4538.704984176018, 4545.772711364435, 4590.173768721629, 4026.5570787213237, 4218.915621687564, 4530.478994915594, 4530.841420550265, 4551.954480455186, 4000.6199411055904, 4007.5591684914634, 4029.959700403017, 4033.1530378620523, 4060.776657283848, 5697.312279272582, 5804.564657650651, 5815.505682017034, 5838.2118414013985, 5840.678762668052, 4640.466346370143, 5328.5470734987575, 5377.193420986844, 5395.21769343443, 5424.647397919162, 6335.0, 6355.459785918202, 6367.554271201017, 6381.266154392258, 6414.679266036679, 4385.868423947255, 4508.504064552896, 4538.7503687297185, 4548.795304211319, 4565.2467342888385, 5751.482366586985, 6357.968210158966, 6559.672016399209, 6592.340765923465, 6615.5369124161325, 3827.61723827615, 4686.367340409001, 4690.202665546865, 4725.605946245797, 4739.075880203327, 3889.416587511896, 3959.980200098981, 4054.533728621246, 4553.567411096007, 4798.448178459452, 4741.5495527924795, 5096.541311282002, 5247.239150323182, 5349.368442624959, 5415.1335786274, 5585.832425027255, 5614.438556144353, 6368.5860419072615, 6401.135846660734, 6425.871482570323, 1583.7070142023763, 1621.4081860121207, 1628.2510045379022, 1639.7458393948957, 1650.5378494021454, 1062.697131317571, 1111.855458790352, 1138.5673444091192, 1146.621614867092, 1157.3518829455531, 5908.497777688945, 5926.614770039933, 5928.970355148197, 5935.901858156255, 5950.9107363389585, 5710.057840456361, 5981.252343457068, 6437.2918978912285, 6445.713600455738, 6480.449161821204, 5329.680219186784, 5784.768609255667, 5847.152162936351, 5879.911801322984, 5946.959282125611, 2846.0, 2878.928026799305, 3348.5814273215847, 3388.9322213556084, 3415.658434156614, 6501.707423165969, 6681.398674119353, 6709.429698475684, 6746.055552222692, 6752.324767523307, 5855.443732845373, 5862.237909071793, 6559.672016399161, 6601.768938087144, 6605.30644282353, 5532.446755324436, 6498.415142637185, 6730.697118629673, 6744.763933262312, 6775.966120169368, 5882.23530940975, 6153.5692501526255, 6536.477081833537, 6545.836354091186, 6558.819412505794, 6174.598651087665, 6294.0, 6339.683015849097, 6355.237371515448, 6449.7097630605795, 5499.395066542597, 6143.600665956698, 6615.460728510224, 6804.693788779616, 6939.826504337432, 4119.0, 4629.652776041743, 4662.300654901792, 4683.15703173432, 4704.012157446974, 6533.118029066015, 6537.967310163419, 6543.967280163569, 6578.309277525982, 6590.868182636416, 4872.0, 5150.244619128748, 6032.155498230339, 6349.619022858643, 6383.93616063852, 5141.71720555373, 5851.327097383685, 5935.584509084322, 6295.653739044214, 6369.777057802955, 5903.586748927534, 6150.200473938359, 6258.874822503615, 6282.497400207881, 6292.622442653273, 6212.596181248205, 6263.467605253604, 6325.746970121328, 6362.0, 6362.331955144643, 4764.404449443819, 6246.656433896179, 6260.467860231749, 6267.9971204607045, 6278.560500764903], "value": 4681.414114631751, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.9958650206748, 846.775604464817, 848.3340577646545, 849.8272384109928, 864.1833467373331, 16187.0, 16461.50615481536, 16821.915890420532, 16850.410235641724, 16881.662366752673, 15812.651520831778, 15829.891907566474, 15881.682366352667, 15978.0, 16028.919855400707, 993.8857031441387, 1004.4475538453821, 1009.1876039787968, 1032.5405194688328, 1052.6263176572322, 1039.2569312941246, 1043.2488608202082, 1043.2905624175557, 1044.2116202267277, 1044.7544826965657, 1060.48566445274, 1100.515773059855, 1106.5739690219284, 1107.6898468428853, 1107.7507560798797, 1088.9618863339745, 1091.2633972068822, 1091.9672409827717, 1094.879563248046, 1097.1716354152604, 803.8914746509205, 805.5247404031591, 806.0850934189737, 807.3621838747382, 807.648672827322, 16675.583110422216, 16827.83172168275, 17033.148342582823, 17227.82772172287, 17426.47720568385, 17171.656566868594, 17246.965182089116, 17638.470845874643, 17659.55851103733, 17768.46694599164, 14102.0, 14117.0, 14234.715305693931, 14386.0, 14421.351039203157, 3498.4052711039185, 3513.9121521962156, 3516.43737002081, 3522.1370764162943, 3529.6470352964757, 86572.13427865767, 133480.3325983364, 134930.0, 136813.0, 137510.0, 9806.803863922682, 11336.829947550794, 13055.738885222243, 13341.798603230553, 13342.0, 24434.38914027128, 25042.0, 25344.619830702555, 25714.100006499684, 25753.8712306441, 8025.277725004661, 8121.512709237465, 8125.1062383137805, 8129.105798362176, 8134.023917129983, 3873.6707379872464, 3894.0264933766557, 3902.180542086194, 3950.2692001979726, 3956.7032472564674, 4356.934645980313, 4434.5343738907595, 4441.289393697058, 4446.799894004713, 4453.866384008485, 785.2932360875258, 932.2448816458555, 953.1755031897497, 966.2511553546058, 967.0426277984744, 659.515256286629, 680.2789043613785, 681.8840797064511, 682.7678589279669, 687.7524091327118, 9985.301028927905, 10304.639337623297, 11788.764224715458, 11909.464074116686, 11928.224665396721, 514.4675261104738, 668.8963210702349, 673.0678010954863, 685.9211190713032, 690.3890057299226, 5427.9185812212845, 5513.724313784295, 5604.663720176803, 5639.577031722637, 5764.308283006066, 80956.07015579616, 94112.5883111754, 110759.0, 111045.33431998527, 115918.42040789741, 84897.0, 86378.0, 88499.55750221208, 99072.50463747635, 103345.48327258315, 79700.0074998118, 79721.20278797254, 79726.0, 80002.39995200063, 84467.57766211253, 12052.75894482123, 12816.871831281567, 12914.418851151533, 12916.418761155583, 12946.676333091747, 28559.714402855705, 38743.45026198921, 38948.0, 39358.60641393664, 40663.796681017586, 67731.66134169298, 68722.62554748978, 70122.64938675275, 70537.0, 71766.92349614656, 15941.760873587138, 16482.505524834272, 16629.667406652043, 16670.83329166741, 16999.0, 18088.186031628346, 18507.537311567317, 18641.90679046596, 18646.53383665365, 18678.53303667419, 16509.761767867698, 16744.832551674815, 17116.315347385964, 17319.047452389736, 17351.913240433718, 16451.67096658084, 16653.41713040038, 17125.57186070358, 17163.570910727332, 17467.38864139749, 16443.260053297392, 16850.410235641695, 16877.831221687626, 16878.071706056286, 17074.402395916084, 11261.099112070851, 11271.887281127083, 11673.416329183337, 11691.707707307385, 11934.343611101473, 11405.49112895239, 12828.10203285761, 12860.807087893501, 13771.517996870061, 13996.790048149081], "value": 8484.151586868713, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1409.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 110.0, 109.0, 107.0, 104.0, 110.0, 110.0, 109.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 110.0, 108.0, 148.0, 110.0, 104.0, 86.0, 111.0, 153.0, 125.0], "value": 119.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 189.0, 184.0, 183.0, 187.0, 183.0, 184.0, 182.0, 183.0, 181.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 57.0, 87.0, 58.0, 59.0, 58.0, 56.0, 80.0, 55.0], "value": 64.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 67.0, 84.0, 68.0, 68.0, 67.0, 85.0, 68.0, 68.0, 90.0], "value": 75.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 93.0, 88.0, 89.0, 88.0, 88.0, 88.0, 88.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 93.0, 95.0, 94.0, 94.0, 93.0, 94.0, 94.0], "value": 94.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 86.0, 87.0, 85.0, 86.0, 84.0, 83.0, 86.0, 85.0], "value": 85.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 49.0, 50.0, 50.0, 49.0, 50.0, 49.0, 50.0, 50.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 60.0, 66.0, 66.0, 62.0, 57.0, 58.0, 55.0, 57.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 82.0, 83.0, 83.0, 83.0, 80.0, 82.0, 81.0, 81.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 138.0, 134.0, 135.0, 146.0, 135.0, 134.0, 174.0, 154.0], "value": 142.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 184.0, 183.0, 187.0, 181.0, 183.0, 192.0, 219.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 68.0, 68.0, 67.0, 68.0, 68.0, 67.0], "value": 67.4, "unit": "score"}]}]} 02:46:08 INFO - Return code: 0 02:46:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:46:08 INFO - # TBPL SUCCESS # 02:46:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:46:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:46:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:46:08 INFO - 2017-05-03 02:46:08.569 screenresolution[2371:33354] starting screenresolution argv=screenresolution get 02:46:08 INFO - 2017-05-03 02:46:08.572 screenresolution[2371:33354] Display 0: 1600x1200x32@60 02:46:08 INFO - 2017-05-03 02:46:08.578 screenresolution[2372:33357] starting screenresolution argv=screenresolution list 02:46:08 INFO - Available Modes on Display 0 02:46:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:46:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:46:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:46:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:46:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:46:08 INFO - Intel Iris: 02:46:08 INFO - Chipset Model: Intel Iris 02:46:08 INFO - Type: GPU 02:46:08 INFO - Bus: Built-In 02:46:08 INFO - VRAM (Dynamic, Max): 1536 MB 02:46:08 INFO - Vendor: Intel (0x8086) 02:46:08 INFO - Device ID: 0x0a2e 02:46:08 INFO - Revision ID: 0x0009 02:46:08 INFO - Displays: 02:46:08 INFO - Display: 02:46:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:46:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:46:08 INFO - Display Serial Number: 1600x1200 60 02:46:08 INFO - Main Display: Yes 02:46:08 INFO - Mirror: Off 02:46:08 INFO - Online: Yes 02:46:08 INFO - Rotation: Supported 02:46:08 INFO - Return code: 0 02:46:08 INFO - Running post-action listener: _resource_record_post_action 02:46:08 INFO - [mozharness: 2017-05-03 09:46:08.828903Z] Finished run-tests step (success) 02:46:08 INFO - Running post-run listener: _resource_record_post_run 02:46:08 INFO - Total resource usage - Wall time: 1199s; CPU: 53.0%; Read bytes: 1417728; Write bytes: 510897664; Read time: 9; Write time: 1572 02:46:08 INFO - TinderboxPrint: CPU usage
53.4% 02:46:08 INFO - TinderboxPrint: I/O read bytes / time
1,417,728 / 9 02:46:08 INFO - TinderboxPrint: I/O write bytes / time
510,897,664 / 1,572 02:46:08 INFO - TinderboxPrint: CPU idle
2,237.2 (46.6%) 02:46:08 INFO - TinderboxPrint: CPU system
955.6 (19.9%) 02:46:08 INFO - TinderboxPrint: CPU user
1,604.8 (33.5%) 02:46:08 INFO - TinderboxPrint: Swap in / out
209,768,448 / 0 02:46:08 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 199098880; Write bytes: 192765952; Read time: 15134; Write time: 400 02:46:08 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 1417728; Write bytes: 301288960; Read time: 9; Write time: 1123 02:46:09 INFO - Running post-run listener: _upload_blobber_files 02:46:09 INFO - Blob upload gear active. 02:46:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:46:09 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 02:46:09 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'] 02:46:09 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 02:46:09 INFO - (blobuploader) - INFO - Open directory for files ... 02:46:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:46:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:46:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:10 INFO - (blobuploader) - INFO - Done attempting. 02:46:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:46:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:46:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:11 INFO - (blobuploader) - INFO - Done attempting. 02:46:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:46:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:46:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:12 INFO - (blobuploader) - INFO - Done attempting. 02:46:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:46:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:46:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:46:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:46:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:46:14 INFO - (blobuploader) - INFO - Done attempting. 02:46:14 INFO - (blobuploader) - INFO - Iteration through files over. 02:46:14 INFO - Return code: 0 02:46:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:46:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:46:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/114b5fb10eb7c1c4b8d593583f22a142f7344010c32f1269f5525951943574c2a1fddd7a658b41dd3dc0d4fc7976d1edee768a46152a8e862b89cd6ce61644ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a4a4e56884c8b0e3f5ce4d64f9fc403e5a4d8bc05754dc3a19a20aeee561a04298d71dd1af0da777ad1c43ab7eb8450497b0a8b2f73a0189bb1fdb669048268", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbcebbd4ab19571fa9421ede0cb081c433d1279b7700f9adf398c3eec3a8fdaf9144ab1ad81ea2848efb71ba44ce82f944976ace565994d24009ae54b91b2aff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce811c489e43cbe889b0226a91c3069bae779571c827f7ccbf94de86c4356bae0e98b631d3a8b3d26342ad82d69bac77a74a241311c32c4a17e6c3aaf0ad8d4c"} 02:46:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:46:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:46:14 INFO - Contents: 02:46:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/114b5fb10eb7c1c4b8d593583f22a142f7344010c32f1269f5525951943574c2a1fddd7a658b41dd3dc0d4fc7976d1edee768a46152a8e862b89cd6ce61644ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a4a4e56884c8b0e3f5ce4d64f9fc403e5a4d8bc05754dc3a19a20aeee561a04298d71dd1af0da777ad1c43ab7eb8450497b0a8b2f73a0189bb1fdb669048268", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbcebbd4ab19571fa9421ede0cb081c433d1279b7700f9adf398c3eec3a8fdaf9144ab1ad81ea2848efb71ba44ce82f944976ace565994d24009ae54b91b2aff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce811c489e43cbe889b0226a91c3069bae779571c827f7ccbf94de86c4356bae0e98b631d3a8b3d26342ad82d69bac77a74a241311c32c4a17e6c3aaf0ad8d4c"} 02:46:14 INFO - Running post-run listener: copy_logs_to_upload_dir 02:46:14 INFO - Copying logs to upload dir... 02:46:14 INFO - mkdir: /builds/slave/test/build/upload/logs 02:46:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.043966 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-03 02:46:14.668368) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 02:46:14.671881) ========= 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.cAQyOGUfWc/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.c28yd7tBGP/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/114b5fb10eb7c1c4b8d593583f22a142f7344010c32f1269f5525951943574c2a1fddd7a658b41dd3dc0d4fc7976d1edee768a46152a8e862b89cd6ce61644ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a4a4e56884c8b0e3f5ce4d64f9fc403e5a4d8bc05754dc3a19a20aeee561a04298d71dd1af0da777ad1c43ab7eb8450497b0a8b2f73a0189bb1fdb669048268", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbcebbd4ab19571fa9421ede0cb081c433d1279b7700f9adf398c3eec3a8fdaf9144ab1ad81ea2848efb71ba44ce82f944976ace565994d24009ae54b91b2aff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce811c489e43cbe889b0226a91c3069bae779571c827f7ccbf94de86c4356bae0e98b631d3a8b3d26342ad82d69bac77a74a241311c32c4a17e6c3aaf0ad8d4c"} build_url:https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011226 build_url: 'https://queue.taskcluster.net/v1/task/NE7m8POeTfm-_ilsOOCczQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/114b5fb10eb7c1c4b8d593583f22a142f7344010c32f1269f5525951943574c2a1fddd7a658b41dd3dc0d4fc7976d1edee768a46152a8e862b89cd6ce61644ec", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8a4a4e56884c8b0e3f5ce4d64f9fc403e5a4d8bc05754dc3a19a20aeee561a04298d71dd1af0da777ad1c43ab7eb8450497b0a8b2f73a0189bb1fdb669048268", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dbcebbd4ab19571fa9421ede0cb081c433d1279b7700f9adf398c3eec3a8fdaf9144ab1ad81ea2848efb71ba44ce82f944976ace565994d24009ae54b91b2aff", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ce811c489e43cbe889b0226a91c3069bae779571c827f7ccbf94de86c4356bae0e98b631d3a8b3d26342ad82d69bac77a74a241311c32c4a17e6c3aaf0ad8d4c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-03 02:46:14.707086) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 02:46:14.707446) ========= 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.cAQyOGUfWc/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.c28yd7tBGP/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005272 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-03 02:46:14.758606) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 02:46:14.758927) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-03 02:46:14.759267) ========= ========= Total master_lag: 0.85 =========