builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0094 starttime: 1490788452.69 results: success (0) buildid: 20170329030240 builduid: c804c351bf334cb886e20815c105b1d9 revision: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.692536) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.693066) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.693387) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.718811) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.719206) ========= 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.ElO4dylnAX/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.fuTKoJvTrd/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-03-29 04:54:12-- 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% 159M=0s 2017-03-29 04:54:12 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130679 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.873033) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.873388) ========= 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.ElO4dylnAX/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.fuTKoJvTrd/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.020971 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.923289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:12.923850) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 272ce6c2572164f5f6a9fba2a980ba9ccf50770c --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 272ce6c2572164f5f6a9fba2a980ba9ccf50770c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ElO4dylnAX/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.fuTKoJvTrd/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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-03-29 04:54:12,972 truncating revision to first 12 chars 2017-03-29 04:54:12,972 Setting DEBUG logging. 2017-03-29 04:54:12,972 attempt 1/10 2017-03-29 04:54:12,972 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/272ce6c25721?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-29 04:54:13,595 unpacking tar archive at: mozilla-central-272ce6c25721/testing/mozharness/ program finished with exit code 0 elapsedTime=0.874663 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:13.822651) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:13.822983) ========= script_repo_revision: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:13.823425) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:13.823701) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-29 04:54:13.843423) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-29 04:54:13.843775) ========= /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.ElO4dylnAX/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.fuTKoJvTrd/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:54:14 INFO - MultiFileLogger online at 20170329 04:54:14 in /builds/slave/test 04:54:14 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 04:54:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:54:14 INFO - {'append_to_log': False, 04:54:14 INFO - 'base_work_dir': '/builds/slave/test', 04:54:14 INFO - 'blob_upload_branch': 'Firefox', 04:54:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:54:14 INFO - 'branch': 'Firefox', 04:54:14 INFO - 'buildbot_json_path': 'buildprops.json', 04:54:14 INFO - 'config_files': ('talos/mac_config.py',), 04:54:14 INFO - 'default_actions': ('clobber', 04:54:14 INFO - 'read-buildbot-config', 04:54:14 INFO - 'download-and-extract', 04:54:14 INFO - 'populate-webroot', 04:54:14 INFO - 'create-virtualenv', 04:54:14 INFO - 'install', 04:54:14 INFO - 'run-tests'), 04:54:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:54:14 INFO - 'download_minidump_stackwalk': True, 04:54:14 INFO - 'download_symbols': 'ondemand', 04:54:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:54:14 INFO - 'tooltool.py': '/tools/tooltool.py', 04:54:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:54:14 INFO - '/tools/misc-python/virtualenv.py')}, 04:54:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:54:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:54:14 INFO - 'gecko_profile': False, 04:54:14 INFO - 'gecko_profile_interval': 0, 04:54:14 INFO - 'installer_path': 'installer.exe', 04:54:14 INFO - 'log_level': 'info', 04:54:14 INFO - 'log_name': 'talos', 04:54:14 INFO - 'log_to_console': True, 04:54:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:54:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:54:14 INFO - 'opt_config_files': (), 04:54:14 INFO - 'pip_index': False, 04:54:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:54:14 INFO - 'cmd': ('bash', 04:54:14 INFO - '-c', 04:54:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:54:14 INFO - 'enabled': True, 04:54:14 INFO - 'halt_on_failure': False, 04:54:14 INFO - 'name': 'check_screen_resolution'},), 04:54:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:54:14 INFO - 'cmd': ('bash', 04:54:14 INFO - '-c', 04:54:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:54:14 INFO - 'enabled': True, 04:54:14 INFO - 'halt_on_failure': False, 04:54:14 INFO - 'name': 'check_screen_resolution'},), 04:54:14 INFO - 'run_cmd_checks_enabled': True, 04:54:14 INFO - 'suite': 'dromaeojs', 04:54:14 INFO - 'system_bits': '32', 04:54:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:54:14 INFO - 'title': 't-yosemite-r7-0094', 04:54:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:54:14 INFO - 'use_talos_json': True, 04:54:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:54:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:54:14 INFO - 'work_dir': 'build'} 04:54:14 INFO - [mozharness: 2017-03-29 11:54:14.017326Z] Running clobber step. 04:54:14 INFO - Running pre-action listener: _resource_record_pre_action 04:54:14 INFO - Running main action method: clobber 04:54:14 INFO - rmtree: /builds/slave/test/build 04:54:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:54:16 INFO - Running post-action listener: _resource_record_post_action 04:54:16 INFO - [mozharness: 2017-03-29 11:54:16.101533Z] Finished clobber step (success) 04:54:16 INFO - [mozharness: 2017-03-29 11:54:16.101664Z] Running read-buildbot-config step. 04:54:16 INFO - Running pre-action listener: _resource_record_pre_action 04:54:16 INFO - Running main action method: read_buildbot_config 04:54:16 INFO - Using buildbot properties: 04:54:16 INFO - { 04:54:16 INFO - "project": "", 04:54:16 INFO - "product": "firefox", 04:54:16 INFO - "script_repo_revision": "production", 04:54:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:54:16 INFO - "repository": "", 04:54:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:54:16 INFO - "buildid": "20170329030240", 04:54:16 INFO - "stage_platform": "macosx64", 04:54:16 INFO - "basedir": "/builds/slave/test", 04:54:16 INFO - "nightly_build": "True", 04:54:16 INFO - "buildnumber": 2, 04:54:16 INFO - "slavename": "t-yosemite-r7-0094", 04:54:16 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:54:16 INFO - "platform": "yosemite_r7", 04:54:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:54:16 INFO - "branch": "mozilla-central", 04:54:16 INFO - "repo_path": "mozilla-central", 04:54:16 INFO - "revision": "272ce6c2572164f5f6a9fba2a980ba9ccf50770c", 04:54:16 INFO - "pgo_build": "False", 04:54:16 INFO - "builduid": "c804c351bf334cb886e20815c105b1d9", 04:54:16 INFO - "slavebuilddir": "test" 04:54:16 INFO - } 04:54:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:54:16 INFO - Running post-action listener: _resource_record_post_action 04:54:16 INFO - [mozharness: 2017-03-29 11:54:16.105100Z] Finished read-buildbot-config step (success) 04:54:16 INFO - [mozharness: 2017-03-29 11:54:16.105187Z] Running download-and-extract step. 04:54:16 INFO - Running pre-action listener: _resource_record_pre_action 04:54:16 INFO - Running main action method: download_and_extract 04:54:16 INFO - mkdir: /builds/slave/test/build/tests 04:54:16 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')]} 04:54:16 INFO - trying https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:54:16 INFO - Downloading https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:54:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:54:17 INFO - Downloaded 1325 bytes. 04:54:17 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:54:17 INFO - Using the following test package requirements: 04:54:17 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:54:17 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:54:17 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:54:17 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:54:17 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'jsshell-mac.zip'], 04:54:17 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:54:17 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:54:17 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:54:17 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:54:17 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:54:17 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:54:17 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:54:17 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:54:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:54:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:54:17 INFO - Fetch https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:54:18 INFO - Expected file size: 52503960 04:54:18 INFO - Obtained file size: 52503960 04:54:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:54:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:54:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:54:35 INFO - Fetch https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:54:37 INFO - Expected file size: 52503960 04:54:37 INFO - Obtained file size: 52503960 04:54:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:54:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:54:52 INFO - Fetch https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:54:53 INFO - Expected file size: 14404171 04:54:53 INFO - Obtained file size: 14404171 04:54:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:53 INFO - trying https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:54:53 INFO - Downloading https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:54:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:54:55 INFO - Downloaded 75664372 bytes. 04:54:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:54:55 INFO - mkdir: /builds/slave/test/properties 04:54:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:54:55 INFO - Writing to file /builds/slave/test/properties/build_url 04:54:55 INFO - Contents: 04:54:55 INFO - build_url:https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:54:56 INFO - Running post-action listener: _resource_record_post_action 04:54:56 INFO - Running post-action listener: set_extra_try_arguments 04:54:56 INFO - [mozharness: 2017-03-29 11:54:56.537332Z] Finished download-and-extract step (success) 04:54:56 INFO - [mozharness: 2017-03-29 11:54:56.537463Z] Running populate-webroot step. 04:54:56 INFO - Running pre-action listener: _resource_record_pre_action 04:54:56 INFO - Running main action method: populate_webroot 04:54:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:54:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:54:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:54:56 INFO - '--tppagecycles', 04:54:56 INFO - '7'], 04:54:56 INFO - 'tests': ['tsvgm']}}, 04:54:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:54:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:54:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:54:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:54:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:54:56 INFO - 'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:54:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:54:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:54:56 INFO - 'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['damp', 'tps']}, 04:54:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:54:56 INFO - 'tests': ['damp', 'tps']}, 04:54:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['dromaeo_dom']}, 04:54:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:54:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['basic_compositor_video']}, 04:54:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:54:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['a11yr', 04:54:56 INFO - 'ts_paint', 04:54:56 INFO - 'tpaint', 04:54:56 INFO - 'sessionrestore', 04:54:56 INFO - 'sessionrestore_no_auto_restore', 04:54:56 INFO - 'tabpaint']}, 04:54:56 INFO - 'other-e10s': {'tests': ['a11yr', 04:54:56 INFO - 'ts_paint', 04:54:56 INFO - 'tpaint', 04:54:56 INFO - 'sessionrestore', 04:54:56 INFO - 'sessionrestore_no_auto_restore', 04:54:56 INFO - 'tabpaint']}, 04:54:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:54:56 INFO - 'ts_paint', 04:54:56 INFO - 'tpaint', 04:54:56 INFO - 'sessionrestore', 04:54:56 INFO - 'sessionrestore_no_auto_restore', 04:54:56 INFO - 'tabpaint']}, 04:54:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:54:56 INFO - 'ts_paint', 04:54:56 INFO - 'tpaint', 04:54:56 INFO - 'sessionrestore', 04:54:56 INFO - 'sessionrestore_no_auto_restore', 04:54:56 INFO - 'tabpaint']}, 04:54:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['a11yr', 04:54:56 INFO - 'ts_paint', 04:54:56 INFO - 'tpaint', 04:54:56 INFO - 'sessionrestore', 04:54:56 INFO - 'sessionrestore_no_auto_restore', 04:54:56 INFO - 'tabpaint']}, 04:54:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['a11yr', 04:54:56 INFO - 'ts_paint', 04:54:56 INFO - 'tpaint', 04:54:56 INFO - 'sessionrestore', 04:54:56 INFO - 'sessionrestore_no_auto_restore', 04:54:56 INFO - 'tabpaint']}, 04:54:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['tsvgx', 04:54:56 INFO - 'tsvgr_opacity', 04:54:56 INFO - 'tart', 04:54:56 INFO - 'tscrollx', 04:54:56 INFO - 'cart', 04:54:56 INFO - 'tsvg_static']}, 04:54:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:54:56 INFO - 'tsvgr_opacity', 04:54:56 INFO - 'tart', 04:54:56 INFO - 'tscrollx', 04:54:56 INFO - 'cart', 04:54:56 INFO - 'tsvg_static']}, 04:54:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:54:56 INFO - 'talos_options': ['--disable-e10s'], 04:54:56 INFO - 'tests': ['tp5o']}, 04:54:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:54:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:54:56 INFO - 'talos_options': ['--disable-e10s', 04:54:56 INFO - '--xperf_path', 04:54:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:56 INFO - 'tests': ['tp5n']}, 04:54:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:54:56 INFO - 'talos_options': ['--xperf_path', 04:54:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:54:56 INFO - 'tests': ['tp5n']}}, 04:54:56 INFO - 'talos.zip': {'path': '', 04:54:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:54:56 INFO - Running post-action listener: _resource_record_post_action 04:54:56 INFO - [mozharness: 2017-03-29 11:54:56.556314Z] Finished populate-webroot step (success) 04:54:56 INFO - [mozharness: 2017-03-29 11:54:56.556432Z] Running create-virtualenv step. 04:54:56 INFO - Running pre-action listener: _resource_record_pre_action 04:54:56 INFO - Running main action method: create_virtualenv 04:54:56 INFO - Creating virtualenv /builds/slave/test/build/venv 04:54:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:54:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:54:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:54:56 INFO - Using real prefix '/tools/python27' 04:54:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:54:57 INFO - Installing distribute.............................................................................................................................................................................................done. 04:55:00 INFO - Installing pip.................done. 04:55:00 INFO - Return code: 0 04:55:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:55:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:55:00 INFO - Reading from file tmpfile_stdout 04:55:00 INFO - Output received: 04:55:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:55:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:00 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:00 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 04:55:00 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 04:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:00 INFO - 'HOME': '/Users/cltbld', 04:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:00 INFO - 'LOGNAME': 'cltbld', 04:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:00 INFO - 'MOZ_NO_REMOTE': '1', 04:55:00 INFO - 'NO_EM_RESTART': '1', 04:55:00 INFO - 'PAGER': '/bin/cat', 04:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:00 INFO - 'PWD': '/builds/slave/test', 04:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:00 INFO - 'SHELL': '/bin/bash', 04:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:00 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:00 INFO - 'USER': 'cltbld', 04:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:00 INFO - 'XPC_FLAGS': '0x0', 04:55:00 INFO - 'XPC_SERVICE_NAME': '0', 04:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:55:00 INFO - Cleaning up... 04:55:00 INFO - Return code: 0 04:55:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:00 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:00 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 04:55:00 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 04:55:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:00 INFO - 'HOME': '/Users/cltbld', 04:55:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:00 INFO - 'LOGNAME': 'cltbld', 04:55:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:00 INFO - 'MOZ_NO_REMOTE': '1', 04:55:00 INFO - 'NO_EM_RESTART': '1', 04:55:00 INFO - 'PAGER': '/bin/cat', 04:55:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:00 INFO - 'PWD': '/builds/slave/test', 04:55:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:00 INFO - 'SHELL': '/bin/bash', 04:55:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:00 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:00 INFO - 'USER': 'cltbld', 04:55:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:00 INFO - 'XPC_FLAGS': '0x0', 04:55:00 INFO - 'XPC_SERVICE_NAME': '0', 04:55:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:01 INFO - Downloading/unpacking psutil>=3.1.1 04:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:01 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 04:55:01 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 04:55:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:55:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:55:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:04 INFO - Installing collected packages: psutil 04:55:04 INFO - Running setup.py install for psutil 04:55:04 INFO - building 'psutil._psutil_osx' extension 04:55:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:55:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:55:05 INFO - building 'psutil._psutil_posix' extension 04:55:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:55:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:55:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:55:05 INFO - ^ 04:55:05 INFO - 1 warning generated. 04:55:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:55:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:55:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:05 INFO - Successfully installed psutil 04:55:05 INFO - Cleaning up... 04:55:05 INFO - Return code: 0 04:55:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:55:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:55:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:55:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:05 INFO - 'HOME': '/Users/cltbld', 04:55:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:05 INFO - 'LOGNAME': 'cltbld', 04:55:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:05 INFO - 'MOZ_NO_REMOTE': '1', 04:55:05 INFO - 'NO_EM_RESTART': '1', 04:55:05 INFO - 'PAGER': '/bin/cat', 04:55:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:05 INFO - 'PWD': '/builds/slave/test', 04:55:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:05 INFO - 'SHELL': '/bin/bash', 04:55:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:05 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:05 INFO - 'USER': 'cltbld', 04:55:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:05 INFO - 'XPC_FLAGS': '0x0', 04:55:05 INFO - 'XPC_SERVICE_NAME': '0', 04:55:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:55:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:55:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:55:08 INFO - Installing collected packages: mozsystemmonitor 04:55:08 INFO - Running setup.py install for mozsystemmonitor 04:55:08 INFO - Successfully installed mozsystemmonitor 04:55:08 INFO - Cleaning up... 04:55:08 INFO - Return code: 0 04:55:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:09 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')]} 04:55:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:10 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:10 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 04:55:10 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 04:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:10 INFO - 'HOME': '/Users/cltbld', 04:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:10 INFO - 'LOGNAME': 'cltbld', 04:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:10 INFO - 'MOZ_NO_REMOTE': '1', 04:55:10 INFO - 'NO_EM_RESTART': '1', 04:55:10 INFO - 'PAGER': '/bin/cat', 04:55:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:10 INFO - 'PWD': '/builds/slave/test', 04:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:10 INFO - 'SHELL': '/bin/bash', 04:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:10 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:10 INFO - 'USER': 'cltbld', 04:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:10 INFO - 'XPC_FLAGS': '0x0', 04:55:10 INFO - 'XPC_SERVICE_NAME': '0', 04:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:10 INFO - Downloading/unpacking jsonschema==2.5.1 04:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:10 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 04:55:10 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 04:55:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:55:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:13 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 04:55:13 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 04:55:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:13 INFO - Downloading functools32-3.2.3-2.tar.gz 04:55:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:55:13 INFO - warning: no files found matching '*.txt' 04:55:13 INFO - no previously-included directories found matching 'build' 04:55:13 INFO - no previously-included directories found matching 'dist' 04:55:13 INFO - no previously-included directories found matching '.git*' 04:55:13 INFO - Installing collected packages: jsonschema, functools32 04:55:13 INFO - Running setup.py install for functools32 04:55:14 INFO - warning: no files found matching '*.txt' 04:55:14 INFO - no previously-included directories found matching 'build' 04:55:14 INFO - no previously-included directories found matching 'dist' 04:55:14 INFO - no previously-included directories found matching '.git*' 04:55:14 INFO - Successfully installed jsonschema functools32 04:55:14 INFO - Cleaning up... 04:55:14 INFO - Return code: 0 04:55:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:55:14 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')]} 04:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:14 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')]} 04:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:14 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:14 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 04:55:14 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 04:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:14 INFO - 'HOME': '/Users/cltbld', 04:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:14 INFO - 'LOGNAME': 'cltbld', 04:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:14 INFO - 'MOZ_NO_REMOTE': '1', 04:55:14 INFO - 'NO_EM_RESTART': '1', 04:55:14 INFO - 'PAGER': '/bin/cat', 04:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:14 INFO - 'PWD': '/builds/slave/test', 04:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:14 INFO - 'SHELL': '/bin/bash', 04:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:14 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:14 INFO - 'USER': 'cltbld', 04:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:14 INFO - 'XPC_FLAGS': '0x0', 04:55:14 INFO - 'XPC_SERVICE_NAME': '0', 04:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:55:14 INFO - Cleaning up... 04:55:14 INFO - Return code: 0 04:55:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:55:14 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')]} 04:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:14 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')]} 04:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:14 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:14 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 04:55:14 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 04:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:14 INFO - 'HOME': '/Users/cltbld', 04:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:14 INFO - 'LOGNAME': 'cltbld', 04:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:14 INFO - 'MOZ_NO_REMOTE': '1', 04:55:14 INFO - 'NO_EM_RESTART': '1', 04:55:14 INFO - 'PAGER': '/bin/cat', 04:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:14 INFO - 'PWD': '/builds/slave/test', 04:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:14 INFO - 'SHELL': '/bin/bash', 04:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:14 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:14 INFO - 'USER': 'cltbld', 04:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:14 INFO - 'XPC_FLAGS': '0x0', 04:55:14 INFO - 'XPC_SERVICE_NAME': '0', 04:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:14 INFO - Downloading/unpacking blobuploader==1.2.4 04:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:14 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 04:55:14 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 04:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:17 INFO - Downloading blobuploader-1.2.4.tar.gz 04:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:55:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:55:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:18 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 04:55:18 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 04:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:18 INFO - Downloading docopt-0.6.1.tar.gz 04:55:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:55:18 INFO - Installing collected packages: blobuploader, requests, docopt 04:55:18 INFO - Running setup.py install for blobuploader 04:55:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:55:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:55:18 INFO - Running setup.py install for requests 04:55:19 INFO - Running setup.py install for docopt 04:55:19 INFO - Successfully installed blobuploader requests docopt 04:55:19 INFO - Cleaning up... 04:55:19 INFO - Return code: 0 04:55:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55:19 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')]} 04:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:19 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')]} 04:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:19 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:19 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 04:55:19 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 04:55:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:19 INFO - 'HOME': '/Users/cltbld', 04:55:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:19 INFO - 'LOGNAME': 'cltbld', 04:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:19 INFO - 'MOZ_NO_REMOTE': '1', 04:55:19 INFO - 'NO_EM_RESTART': '1', 04:55:19 INFO - 'PAGER': '/bin/cat', 04:55:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:19 INFO - 'PWD': '/builds/slave/test', 04:55:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:19 INFO - 'SHELL': '/bin/bash', 04:55:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:19 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:19 INFO - 'USER': 'cltbld', 04:55:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:19 INFO - 'XPC_FLAGS': '0x0', 04:55:19 INFO - 'XPC_SERVICE_NAME': '0', 04:55:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:55:19 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-TCtNVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:55:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:55:19 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-5m3Fw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-X5lNUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-uGcpxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-84TPKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-sw9zQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0uRFrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-AU9AM5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-yfUK2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-vfmfEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:55:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:55:20 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ZH3TYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:55:21 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-zGp0BJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:55:21 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-OiSnPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:55:21 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-FT6ICW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:21 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-M6gyGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:55:21 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-M4igzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:55:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:55:21 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-qT_hYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:55:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:55:21 INFO - Running setup.py install for manifestparser 04:55:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:55:21 INFO - Running setup.py install for mozcrash 04:55:21 INFO - Running setup.py install for mozdebug 04:55:22 INFO - Running setup.py install for mozdevice 04:55:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:55:22 INFO - Running setup.py install for mozfile 04:55:22 INFO - Running setup.py install for mozhttpd 04:55:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:55:22 INFO - Running setup.py install for mozinfo 04:55:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:55:22 INFO - Running setup.py install for mozInstall 04:55:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:55:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:55:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:55:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:55:22 INFO - Running setup.py install for mozleak 04:55:22 INFO - Running setup.py install for mozlog 04:55:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:55:23 INFO - Running setup.py install for moznetwork 04:55:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:55:23 INFO - Running setup.py install for mozprocess 04:55:23 INFO - Running setup.py install for mozprofile 04:55:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:55:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:55:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:55:23 INFO - Running setup.py install for mozrunner 04:55:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:55:23 INFO - Running setup.py install for mozscreenshot 04:55:23 INFO - Running setup.py install for moztest 04:55:24 INFO - Running setup.py install for mozversion 04:55:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:55:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:55:24 INFO - Cleaning up... 04:55:24 INFO - Return code: 0 04:55:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55:24 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')]} 04:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:24 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')]} 04:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:24 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:24 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 04:55:24 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 04:55:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:24 INFO - 'HOME': '/Users/cltbld', 04:55:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:24 INFO - 'LOGNAME': 'cltbld', 04:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:24 INFO - 'MOZ_NO_REMOTE': '1', 04:55:24 INFO - 'NO_EM_RESTART': '1', 04:55:24 INFO - 'PAGER': '/bin/cat', 04:55:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:24 INFO - 'PWD': '/builds/slave/test', 04:55:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:24 INFO - 'SHELL': '/bin/bash', 04:55:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:24 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:24 INFO - 'USER': 'cltbld', 04:55:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:24 INFO - 'XPC_FLAGS': '0x0', 04:55:24 INFO - 'XPC_SERVICE_NAME': '0', 04:55:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:55:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-sMW4JV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:55:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-VNYPqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:55:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-KvJVUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:55:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-MMXCYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:55:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:55:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:55:24 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-rDMf8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-YLeXd1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-lzdrt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-qZPi2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-CZqlQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-lLELfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-u2IQRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-XB5KBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0kWzIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:55:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:55:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:55:25 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-P1jQOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:55:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:26 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-LXrHfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:55:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:55:26 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-8vumxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:55:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:55:26 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-5Raca2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:55:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:55:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:26 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 04:55:26 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 04:55:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:28 INFO - Downloading blessings-1.6.tar.gz 04:55:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:55:29 INFO - Installing collected packages: blessings 04:55:29 INFO - Running setup.py install for blessings 04:55:29 INFO - Successfully installed blessings 04:55:29 INFO - Cleaning up... 04:55:29 INFO - Return code: 0 04:55:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:55:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:55:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:55:29 INFO - Reading from file tmpfile_stdout 04:55:29 INFO - Current package versions: 04:55:29 INFO - blessings == 1.6 04:55:29 INFO - blobuploader == 1.2.4 04:55:29 INFO - docopt == 0.6.1 04:55:29 INFO - functools32 == 3.2.3-2 04:55:29 INFO - jsonschema == 2.5.1 04:55:29 INFO - manifestparser == 1.1 04:55:29 INFO - mozInstall == 1.12 04:55:29 INFO - mozcrash == 1.0 04:55:29 INFO - mozdebug == 0.1 04:55:29 INFO - mozdevice == 0.49 04:55:29 INFO - mozfile == 1.2 04:55:29 INFO - mozhttpd == 0.7 04:55:29 INFO - mozinfo == 0.9 04:55:29 INFO - mozleak == 0.1 04:55:29 INFO - mozlog == 3.4 04:55:29 INFO - moznetwork == 0.27 04:55:29 INFO - mozprocess == 0.25 04:55:29 INFO - mozprofile == 0.28 04:55:29 INFO - mozrunner == 6.13 04:55:29 INFO - mozscreenshot == 0.1 04:55:29 INFO - mozsystemmonitor == 0.3 04:55:29 INFO - moztest == 0.8 04:55:29 INFO - mozversion == 1.4 04:55:29 INFO - psutil == 3.1.1 04:55:29 INFO - requests == 1.2.3 04:55:29 INFO - wsgiref == 0.1.2 04:55:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:29 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:29 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 04:55:29 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 04:55:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:29 INFO - 'HOME': '/Users/cltbld', 04:55:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:29 INFO - 'LOGNAME': 'cltbld', 04:55:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:29 INFO - 'MOZ_NO_REMOTE': '1', 04:55:29 INFO - 'NO_EM_RESTART': '1', 04:55:29 INFO - 'PAGER': '/bin/cat', 04:55:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:29 INFO - 'PWD': '/builds/slave/test', 04:55:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:29 INFO - 'SHELL': '/bin/bash', 04:55:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:29 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:29 INFO - 'USER': 'cltbld', 04:55:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:29 INFO - 'XPC_FLAGS': '0x0', 04:55:29 INFO - 'XPC_SERVICE_NAME': '0', 04:55:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:55:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:55:29 INFO - Cleaning up... 04:55:29 INFO - Return code: 0 04:55:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:29 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 0x10408cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10408f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa98c77ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, '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.ElO4dylnAX/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.fuTKoJvTrd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:55:29 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 04:55:29 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 04:55:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:29 INFO - 'HOME': '/Users/cltbld', 04:55:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:29 INFO - 'LOGNAME': 'cltbld', 04:55:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:29 INFO - 'MOZ_NO_REMOTE': '1', 04:55:29 INFO - 'NO_EM_RESTART': '1', 04:55:29 INFO - 'PAGER': '/bin/cat', 04:55:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:29 INFO - 'PWD': '/builds/slave/test', 04:55:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:29 INFO - 'SHELL': '/bin/bash', 04:55:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:29 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:29 INFO - 'USER': 'cltbld', 04:55:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:29 INFO - 'XPC_FLAGS': '0x0', 04:55:29 INFO - 'XPC_SERVICE_NAME': '0', 04:55:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:55:30 INFO - Cleaning up... 04:55:30 INFO - Return code: 0 04:55:30 INFO - Running post-action listener: _resource_record_post_action 04:55:30 INFO - Running post-action listener: _start_resource_monitoring 04:55:30 INFO - Starting resource monitoring. 04:55:30 INFO - [mozharness: 2017-03-29 11:55:30.194544Z] Finished create-virtualenv step (success) 04:55:30 INFO - [mozharness: 2017-03-29 11:55:30.195506Z] Running install step. 04:55:30 INFO - Running pre-action listener: _resource_record_pre_action 04:55:30 INFO - Running main action method: install 04:55:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:55:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:55:30 INFO - Reading from file tmpfile_stdout 04:55:30 INFO - Detecting whether we're running mozinstall >=1.0... 04:55:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:55:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:55:30 INFO - Reading from file tmpfile_stdout 04:55:30 INFO - Output received: 04:55:30 INFO - Usage: mozinstall [options] installer 04:55:30 INFO - Options: 04:55:30 INFO - -h, --help show this help message and exit 04:55:30 INFO - -d DEST, --destination=DEST 04:55:30 INFO - Directory to install application into. [default: 04:55:30 INFO - "/builds/slave/test"] 04:55:30 INFO - --app=APP Application being installed. [default: firefox] 04:55:30 INFO - mkdir: /builds/slave/test/build/application 04:55:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:55:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:55:51 INFO - Reading from file tmpfile_stdout 04:55:51 INFO - Output received: 04:55:51 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:55:51 INFO - Running post-action listener: _resource_record_post_action 04:55:51 INFO - [mozharness: 2017-03-29 11:55:51.120616Z] Finished install step (success) 04:55:51 INFO - [mozharness: 2017-03-29 11:55:51.120761Z] Running run-tests step. 04:55:51 INFO - Running pre-action listener: _resource_record_pre_action 04:55:51 INFO - Running main action method: run_tests 04:55:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:55:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:55:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:55:51 INFO - 2017-03-29 04:55:51.135 screenresolution[1915:12725] starting screenresolution argv=screenresolution get 04:55:51 INFO - 2017-03-29 04:55:51.138 screenresolution[1915:12725] Display 0: 1600x1200x32@60 04:55:51 INFO - 2017-03-29 04:55:51.144 screenresolution[1916:12727] starting screenresolution argv=screenresolution list 04:55:51 INFO - Available Modes on Display 0 04:55:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:55:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:55:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:55:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:55:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:55:51 INFO - Intel Iris: 04:55:51 INFO - Chipset Model: Intel Iris 04:55:51 INFO - Type: GPU 04:55:51 INFO - Bus: Built-In 04:55:51 INFO - VRAM (Dynamic, Max): 1536 MB 04:55:51 INFO - Vendor: Intel (0x8086) 04:55:51 INFO - Device ID: 0x0a2e 04:55:51 INFO - Revision ID: 0x0009 04:55:51 INFO - Displays: 04:55:51 INFO - Display: 04:55:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:55:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:55:51 INFO - Display Serial Number: 1600x1200 60 04:55:51 INFO - Main Display: Yes 04:55:51 INFO - Mirror: Off 04:55:51 INFO - Online: Yes 04:55:51 INFO - Rotation: Supported 04:55:51 INFO - Return code: 0 04:55:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:55:51 INFO - Python 2.7.3 04:55:51 INFO - Return code: 0 04:55:51 INFO - grabbing minidump binary from tooltool 04:55: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')]} 04:55:51 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 0x103e6ad30>, '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 0x103d91db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103e5fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:55:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:55:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:55:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:55:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:55:51 INFO - Return code: 0 04:55:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:55:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:55:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:55:51 INFO - ENV: RUST_BACKTRACE is now 1 04:55:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:55:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:55:51 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 04:55:51 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --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 04:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ElO4dylnAX/Render', 04:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:51 INFO - 'HOME': '/Users/cltbld', 04:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:51 INFO - 'LOGNAME': 'cltbld', 04:55:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:55:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:51 INFO - 'MOZ_NO_REMOTE': '1', 04:55:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:55:51 INFO - 'NO_EM_RESTART': '1', 04:55:51 INFO - 'PAGER': '/bin/cat', 04:55:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:51 INFO - 'PWD': '/builds/slave/test', 04:55:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:51 INFO - 'RUST_BACKTRACE': '1', 04:55:51 INFO - 'SHELL': '/bin/bash', 04:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fuTKoJvTrd/Listeners', 04:55:51 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 04:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:51 INFO - 'USER': 'cltbld', 04:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:51 INFO - 'XPC_FLAGS': '0x0', 04:55:51 INFO - 'XPC_SERVICE_NAME': '0', 04:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:51 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 04:55:51 INFO - mozversion application_buildid: 20170329030240 04:55:51 INFO - mozversion application_changeset: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c 04:55:51 INFO - mozversion application_display_name: Nightly 04:55:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:55:51 INFO - mozversion application_name: Firefox 04:55:51 INFO - mozversion application_remotingname: firefox 04:55:51 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:55:51 INFO - mozversion application_vendor: Mozilla 04:55:51 INFO - mozversion application_version: 55.0a1 04:55:51 INFO - mozversion platform_buildid: 20170329030240 04:55:51 INFO - mozversion platform_changeset: 272ce6c2572164f5f6a9fba2a980ba9ccf50770c 04:55:51 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:55:51 INFO - mozversion platform_version: 55.0a1 04:55:51 INFO - using testdate: 1490788551 04:55:51 INFO - actual date: 1490788551 04:55:51 INFO - starting webserver on 'localhost:49499' 04:55:51 INFO - SUITE-START | Running 2 tests 04:55:51 INFO - TEST-START | dromaeo_css 04:55:51 INFO - operating with platform_type : mac_ 04:55:51 INFO - Initialising browser for dromaeo_css test... 04:55:51 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp8vXwMs/profile http://localhost:49499/getInfo.html) 04:55:54 INFO - PID 1926 | __metrics Screen width/height:1600/1200 04:55:54 INFO - PID 1926 | colorDepth:24 04:55:54 INFO - PID 1926 | Browser inner width/height: 1024/689 04:55:54 INFO - PID 1926 | __metrics 04:55:54 ERROR - PID 1926 | 1490788554420 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 04:55:54 INFO - PID 1926 | 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" 04:55:54 INFO - PID 1926 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:55:55 INFO - TEST-INFO | 1926: exit 0 04:55:55 INFO - Browser initialized. 04:55:55 INFO - Running cycle 1/1 for dromaeo_css test... 04:55:55 INFO - TEST-INFO | started process 1929 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmp8vXwMs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:56:07 INFO - PID 1929 | RSS: Main: 234082304 04:56:07 INFO - PID 1929 | 04:59:13 INFO - PID 1929 | Cycle 1(1): loaded http://localhost:49499/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49499/tests/dromaeo/cssquery-ext.html) 04:59:14 INFO - PID 1929 | RSS: Main: 382722048 04:59:14 INFO - PID 1929 | 05:00:57 INFO - PID 1929 | ************************* 05:00:57 INFO - PID 1929 | A coding exception was thrown and uncaught in a Task. 05:00:57 INFO - PID 1929 | 05:00:57 ERROR - PID 1929 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:00:57 INFO - PID 1929 | Full stack: 05:00:57 INFO - PID 1929 | ************************* 05:00:57 INFO - PID 1929 | ************************* 05:00:57 INFO - PID 1929 | A coding exception was thrown and uncaught in a Task. 05:00:57 INFO - PID 1929 | 05:00:57 ERROR - PID 1929 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:00:57 INFO - PID 1929 | Full stack: 05:00:57 INFO - PID 1929 | ************************* 05:00:57 INFO - PID 1929 | ************************* 05:00:57 INFO - PID 1929 | A coding exception was thrown and uncaught in a Task. 05:00:57 INFO - PID 1929 | 05:00:57 ERROR - PID 1929 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:00:57 INFO - PID 1929 | Full stack: 05:00:57 INFO - PID 1929 | ************************* 05:00:57 INFO - PID 1929 | ************************* 05:00:57 INFO - PID 1929 | A coding exception was thrown and uncaught in a Task. 05:00:57 INFO - PID 1929 | 05:00:57 ERROR - PID 1929 | Full message: TypeError: NetworkError when attempting to fetch resource. 05:00:57 INFO - PID 1929 | Full stack: 05:00:57 INFO - PID 1929 | ************************* 05:02:17 INFO - PID 1929 | Cycle 1(1): loaded http://localhost:49499/tests/dromaeo/cssquery-ext.html (next: http://localhost:49499/tests/dromaeo/cssquery-jquery.html) 05:02:18 INFO - PID 1929 | RSS: Main: 368738304 05:02:18 INFO - PID 1929 | 05:05:20 INFO - PID 1929 | Cycle 1(1): loaded http://localhost:49499/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49499/tests/dromaeo/cssquery-mootools.html) 05:05:21 INFO - PID 1929 | RSS: Main: 358744064 05:05:21 INFO - PID 1929 | 05:08:24 INFO - PID 1929 | Cycle 1(1): loaded http://localhost:49499/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49499/tests/dromaeo/cssquery-prototype.html) 05:08:24 INFO - PID 1929 | RSS: Main: 330551296 05:08:24 INFO - PID 1929 | 05:11:28 INFO - PID 1929 | Cycle 1(1): loaded http://localhost:49499/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49499/tests/dromaeo/cssquery-yui.html) 05:11:29 INFO - PID 1929 | RSS: Main: 335732736 05:11:29 INFO - PID 1929 | 05:14:32 INFO - PID 1929 | Cycle 1(1): loaded http://localhost:49499/tests/dromaeo/cssquery-yui.html (next: http://localhost:49499/tests/dromaeo/cssquery-dojo.html) 05:14:33 INFO - PID 1929 | RSS: Main: 607367168 05:14:33 INFO - PID 1929 | 05:14:33 INFO - PID 1929 | __start_tp_report 05:14:33 INFO - PID 1929 | _x_x_mozilla_page_load 05:14:33 INFO - PID 1929 | _x_x_mozilla_page_load_details 05:14:33 INFO - PID 1929 | |i|pagename|runs| 05:14:33 INFO - PID 1929 | |0;dojo.html;2910.825350478971;3403.285310084882;3421.7091547218483;3471.30573885223;3478.46947343169;5864.384239654828;6080.787172448965;6127;6143.078538219269;6152.477039451652;6114.510272098694;6139;6159.568830181889;6164.660943648098;6189.2572891253;4948.2824990376375;5328.973355133219;5505.724713764296;5512.80705175319;5532.5573954083575;5581.023320918823;5582.190582365554;5640.238567793357;5656.745446454919;5693.544516438675;5371.785128594832;5419.268398766156;5427.810026649048;5441.102218134003;5448.37343705475;5463.48096930791;5466.070767969455;5479.671219726829;5533.889322213573;5574.944250557483;4557.977210113928;5465.890682186374;5510.952918945388;5528.087865502187;5550.666959982414;6070.757169713206;6094.329623741385;6115.87768244637;6127.172831667713;6153.661548614825;6187.504999600022;6503;6513.544051916368;6526.510511711641;6538.8365290868105;6354.301026887085;6397.680115994228;6442.098106265126;6443.548951573392;6445.452136568398;6122.051082082285;6122.550631082941;6140.350877192993;6151.108089327023;6163.876722465615;6668.199816022108;6752.93247067523;6756.493263005196;6770.187577490733;6770.898436523456;6272.341404152589;6309.936900631027;6317.368263173737;6409.327020662763;6437.0988061671405;6393.968030159819;6403.903941440882;6407.615543067431;6418.679066046679;6438.034294855715;6337.17616709834;6347.682615869188;6389.424951754364;6432.0351947207355;6436.645984470899;6471.061696054046;6505.934940650627;6508.186476690414;6510;6514.990176522647;4378.693491455631;5091.567216786615;5143.8456846294675;5185.144451165591;5247.2653828464045;4569.9314510282375;4594.379758732528;4605.65457590682;4655.4180727409075;4704.200285951397;6188.133661287423;6243;6245.5628106033055;6250.499960003189;6255.999040153553;6276.246850377984;6298.086777417248;6615.272320044791;6616.8345791355605;6624.503162262753;6101.307919489667;6217.129601855744;6227;6233.812985610439;6262.624242545462;5794.304683438015;5802.767889284381;5805.7387417566315;5810.535157187415;5825.533957283407;6782.491313151435;6793.2867048961125;6814.386705196556;6827.93172068273;6853.417459515997;6319.462845657987;6379.915414379474;6386.840328991812;6472.223333200046;6590.8352291193105;6782.864342713019;6806.727730890709;6823.38589526945;6834.111565496453;6841.726330946706;6386.616802991835;6475.935240647534;6476.643784591704;6479.287278399278;6507.60954342722;6284.8428789278805;6354.23749150105;6455.160829092188;6578.440832529097;6616.603003819786;6271.592346497463;6357.396047375492;6358.841028974312;6361.713722882574;6361.904571431525;6185.288691800501;6197.163382943245;6240.438360547572;6331.018692102732;6352.142460767737;4884.389451318585;6174.320824709189;6280.466160376236;6286.025666021774;6298.622082674871;5251.238570407193;6184.474319682878;6395.456386207225;6398.200224971879;6423.325550817097;6184.6598437086395;6200.689965501797;6214.067889816383;6243.313235543996;6261.123442718114 05:14:33 INFO - PID 1929 | |1;ext.html;751.5753599216442;767.4627760567603;808.894843670323;1134.1267224237338;1154.2266681323513;27632.171034868938;28173.295667608294;28240.717592824065;36609;37062.62937370625;38757.22485550287;39026.80486597563;39158.4126238106;39174;39185.21629567407;8343.499390036586;8523.06246312905;8536.615852286632;8541.786455338635;8547.914520854805;6036.3661815509395;6194.318624951275;8217.7123800667;8385.203405676451;8415.95792021039;8451.746447606582;8503.744887653349;8506.82986340276;8508.872366914502;8535.231829135377;8421.989361276655;8458.66165353385;8487.957560212159;8494.108118647577;8518.190771876692;8431.241188293083;8498.277646400064;8503.872441913376;8514.659413623447;8617.741467755977;38092.61907380918;38169.42745858814;38216.85349439521;38302.04244893872;38348.616513834786;3050.633923929143;3074.723274905247;3077.79994300372;3089.4439000979814;3098.2719061020643;27504.76228569719;27511.037113701124;27695.584566231522;27837.443251135068;27941.860290698416;2784.3456787654886;2791.3719413132085;2810.016494227025;2821.9294517637018;2836.1349788314556;335579;335893;335936;336608;351061.48938510544;35343.82328088343;50174.498255017716;50263.24605130926;50381.74809125931;50382.49617503851;53191;53212;53257;53314.7334263334;53315.33423328896;14317.284135793168;14630.19533925623;14647.974641774974;14651.194184319966;14654.926725366306;11845.23006004607;11896.821547676791;11921.165518413802;11922.701932451759;11925.986291165174;10736.89263107359;10826.945865270623;12235.204711693712;12354.876451235552;12455.93772031134;5500.724963751876;5555.2778138841695;5562.47156520138;5595.608307418523;5614.862400903209;954.9140577348072;970.4080510888352;975.1077763846143;979.304693667504;988.5255077562811;27797.722022779515;28420;28963.56554651682;28976.71023289782;29017.854910725735;907.6732376344511;1017.7913527726946;1025.958961641526;1057.931234469757;1074.065562960214;11483.598074067368;12140.15018948665;12152.57465988686;12204;12228.266304021787;368620;369545;369756;370190;370355;51672.74163629233;51755.741221293654;51916.48083519192;51936.74031629818;53997;1454.9054311469931;1457.3951809999155;1507.5929499035199;1509.939602415913;1512.4026009726097;21946.561068778854;23303.533929321657;23335.2999410018;23349.649755254024;23451.94466249057;39154.216915662095;39251.214975700896;39324;39330.41004384994;39564.60435395609;382110;383717;384496;384767;384817;32096.35807283888;32422.35155296928;32671.282525557457;32725.836370818946;32895.67104329022;31179.376412472076;31853.681463185072;36723.26553468969;40201.39697904475;40496.595034049285;26809.061682841006;27342.726572734813;27531.31171720723;27807.165785026482;28597.571036434885;26929.730702692723;27390.5891411625;28100.859495702392;28173.71826281711;28563.143105706862;31868.043958680348;32234.516482253253;32639.34721305513;33176;33974.15064623404;28336;29611.111666650035;29926;29937;30012.549811753277;20209;22993.941332906732;23038.42403939915;23262.883685581462;23518 05:14:33 INFO - PID 1929 | |2;jquery.html;412.6306955275029;416.46068341497755;418.96019878111576;421.8460262004368;439.0494579235954;18461.7230741539;20014.69977950332;20449;21168.364949051534;21940.561188776213;22336.55326893461;22809.657855132144;22885.01368352564;22927.312180634566;23329.650055249185;6089.665068421235;6337.144485494468;6363.936360636404;6365.1407060046995;6379.649119298437;5976.223090998186;6226;6300;6317.399847014526;6320.683965801692;5926.4073592640825;6188.071789231606;6210.378962103799;6222.5644204905675;6245.718942647569;5873.089671100944;6133.11069894865;6172.969135154318;6179.258488981351;6184.350643182446;5418.431064738224;5481.616286859921;5491.093969495028;5496.780128794843;5498.972505137449;26843.32891677704;26876.59685104725;27107.45785084307;27420.17739467819;28647.997320093906;1944.3686260886798;2022.5979168630918;2048.2114385961513;2100.24391219161;2131.4564785979633;15971.361145554163;16234.512964610962;16433.671326573403;16555.83444165555;16826.990380577205;1325.8995034121751;1612.5404259785905;1630.0790053619667;1633.5834362237554;1634.133909028217;152887;163822.3617763831;164414.3558564411;166535;168640.15679921643;11981.820272695917;12104.334261615519;12200.023998080133;12221.205621634743;12406;16217.918910405608;16224.269907854175;16224.675506489804;16231.431899883448;16255.918720406322;7406.259374062658;8652.480851148952;8849.778755531162;8869.822603547893;8905.866412003825;7027.402670772939;8269.503829770232;8411.285040771603;8595.484270943763;8614.353923455767;6342.682865856688;6918.238993710688;7260.2739726026975;7705.537667739842;7748.22517748232;1813.6150961485869;2043.9897800510903;2082.5106100066473;2087.7912208779;2219.9778002220096;808.3088958940021;829.0383155539545;884.787650963764;901.932355073359;925.3522534226069;19061.9416319236;21986.89006554989;24640.507189856104;25468.49063018766;25700.742992569834;930.0048947625975;949.6106596295485;951.8286708392556;954.6467806911487;978.9070038346346;9706.514674266258;10129.696109116738;10325.535350909227;10590.947045264724;11196.160287978435;408905;409690;410662;592095;723089;116518;131276.3436182813;133268.66731332563;149887.50112498953;161920;1163.2845799833042;1204.500132445043;1207.4506099724604;1230.0897335971317;1252.2799390350585;20740.792592073885;21396.893015534824;21514.784852151904;21547.78452215458;21566.412030556432;48003.5199647999;51641;57366.13950790825;57426.7128664354;61569.07646385398;82459.46472943806;84111.57944210485;85062;90200;91174;12574.308413037004;12975.610731678065;13154.276514791396;13236.823162440753;13492.257925814174;13714;13756.449742010207;13900.235487048309;14330.426782928984;14342.856571434151;11638.185327027028;11686.590969316034;11885.524579017088;12024.75950481003;12072.939635301767;11062.612808551663;11272.887271127513;11377.862213778555;11420.200585958903;11439.885601144215;11455.942720286344;12033.278003319829;12041.277523348626;12294.20087694297;12318.384080795746;12318.384080796104;12831.743365132832;12976.416061277454;12990;13335.733285334432;11640.592579259684;11694.064474842162;11912.880871191524;12012.819807702717;12057.698557536132 05:14:33 INFO - PID 1929 | |3;mootools.html;1186.9525218991241;1252.4489224741112;1422.7083447893183;1440.3662388549037;1445.4724025730604;9186.770330741727;10702.946485267572;10926.399048052353;11007.889921100797;11019.944900275508;10653.84019239712;10797.5680972761;10807.783844323129;10821.13430925526;10977.451127443615;2434.4887573609562;2481.85108893467;2486.080150344375;2486.627005949104;2487.440325926661;2074.408793493855;2163.3401812447196;2383.761623837611;2463.174836429789;2485.3662316109367;2422.4549476367765;2444.352246654629;2447.449323902126;2450.4608986023045;2452.730199678034;2401.1835975768236;2419.8669073200967;2441.5727247731666;2444.181199298237;2467.592847180213;2053.414776788608;2396.6405039244078;2415.5048215115858;2444.5477586646525;2453.9754602453927;9026;10805.189610779144;10815.297005694683;10916.181286403473;10961.177911656658;6147.323794382659;6397.200349956255;6400;6411.134496842913;6419.711112999927;3798.1264309209037;3857.768533887948;4070.8778736637946;4118;4118.361653943643;1191.7795207886568;1210.3222195570509;1525.1078119300223;1585.5560443075958;1638.86069684077;12928.806067908989;14578.489752858599;15203.163825989566;15224.086554806747;15236.162011089385;5355.437679043722;5412.675239485643;5627.887442251132;5873.442023007808;5911.201987731602;11387.259828111146;11449.54201831934;11474.713132171595;11485.368304743319;11501.309921404743;6246.625202487865;6248.125262463259;6250.9062364064575;6252.906206406908;6270.2475702915945;4922.827701030446;5733.913991290134;5826.096954971986;5885.588008839341;5983.760649574056;3232.660570640096;3773.52830896138;3880.7089468289428;4236.322188449833;4274.6962176535835;1237.7153254751277;1250.4373032135575;1257.8050402187675;1275.043717212091;1275.0500876846809;697.8499622581068;743.1824992508198;749.1609397474863;774.7598244544209;779.0729032451364;5990.400959903975;6127;6665.833354166088;6728.562643428259;6904.620245886525;825.5005721538503;849.3757088539918;876.838592868582;891.9821603568022;905.0406569036842;4003.319435695939;4160.794778767258;4212.347086201644;4288.442502474721;4315.654747620183;13467.543916445391;14001.089929154572;14032.649183770283;14333.140011599337;14428.783568246276;10479.371237725762;13993.090449120775;14088.366023528966;14206.786898196535;14238.572842814732;10261.28171028021;10326.896731032744;10373.118284945864;10396.272260941661;10507.369557826401;6435.613863168225;6523.575967562093;6537.771178008747;6577.50668699859;6650.135482387259;12511.186772859735;12564.685882853002;12879.678008049874;12883.35583220854;12887.742245155232;12385;12696.492140314653;12727.554535591209;12744.362781861055;12865.614031579067;8345.165483451607;8811.779705507157;8867.866981995405;8991.235744961752;9096.044915284065;8712.520811355436;8726.083761204982;8971.28229741606;10040.748981275527;10151.390916544735;7221.638918053971;8450.915490845013;8505.447145935494;8566.52884091381;8571.4000019998;8299.83400332002;8409.831803363775;8499.57502124879;8579.871301930601;8673.653053878026;7608.2391760823475;7631.54210747357;7690;7719.228077192236;7735.535867847947;4623.42207224097;5815.883682326245;5960.731767070405;6043.486303664062;6081.361457046946;5892.1161825726485;5987.43119403656;6063.727132279147;6146.846328841814;6242.937570624235 05:14:33 INFO - PID 1929 | |4;prototype.html;385.8533757172275;388.1305874840357;392.9332013557695;397.25712916845504;398.6770715720266;5055.721935293557;5979.641221526712;5989.910151347728;6062.4543791058795;6133.080037994292;5637.238972738679;5786.755847492788;5804.361520232771;5874.3244526879425;5970.492508136814;4653.16243076247;4679.391679081715;4718.221493453576;4718.598919091882;4720.881977950561;4092.386142078697;4679.766011699418;4711.269753188244;4713.481517033124;4781.848168324251;4462.531434199411;4633.142868569325;4667.7199368037855;4669.9066018679605;4684.625229981611;4192.287311157111;4282.272013757669;4646.97766491373;4648.628109751212;4669.229577119771;3085.4291955988215;3928.204206722386;4104.835806567734;4161.417401563784;4171.311733563958;4527.818887244506;4808.75956202192;4986.5013498650205;5847.356790753014;5939.792107276225;4515.638748900113;4732.858014259543;5387.353517577876;5388.43421440747;5415.702136382497;4690.671652984292;5729.598928075036;6350.682465876687;6404.903926441107;6504.837379065514;3970.761754294723;4378.255696531597;4499.842505512292;4595.253803554628;4664.486906440289;5616.297962754656;6417.582857114319;6701.195856497252;6766.627835469048;6882;4663.253879379351;4721.881952951135;4753.168195565831;4796.040791841646;4822.132016237111;4567;4583.908321833612;4863.683860549053;4872.244802055687;4898.90202195954;5157.974210128926;5385.515303622615;5403.945960540423;5421.069724449742;5498.395176530578;4981.800727970841;5626.409227031185;6501.9843848407145;6503.6097834130105;6506.512011599149;1355.3194039391085;1658.7428948513;1660.4271526323398;1667.2664027827716;1681.9495415137321;1016.517154351677;1101.5979167603766;1127.4869934179928;1143.3254379915888;1150.930944143354;5737.799177028867;5962.195103661037;6030.698465076729;6043.395660434009;6064.48451881595;5561.916571251434;6572.507061970371;6578.144841170713;6591.110200123018;6603.70283337251;5663.348714897757;5803.825885223366;5964;5987.832372687284;6062.636241825505;3047.481928072233;3297.9835100824343;3441.296558203705;3470.375332440185;3507.8772242971886;5666.121751128581;5714.828555143353;6424.903626445607;6590.209174898947;6752.932470675329;6215.658138802319;6487.086309538698;6858.245592984767;6862.588244705334;6895.5173137879865;5833.795817146464;6421.229452465734;6618.7352505899225;6813.318668133147;6837.427391699937;5447.824566661767;5624.240727501735;6584.374484423972;6613.338666133348;6697.330266973264;5596.888062238651;5773.16289138081;6613.636250006296;6652.634105124071;6666.400023997863;6626.337366263335;6727.663616819237;6808.65956702153;6925.411340036153;6971.457691135458;3557.697595704394;4251.808668609981;4344.543822898677;4397.516273209881;4424.690271680952;5489.066858633962;6339.746410143543;6685.76599819004;6686.130802995774;6751;6332.873342533216;6414.390632889868;6430.549861509649;6457.483401327976;6463.418292353711;6057.515398768176;6172.358308597769;6442.8389290268115;6443.743470023488;6489.415952564292;5199.558037566698;5221.634485586124;5436.3476382834315;6243.500519958302;6305.464035557029;4818.855434336975;5037.722925239147;6325.746970121328;6410.1987251593555;6494.6103233806125;5994.6403215807195;6199;6258.311585725475;6270.968645156745;6284.308726040041 05:14:33 INFO - PID 1929 | |5;yui.html;676.597424532403;716.3767522255636;731.9707211711531;733.2703959560237;738.3613174603969;17294.567635809097;17709.82290177098;17936.103194840238;18258.178381972793;18313.901165930038;16983.57541061474;17317;17551.297948082094;17579.38472153475;17581.29674813006;919.6137622198676;952.3666761603525;953.909378609031;954.1079091049891;957.1768279279814;968.8837339519267;1001.1940387987667;1003.7841863999239;1006.7936073105033;1011.716719318592;1055.5091882274742;1056.10758908722;1057.7249915022073;1057.9682609521726;1069.7432616172102;1045.764702941832;1047.9056884880397;1050.0864248104122;1051.363924825481;1063.0538820449806;785.8899754034383;787.0083694544915;797.0634504457239;800.8918795962529;800.9439339246255;15905.443309484228;16167.595810104724;16367.42714005016;16606.335746570123;16890.915545422195;14380.568582942424;14562.854371456362;14586.635334116625;14737.705245895022;14746.631334216623;11542.769144617061;11689.824652630217;11762.94118529402;11962;12213.511459541607;2977.330100727341;3099.4575949208797;3132.1856317357424;3143.6070491188602;3166.8891588794513;104718.95281047169;107942.46028769885;112055;112464.4376778119;114280.42859785732;11372.715682108013;11445.198836081394;11461.197716159955;11642.592509262135;11903.69059403465;22484.988175531813;24323.878380608337;25361.61957570638;25365.239042828376;25464.745352546237;6891.827704307432;7148.678309476086;7746.57393843344;7754.302112809762;7811.984442022501;4040.454538637305;4136;4138.896527586834;4141.751494910315;4144.647704945113;4114.897127571775;4142.337226043818;4217.093324935154;4244.978775106167;4255.680823938217;744.5458270455018;759.6277823866266;778.7352300217954;793.2662287384146;807.184743409161;479.4701854450782;547.8301726464808;550.402812947948;573.850798792317;616.51295476573;9660.565274562661;9992.950035249778;9996.200303975664;10051.949740251252;10197.847032294521;531.6225479909231;564.1284215886466;575.3670961941914;598.308953159102;601.4649542449965;4651.99605864264;4737.502562230985;4810.577878532129;4819.2530157825595;4905.23968784839;70681.8193181308;73692;76121;95260.57109143368;99537;53720;62233;86987.56506217555;87964.68052979211;89268;65605.42353899972;69717.95423068658;69961;69981.65009174819;70611;10238.897611023795;10655.786884262427;10797.514111864828;10899.29154604948;10921.344719316867;35068;35852;36751.632483675894;36758.63241367552;37276.62723372732;41398.79300603598;49676.75161624169;50155;51047.489525104276;51347;13561.79657305161;13621.523246686318;13733.519326823513;13782.862171378158;13985.510507132201;11947.426567121238;14205.857941420454;14584.416623334948;14702.485413010494;14828.258587070819;12736;13500.189988600716;13564.253966031569;13675.247861367323;14027.859721403063;13245;13482.19106853592;13636;13886.861131388558;13944.58166255014;12879.88999382228;13007.089503734647;13010.739785204432;13140.671483212996;13534.052616316763;9600.381156572052;9825;9987.450690212108;10022.3485473444;10918.29031113007;8740.984360395654;10180.42455764864;10506.789864202827;11226.494807733836;11241.55033798639 05:14:33 INFO - PID 1929 | __end_tp_report 05:14:33 INFO - PID 1929 | __start_cc_report 05:14:33 INFO - PID 1929 | _x_x_mozilla_cycle_collect,1463 05:14:33 INFO - PID 1929 | __end_cc_report 05:14:33 INFO - PID 1929 | __startTimestamp1490789673161__endTimestamp 05:14:33 INFO - PID 1929 | 05:14:33 INFO - PID 1929 | ------- Summary: start ------- 05:14:33 INFO - PID 1929 | Number of tests: 6 05:14:33 INFO - PID 1929 | 05:14:33 INFO - PID 1929 | [#0] dojo.html Cycles:165 Average:6018.97 Median:6243.31 stddev:714.48 (11.4%) stddev-sans-first:673.79 05:14:33 INFO - PID 1929 | Values: 2910.8 3403.3 3421.7 3471.3 3478.5 5864.4 6080.8 6127.0 6143.1 6152.5 6114.5 6139.0 6159.6 6164.7 6189.3 4948.3 5329.0 5505.7 5512.8 5532.6 5581.0 5582.2 5640.2 5656.7 5693.5 5371.8 5419.3 5427.8 5441.1 5448.4 5463.5 5466.1 5479.7 5533.9 5574.9 4558.0 5465.9 5511.0 5528.1 5550.7 6070.8 6094.3 6115.9 6127.2 6153.7 6187.5 6503.0 6513.5 6526.5 6538.8 6354.3 6397.7 6442.1 6443.5 6445.5 6122.1 6122.6 6140.4 6151.1 6163.9 6668.2 6752.9 6756.5 6770.2 6770.9 6272.3 6309.9 6317.4 6409.3 6437.1 6394.0 6403.9 6407.6 6418.7 6438.0 6337.2 6347.7 6389.4 6432.0 6436.6 6471.1 6505.9 6508.2 6510.0 6515.0 4378.7 5091.6 5143.8 5185.1 5247.3 4569.9 4594.4 4605.7 4655.4 4704.2 6188.1 6243.0 6245.6 6250.5 6256.0 6276.2 6298.1 6615.3 6616.8 6624.5 6101.3 6217.1 6227.0 6233.8 6262.6 5794.3 5802.8 5805.7 5810.5 5825.5 6782.5 6793.3 6814.4 6827.9 6853.4 6319.5 6379.9 6386.8 6472.2 6590.8 6782.9 6806.7 6823.4 6834.1 6841.7 6386.6 6475.9 6476.6 6479.3 6507.6 6284.8 6354.2 6455.2 6578.4 6616.6 6271.6 6357.4 6358.8 6361.7 6361.9 6185.3 6197.2 6240.4 6331.0 6352.1 4884.4 6174.3 6280.5 6286.0 6298.6 5251.2 6184.5 6395.5 6398.2 6423.3 6184.7 6200.7 6214.1 6243.3 6261.1 05:14:33 INFO - PID 1929 | 05:14:33 INFO - PID 1929 | [#1] ext.html Cycles:180 Average:49713.01 Median:335736.00 stddev:96450.76 (28.7%) stddev-sans-first:96651.27 05:14:33 INFO - PID 1929 | Values: 751.6 767.5 808.9 1134.1 1154.2 27632.2 28173.3 28240.7 36609.0 37062.6 38757.2 39026.8 39158.4 39174.0 39185.2 8343.5 8523.1 8536.6 8541.8 8547.9 6036.4 6194.3 8217.7 8385.2 8416.0 8451.7 8503.7 8506.8 8508.9 8535.2 8422.0 8458.7 8488.0 8494.1 8518.2 8431.2 8498.3 8503.9 8514.7 8617.7 38092.6 38169.4 38216.9 38302.0 38348.6 3050.6 3074.7 3077.8 3089.4 3098.3 27504.8 27511.0 27695.6 27837.4 27941.9 2784.3 2791.4 2810.0 2821.9 2836.1 335579.0 335893.0 335936.0 336608.0 351061.5 35343.8 50174.5 50263.2 50381.7 50382.5 53191.0 53212.0 53257.0 53314.7 53315.3 14317.3 14630.2 14648.0 14651.2 14654.9 11845.2 11896.8 11921.2 11922.7 11926.0 10736.9 10826.9 12235.2 12354.9 12455.9 5500.7 5555.3 5562.5 5595.6 5614.9 954.9 970.4 975.1 979.3 988.5 27797.7 28420.0 28963.6 28976.7 29017.9 907.7 1017.8 1026.0 1057.9 1074.1 11483.6 12140.2 12152.6 12204.0 12228.3 368620.0 369545.0 369756.0 370190.0 370355.0 51672.7 51755.7 51916.5 51936.7 53997.0 1454.9 1457.4 1507.6 1509.9 1512.4 21946.6 23303.5 23335.3 23349.6 23451.9 39154.2 39251.2 39324.0 39330.4 39564.6 382110.0 383717.0 384496.0 384767.0 384817.0 32096.4 32422.4 32671.3 32725.8 32895.7 31179.4 31853.7 36723.3 40201.4 40496.6 26809.1 27342.7 27531.3 27807.2 28597.6 26929.7 27390.6 28100.9 28173.7 28563.1 31868.0 32234.5 32639.3 33176.0 33974.2 28336.0 29611.1 29926.0 29937.0 30012.5 20209.0 22993.9 23038.4 23262.9 23518.0 05:14:33 INFO - PID 1929 | 05:14:33 INFO - PID 1929 | [#2] jquery.html Cycles:180 Average:35374.26 Median:21531.28 stddev:90322.33 (419.5%) stddev-sans-first:90537.56 05:14:33 INFO - PID 1929 | Values: 412.6 416.5 419.0 421.8 439.0 18461.7 20014.7 20449.0 21168.4 21940.6 22336.6 22809.7 22885.0 22927.3 23329.7 6089.7 6337.1 6363.9 6365.1 6379.6 5976.2 6226.0 6300.0 6317.4 6320.7 5926.4 6188.1 6210.4 6222.6 6245.7 5873.1 6133.1 6173.0 6179.3 6184.4 5418.4 5481.6 5491.1 5496.8 5499.0 26843.3 26876.6 27107.5 27420.2 28648.0 1944.4 2022.6 2048.2 2100.2 2131.5 15971.4 16234.5 16433.7 16555.8 16827.0 1325.9 1612.5 1630.1 1633.6 1634.1 152887.0 163822.4 164414.4 166535.0 168640.2 11981.8 12104.3 12200.0 12221.2 12406.0 16217.9 16224.3 16224.7 16231.4 16255.9 7406.3 8652.5 8849.8 8869.8 8905.9 7027.4 8269.5 8411.3 8595.5 8614.4 6342.7 6918.2 7260.3 7705.5 7748.2 1813.6 2044.0 2082.5 2087.8 2220.0 808.3 829.0 884.8 901.9 925.4 19061.9 21986.9 24640.5 25468.5 25700.7 930.0 949.6 951.8 954.6 978.9 9706.5 10129.7 10325.5 10590.9 11196.2 408905.0 409690.0 410662.0 592095.0 723089.0 116518.0 131276.3 133268.7 149887.5 161920.0 1163.3 1204.5 1207.5 1230.1 1252.3 20740.8 21396.9 21514.8 21547.8 21566.4 48003.5 51641.0 57366.1 57426.7 61569.1 82459.5 84111.6 85062.0 90200.0 91174.0 12574.3 12975.6 13154.3 13236.8 13492.3 13714.0 13756.4 13900.2 14330.4 14342.9 11638.2 11686.6 11885.5 12024.8 12072.9 11062.6 11272.9 11377.9 11420.2 11439.9 11455.9 12033.3 12041.3 12294.2 12318.4 12318.4 12831.7 12976.4 12990.0 13335.7 11640.6 11694.1 11912.9 12012.8 12057.7 05:14:33 INFO - PID 1929 | 05:14:33 INFO - PID 1929 | [#3] mootools.html Cycles:180 Average:6729.78 Median:3503.09 stddev:4140.69 (118.2%) stddev-sans-first:4131.35 05:14:33 INFO - PID 1929 | Values: 1187.0 1252.4 1422.7 1440.4 1445.5 9186.8 10702.9 10926.4 11007.9 11019.9 10653.8 10797.6 10807.8 10821.1 10977.5 2434.5 2481.9 2486.1 2486.6 2487.4 2074.4 2163.3 2383.8 2463.2 2485.4 2422.5 2444.4 2447.4 2450.5 2452.7 2401.2 2419.9 2441.6 2444.2 2467.6 2053.4 2396.6 2415.5 2444.5 2454.0 9026.0 10805.2 10815.3 10916.2 10961.2 6147.3 6397.2 6400.0 6411.1 6419.7 3798.1 3857.8 4070.9 4118.0 4118.4 1191.8 1210.3 1525.1 1585.6 1638.9 12928.8 14578.5 15203.2 15224.1 15236.2 5355.4 5412.7 5627.9 5873.4 5911.2 11387.3 11449.5 11474.7 11485.4 11501.3 6246.6 6248.1 6250.9 6252.9 6270.2 4922.8 5733.9 5826.1 5885.6 5983.8 3232.7 3773.5 3880.7 4236.3 4274.7 1237.7 1250.4 1257.8 1275.0 1275.1 697.8 743.2 749.2 774.8 779.1 5990.4 6127.0 6665.8 6728.6 6904.6 825.5 849.4 876.8 892.0 905.0 4003.3 4160.8 4212.3 4288.4 4315.7 13467.5 14001.1 14032.6 14333.1 14428.8 10479.4 13993.1 14088.4 14206.8 14238.6 10261.3 10326.9 10373.1 10396.3 10507.4 6435.6 6523.6 6537.8 6577.5 6650.1 12511.2 12564.7 12879.7 12883.4 12887.7 12385.0 12696.5 12727.6 12744.4 12865.6 8345.2 8811.8 8867.9 8991.2 9096.0 8712.5 8726.1 8971.3 10040.7 10151.4 7221.6 8450.9 8505.4 8566.5 8571.4 8299.8 8409.8 8499.6 8579.9 8673.7 7608.2 7631.5 7690.0 7719.2 7735.5 4623.4 5815.9 5960.7 6043.5 6081.4 5892.1 5987.4 6063.7 6146.8 6242.9 05:14:33 INFO - PID 1929 | 05:14:33 INFO - PID 1929 | [#4] prototype.html Cycles:180 Average:5177.77 Median:5620.27 stddev:1561.91 (27.8%) stddev-sans-first:1524.32 05:14:33 INFO - PID 1929 | Values: 385.9 388.1 392.9 397.3 398.7 5055.7 5979.6 5989.9 6062.5 6133.1 5637.2 5786.8 5804.4 5874.3 5970.5 4653.2 4679.4 4718.2 4718.6 4720.9 4092.4 4679.8 4711.3 4713.5 4781.8 4462.5 4633.1 4667.7 4669.9 4684.6 4192.3 4282.3 4647.0 4648.6 4669.2 3085.4 3928.2 4104.8 4161.4 4171.3 4527.8 4808.8 4986.5 5847.4 5939.8 4515.6 4732.9 5387.4 5388.4 5415.7 4690.7 5729.6 6350.7 6404.9 6504.8 3970.8 4378.3 4499.8 4595.3 4664.5 5616.3 6417.6 6701.2 6766.6 6882.0 4663.3 4721.9 4753.2 4796.0 4822.1 4567.0 4583.9 4863.7 4872.2 4898.9 5158.0 5385.5 5403.9 5421.1 5498.4 4981.8 5626.4 6502.0 6503.6 6506.5 1355.3 1658.7 1660.4 1667.3 1681.9 1016.5 1101.6 1127.5 1143.3 1150.9 5737.8 5962.2 6030.7 6043.4 6064.5 5561.9 6572.5 6578.1 6591.1 6603.7 5663.3 5803.8 5964.0 5987.8 6062.6 3047.5 3298.0 3441.3 3470.4 3507.9 5666.1 5714.8 6424.9 6590.2 6752.9 6215.7 6487.1 6858.2 6862.6 6895.5 5833.8 6421.2 6618.7 6813.3 6837.4 5447.8 5624.2 6584.4 6613.3 6697.3 5596.9 5773.2 6613.6 6652.6 6666.4 6626.3 6727.7 6808.7 6925.4 6971.5 3557.7 4251.8 4344.5 4397.5 4424.7 5489.1 6339.7 6685.8 6686.1 6751.0 6332.9 6414.4 6430.5 6457.5 6463.4 6057.5 6172.4 6442.8 6443.7 6489.4 5199.6 5221.6 5436.3 6243.5 6305.5 4818.9 5037.7 6325.7 6410.2 6494.6 5994.6 6199.0 6258.3 6271.0 6284.3 05:14:33 INFO - PID 1929 | 05:14:33 INFO - PID 1929 | [#5] yui.html Cycles:180 Average:18840.26 Median:25414.99 stddev:26112.47 (102.7%) stddev-sans-first:26150.10 05:14:33 INFO - PID 1929 | Values: 676.6 716.4 732.0 733.3 738.4 17294.6 17709.8 17936.1 18258.2 18313.9 16983.6 17317.0 17551.3 17579.4 17581.3 919.6 952.4 953.9 954.1 957.2 968.9 1001.2 1003.8 1006.8 1011.7 1055.5 1056.1 1057.7 1058.0 1069.7 1045.8 1047.9 1050.1 1051.4 1063.1 785.9 787.0 797.1 800.9 800.9 15905.4 16167.6 16367.4 16606.3 16890.9 14380.6 14562.9 14586.6 14737.7 14746.6 11542.8 11689.8 11762.9 11962.0 12213.5 2977.3 3099.5 3132.2 3143.6 3166.9 104719.0 107942.5 112055.0 112464.4 114280.4 11372.7 11445.2 11461.2 11642.6 11903.7 22485.0 24323.9 25361.6 25365.2 25464.7 6891.8 7148.7 7746.6 7754.3 7812.0 4040.5 4136.0 4138.9 4141.8 4144.6 4114.9 4142.3 4217.1 4245.0 4255.7 744.5 759.6 778.7 793.3 807.2 479.5 547.8 550.4 573.9 616.5 9660.6 9993.0 9996.2 10051.9 10197.8 531.6 564.1 575.4 598.3 601.5 4652.0 4737.5 4810.6 4819.3 4905.2 70681.8 73692.0 76121.0 95260.6 99537.0 53720.0 62233.0 86987.6 87964.7 89268.0 65605.4 69718.0 69961.0 69981.7 70611.0 10238.9 10655.8 10797.5 10899.3 10921.3 35068.0 35852.0 36751.6 36758.6 37276.6 41398.8 49676.8 50155.0 51047.5 51347.0 13561.8 13621.5 13733.5 13782.9 13985.5 11947.4 14205.9 14584.4 14702.5 14828.3 12736.0 13500.2 13564.3 13675.2 14027.9 13245.0 13482.2 13636.0 13886.9 13944.6 12879.9 13007.1 13010.7 13140.7 13534.1 9600.4 9825.0 9987.5 10022.3 10918.3 8741.0 10180.4 10506.8 11226.5 11241.6 05:14:33 INFO - PID 1929 | -------- Summary: end -------- 05:14:33 INFO - PID 1929 | 05:14:33 INFO - TEST-INFO | 1929: exit 0 05:14:33 INFO - TEST-OK | dromaeo_css | took 1122160ms 05:14:33 INFO - TEST-START | kraken 05:14:33 INFO - operating with platform_type : mac_ 05:14:33 INFO - Initialising browser for kraken test... 05:14:33 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpomS_Qt/profile http://localhost:49499/getInfo.html) 05:14:36 INFO - PID 1932 | __metrics Screen width/height:1600/1200 05:14:36 INFO - PID 1932 | colorDepth:24 05:14:36 INFO - PID 1932 | Browser inner width/height: 1024/689 05:14:36 INFO - PID 1932 | __metrics 05:14:36 ERROR - PID 1932 | 1490789676294 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 05:14:36 INFO - PID 1932 | 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" 05:14:36 INFO - PID 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:14:37 INFO - TEST-INFO | 1932: exit 0 05:14:37 INFO - Browser initialized. 05:14:37 INFO - Running cycle 1/1 for kraken test... 05:14:37 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpomS_Qt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:14:49 INFO - PID 1934 | RSS: Main: 234205184 05:14:49 INFO - PID 1934 | 05:14:52 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49499/tests/kraken/driver.html?testName=audio-beat-detection) 05:14:53 INFO - PID 1934 | RSS: Main: 286666752 05:14:53 INFO - PID 1934 | 05:14:56 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49499/tests/kraken/driver.html?testName=audio-dft) 05:14:56 INFO - PID 1934 | RSS: Main: 288276480 05:14:56 INFO - PID 1934 | 05:15:00 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49499/tests/kraken/driver.html?testName=audio-fft) 05:15:01 INFO - PID 1934 | RSS: Main: 380612608 05:15:01 INFO - PID 1934 | 05:15:03 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49499/tests/kraken/driver.html?testName=audio-oscillator) 05:15:03 INFO - PID 1934 | RSS: Main: 283054080 05:15:03 INFO - PID 1934 | 05:15:05 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49499/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:15:06 INFO - PID 1934 | RSS: Main: 286699520 05:15:06 INFO - PID 1934 | 05:15:09 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49499/tests/kraken/driver.html?testName=imaging-darkroom) 05:15:10 INFO - PID 1934 | RSS: Main: 328597504 05:15:10 INFO - PID 1934 | 05:15:13 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49499/tests/kraken/driver.html?testName=imaging-desaturate) 05:15:14 INFO - PID 1934 | RSS: Main: 336285696 05:15:14 INFO - PID 1934 | 05:15:17 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49499/tests/kraken/driver.html?testName=json-parse-financial) 05:15:17 INFO - PID 1934 | RSS: Main: 339632128 05:15:17 INFO - PID 1934 | 05:15:19 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49499/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:15:20 INFO - PID 1934 | RSS: Main: 305496064 05:15:20 INFO - PID 1934 | 05:15:22 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49499/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:15:22 INFO - PID 1934 | RSS: Main: 306552832 05:15:22 INFO - PID 1934 | 05:15:25 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49499/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:15:25 INFO - PID 1934 | RSS: Main: 308961280 05:15:25 INFO - PID 1934 | 05:15:28 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49499/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:15:29 INFO - PID 1934 | RSS: Main: 328204288 05:15:29 INFO - PID 1934 | 05:15:33 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49499/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:15:33 INFO - PID 1934 | RSS: Main: 322371584 05:15:33 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | Cycle 1(1): loaded http://localhost:49499/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49499/tests/kraken/driver.html?testName=ai-astar) 05:15:35 INFO - PID 1934 | RSS: Main: 385503232 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | __start_tp_report 05:15:35 INFO - PID 1934 | _x_x_mozilla_page_load 05:15:35 INFO - PID 1934 | _x_x_mozilla_page_load_details 05:15:35 INFO - PID 1934 | |i|pagename|runs| 05:15:35 INFO - PID 1934 | |0;ai-astar;111;124;124;125;121;122;125;122;122;122 05:15:35 INFO - PID 1934 | |1;audio-beat-detection;164;135;153;200;120;121;121;119;149;120 05:15:35 INFO - PID 1934 | |2;audio-dft;212;211;216;210;214;209;214;235;229;212 05:15:35 INFO - PID 1934 | |3;audio-fft;87;65;65;99;67;66;66;61;83;65 05:15:35 INFO - PID 1934 | |4;audio-oscillator;99;75;95;75;75;75;91;74;74;91 05:15:35 INFO - PID 1934 | |5;imaging-gaussian-blur;94;93;93;94;92;92;93;92;92;94 05:15:35 INFO - PID 1934 | |6;imaging-darkroom;103;102;101;101;101;100;103;102;101;103 05:15:35 INFO - PID 1934 | |7;imaging-desaturate;103;101;101;102;103;103;103;101;102;102 05:15:35 INFO - PID 1934 | |8;json-parse-financial;53;54;94;104;105;65;67;66;67;66 05:15:35 INFO - PID 1934 | |9;json-stringify-tinderbox;61;74;80;71;67;64;66;67;67;68 05:15:35 INFO - PID 1934 | |10;stanford-crypto-aes;90;92;93;92;91;91;92;92;92;90 05:15:35 INFO - PID 1934 | |11;stanford-crypto-ccm;145;156;155;156;170;152;161;152;190;172 05:15:35 INFO - PID 1934 | |12;stanford-crypto-pbkdf2;208;201;204;200;199;211;195;201;241;192 05:15:35 INFO - PID 1934 | |13;stanford-crypto-sha256-iterative;74;76;74;73;74;75;75;74;73;74 05:15:35 INFO - PID 1934 | __end_tp_report 05:15:35 INFO - PID 1934 | __start_cc_report 05:15:35 INFO - PID 1934 | _x_x_mozilla_cycle_collect,2183 05:15:35 INFO - PID 1934 | __end_cc_report 05:15:35 INFO - PID 1934 | __startTimestamp1490789735911__endTimestamp 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | ------- Summary: start ------- 05:15:35 INFO - PID 1934 | Number of tests: 14 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#0] ai-astar Cycles:10 Average:121.80 Median:123.00 stddev:4.05 (3.3%) stddev-sans-first:1.50 05:15:35 INFO - PID 1934 | Values: 111.0 124.0 124.0 125.0 121.0 122.0 125.0 122.0 122.0 122.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#1] audio-beat-detection Cycles:10 Average:140.20 Median:142.00 stddev:26.69 (18.8%) stddev-sans-first:26.89 05:15:35 INFO - PID 1934 | Values: 164.0 135.0 153.0 200.0 120.0 121.0 121.0 119.0 149.0 120.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#2] audio-dft Cycles:10 Average:216.20 Median:214.00 stddev:8.69 (4.1%) stddev-sans-first:9.08 05:15:35 INFO - PID 1934 | Values: 212.0 211.0 216.0 210.0 214.0 209.0 214.0 235.0 229.0 212.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#3] audio-fft Cycles:10 Average:72.40 Median:66.50 stddev:12.64 (19.0%) stddev-sans-first:12.26 05:15:35 INFO - PID 1934 | Values: 87.0 65.0 65.0 99.0 67.0 66.0 66.0 61.0 83.0 65.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:83.00 stddev:10.23 (12.3%) stddev-sans-first:8.92 05:15:35 INFO - PID 1934 | Values: 99.0 75.0 95.0 75.0 75.0 75.0 91.0 74.0 74.0 91.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:93.00 stddev:0.88 (0.9%) stddev-sans-first:0.83 05:15:35 INFO - PID 1934 | Values: 94.0 93.0 93.0 94.0 92.0 92.0 93.0 92.0 92.0 94.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:102.00 stddev:1.06 (1.0%) stddev-sans-first:1.01 05:15:35 INFO - PID 1934 | Values: 103.0 102.0 101.0 101.0 101.0 100.0 103.0 102.0 101.0 103.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#7] imaging-desaturate Cycles:10 Average:102.10 Median:102.50 stddev:0.88 (0.9%) stddev-sans-first:0.87 05:15:35 INFO - PID 1934 | Values: 103.0 101.0 101.0 102.0 103.0 103.0 103.0 101.0 102.0 102.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#8] json-parse-financial Cycles:10 Average:74.10 Median:66.00 stddev:19.46 (29.5%) stddev-sans-first:19.09 05:15:35 INFO - PID 1934 | Values: 53.0 54.0 94.0 104.0 105.0 65.0 67.0 66.0 67.0 66.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:67.50 stddev:5.36 (7.9%) stddev-sans-first:4.95 05:15:35 INFO - PID 1934 | Values: 61.0 74.0 80.0 71.0 67.0 64.0 66.0 67.0 67.0 68.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:92.00 stddev:0.97 (1.1%) stddev-sans-first:0.87 05:15:35 INFO - PID 1934 | Values: 90.0 92.0 93.0 92.0 91.0 91.0 92.0 92.0 92.0 90.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:160.90 Median:158.50 stddev:13.11 (8.3%) stddev-sans-first:12.58 05:15:35 INFO - PID 1934 | Values: 145.0 156.0 155.0 156.0 170.0 152.0 161.0 152.0 190.0 172.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.20 Median:202.50 stddev:13.76 (6.8%) stddev-sans-first:14.56 05:15:35 INFO - PID 1934 | Values: 208.0 201.0 204.0 200.0 199.0 211.0 195.0 201.0 241.0 192.0 05:15:35 INFO - PID 1934 | 05:15:35 INFO - PID 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.20 Median:74.00 stddev:0.92 (1.2%) stddev-sans-first:0.97 05:15:35 INFO - PID 1934 | Values: 74.0 76.0 74.0 73.0 74.0 75.0 75.0 74.0 73.0 74.0 05:15:35 INFO - PID 1934 | -------- Summary: end -------- 05:15:35 INFO - PID 1934 | 05:15:36 INFO - TEST-INFO | 1934: exit 0 05:15:36 INFO - TEST-OK | kraken | took 62958ms 05:15:36 INFO - SUITE-END | took 1185s 05:15:37 INFO - Completed test suite (00:19:45) 05:15:37 INFO - javascript benchmark 05:15:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7620.349379688504, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2910.825350478971, 3403.285310084882, 3421.7091547218483, 3471.30573885223, 3478.46947343169, 5864.384239654828, 6080.787172448965, 6127.0, 6143.078538219269, 6152.477039451652, 6114.510272098694, 6139.0, 6159.568830181889, 6164.660943648098, 6189.2572891253, 4948.2824990376375, 5328.973355133219, 5505.724713764296, 5512.80705175319, 5532.5573954083575, 5581.023320918823, 5582.190582365554, 5640.238567793357, 5656.745446454919, 5693.544516438675, 5371.785128594832, 5419.268398766156, 5427.810026649048, 5441.102218134003, 5448.37343705475, 5463.48096930791, 5466.070767969455, 5479.671219726829, 5533.889322213573, 5574.944250557483, 4557.977210113928, 5465.890682186374, 5510.952918945388, 5528.087865502187, 5550.666959982414, 6070.757169713206, 6094.329623741385, 6115.87768244637, 6127.172831667713, 6153.661548614825, 6187.504999600022, 6503.0, 6513.544051916368, 6526.510511711641, 6538.8365290868105, 6354.301026887085, 6397.680115994228, 6442.098106265126, 6443.548951573392, 6445.452136568398, 6122.051082082285, 6122.550631082941, 6140.350877192993, 6151.108089327023, 6163.876722465615, 6668.199816022108, 6752.93247067523, 6756.493263005196, 6770.187577490733, 6770.898436523456, 6272.341404152589, 6309.936900631027, 6317.368263173737, 6409.327020662763, 6437.0988061671405, 6393.968030159819, 6403.903941440882, 6407.615543067431, 6418.679066046679, 6438.034294855715, 6337.17616709834, 6347.682615869188, 6389.424951754364, 6432.0351947207355, 6436.645984470899, 6471.061696054046, 6505.934940650627, 6508.186476690414, 6510.0, 6514.990176522647, 4378.693491455631, 5091.567216786615, 5143.8456846294675, 5185.144451165591, 5247.2653828464045, 4569.9314510282375, 4594.379758732528, 4605.65457590682, 4655.4180727409075, 4704.200285951397, 6188.133661287423, 6243.0, 6245.5628106033055, 6250.499960003189, 6255.999040153553, 6276.246850377984, 6298.086777417248, 6615.272320044791, 6616.8345791355605, 6624.503162262753, 6101.307919489667, 6217.129601855744, 6227.0, 6233.812985610439, 6262.624242545462, 5794.304683438015, 5802.767889284381, 5805.7387417566315, 5810.535157187415, 5825.533957283407, 6782.491313151435, 6793.2867048961125, 6814.386705196556, 6827.93172068273, 6853.417459515997, 6319.462845657987, 6379.915414379474, 6386.840328991812, 6472.223333200046, 6590.8352291193105, 6782.864342713019, 6806.727730890709, 6823.38589526945, 6834.111565496453, 6841.726330946706, 6386.616802991835, 6475.935240647534, 6476.643784591704, 6479.287278399278, 6507.60954342722, 6284.8428789278805, 6354.23749150105, 6455.160829092188, 6578.440832529097, 6616.603003819786, 6271.592346497463, 6357.396047375492, 6358.841028974312, 6361.713722882574, 6361.904571431525, 6185.288691800501, 6197.163382943245, 6240.438360547572, 6331.018692102732, 6352.142460767737, 4884.389451318585, 6174.320824709189, 6280.466160376236, 6286.025666021774, 6298.622082674871, 5251.238570407193, 6184.474319682878, 6395.456386207225, 6398.200224971879, 6423.325550817097, 6184.6598437086395, 6200.689965501797, 6214.067889816383, 6243.313235543996, 6261.123442718114], "value": 5970.266835629921, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.5753599216442, 767.4627760567603, 808.894843670323, 1134.1267224237338, 1154.2266681323513, 27632.171034868938, 28173.295667608294, 28240.717592824065, 36609.0, 37062.62937370625, 38757.22485550287, 39026.80486597563, 39158.4126238106, 39174.0, 39185.21629567407, 8343.499390036586, 8523.06246312905, 8536.615852286632, 8541.786455338635, 8547.914520854805, 6036.3661815509395, 6194.318624951275, 8217.7123800667, 8385.203405676451, 8415.95792021039, 8451.746447606582, 8503.744887653349, 8506.82986340276, 8508.872366914502, 8535.231829135377, 8421.989361276655, 8458.66165353385, 8487.957560212159, 8494.108118647577, 8518.190771876692, 8431.241188293083, 8498.277646400064, 8503.872441913376, 8514.659413623447, 8617.741467755977, 38092.61907380918, 38169.42745858814, 38216.85349439521, 38302.04244893872, 38348.616513834786, 3050.633923929143, 3074.723274905247, 3077.79994300372, 3089.4439000979814, 3098.2719061020643, 27504.76228569719, 27511.037113701124, 27695.584566231522, 27837.443251135068, 27941.860290698416, 2784.3456787654886, 2791.3719413132085, 2810.016494227025, 2821.9294517637018, 2836.1349788314556, 335579.0, 335893.0, 335936.0, 336608.0, 351061.48938510544, 35343.82328088343, 50174.498255017716, 50263.24605130926, 50381.74809125931, 50382.49617503851, 53191.0, 53212.0, 53257.0, 53314.7334263334, 53315.33423328896, 14317.284135793168, 14630.19533925623, 14647.974641774974, 14651.194184319966, 14654.926725366306, 11845.23006004607, 11896.821547676791, 11921.165518413802, 11922.701932451759, 11925.986291165174, 10736.89263107359, 10826.945865270623, 12235.204711693712, 12354.876451235552, 12455.93772031134, 5500.724963751876, 5555.2778138841695, 5562.47156520138, 5595.608307418523, 5614.862400903209, 954.9140577348072, 970.4080510888352, 975.1077763846143, 979.304693667504, 988.5255077562811, 27797.722022779515, 28420.0, 28963.56554651682, 28976.71023289782, 29017.854910725735, 907.6732376344511, 1017.7913527726946, 1025.958961641526, 1057.931234469757, 1074.065562960214, 11483.598074067368, 12140.15018948665, 12152.57465988686, 12204.0, 12228.266304021787, 368620.0, 369545.0, 369756.0, 370190.0, 370355.0, 51672.74163629233, 51755.741221293654, 51916.48083519192, 51936.74031629818, 53997.0, 1454.9054311469931, 1457.3951809999155, 1507.5929499035199, 1509.939602415913, 1512.4026009726097, 21946.561068778854, 23303.533929321657, 23335.2999410018, 23349.649755254024, 23451.94466249057, 39154.216915662095, 39251.214975700896, 39324.0, 39330.41004384994, 39564.60435395609, 382110.0, 383717.0, 384496.0, 384767.0, 384817.0, 32096.35807283888, 32422.35155296928, 32671.282525557457, 32725.836370818946, 32895.67104329022, 31179.376412472076, 31853.681463185072, 36723.26553468969, 40201.39697904475, 40496.595034049285, 26809.061682841006, 27342.726572734813, 27531.31171720723, 27807.165785026482, 28597.571036434885, 26929.730702692723, 27390.5891411625, 28100.859495702392, 28173.71826281711, 28563.143105706862, 31868.043958680348, 32234.516482253253, 32639.34721305513, 33176.0, 33974.15064623404, 28336.0, 29611.111666650035, 29926.0, 29937.0, 30012.549811753277, 20209.0, 22993.941332906732, 23038.42403939915, 23262.883685581462, 23518.0], "value": 17140.126745930316, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.6306955275029, 416.46068341497755, 418.96019878111576, 421.8460262004368, 439.0494579235954, 18461.7230741539, 20014.69977950332, 20449.0, 21168.364949051534, 21940.561188776213, 22336.55326893461, 22809.657855132144, 22885.01368352564, 22927.312180634566, 23329.650055249185, 6089.665068421235, 6337.144485494468, 6363.936360636404, 6365.1407060046995, 6379.649119298437, 5976.223090998186, 6226.0, 6300.0, 6317.399847014526, 6320.683965801692, 5926.4073592640825, 6188.071789231606, 6210.378962103799, 6222.5644204905675, 6245.718942647569, 5873.089671100944, 6133.11069894865, 6172.969135154318, 6179.258488981351, 6184.350643182446, 5418.431064738224, 5481.616286859921, 5491.093969495028, 5496.780128794843, 5498.972505137449, 26843.32891677704, 26876.59685104725, 27107.45785084307, 27420.17739467819, 28647.997320093906, 1944.3686260886798, 2022.5979168630918, 2048.2114385961513, 2100.24391219161, 2131.4564785979633, 15971.361145554163, 16234.512964610962, 16433.671326573403, 16555.83444165555, 16826.990380577205, 1325.8995034121751, 1612.5404259785905, 1630.0790053619667, 1633.5834362237554, 1634.133909028217, 152887.0, 163822.3617763831, 164414.3558564411, 166535.0, 168640.15679921643, 11981.820272695917, 12104.334261615519, 12200.023998080133, 12221.205621634743, 12406.0, 16217.918910405608, 16224.269907854175, 16224.675506489804, 16231.431899883448, 16255.918720406322, 7406.259374062658, 8652.480851148952, 8849.778755531162, 8869.822603547893, 8905.866412003825, 7027.402670772939, 8269.503829770232, 8411.285040771603, 8595.484270943763, 8614.353923455767, 6342.682865856688, 6918.238993710688, 7260.2739726026975, 7705.537667739842, 7748.22517748232, 1813.6150961485869, 2043.9897800510903, 2082.5106100066473, 2087.7912208779, 2219.9778002220096, 808.3088958940021, 829.0383155539545, 884.787650963764, 901.932355073359, 925.3522534226069, 19061.9416319236, 21986.89006554989, 24640.507189856104, 25468.49063018766, 25700.742992569834, 930.0048947625975, 949.6106596295485, 951.8286708392556, 954.6467806911487, 978.9070038346346, 9706.514674266258, 10129.696109116738, 10325.535350909227, 10590.947045264724, 11196.160287978435, 408905.0, 409690.0, 410662.0, 592095.0, 723089.0, 116518.0, 131276.3436182813, 133268.66731332563, 149887.50112498953, 161920.0, 1163.2845799833042, 1204.500132445043, 1207.4506099724604, 1230.0897335971317, 1252.2799390350585, 20740.792592073885, 21396.893015534824, 21514.784852151904, 21547.78452215458, 21566.412030556432, 48003.5199647999, 51641.0, 57366.13950790825, 57426.7128664354, 61569.07646385398, 82459.46472943806, 84111.57944210485, 85062.0, 90200.0, 91174.0, 12574.308413037004, 12975.610731678065, 13154.276514791396, 13236.823162440753, 13492.257925814174, 13714.0, 13756.449742010207, 13900.235487048309, 14330.426782928984, 14342.856571434151, 11638.185327027028, 11686.590969316034, 11885.524579017088, 12024.75950481003, 12072.939635301767, 11062.612808551663, 11272.887271127513, 11377.862213778555, 11420.200585958903, 11439.885601144215, 11455.942720286344, 12033.278003319829, 12041.277523348626, 12294.20087694297, 12318.384080795746, 12318.384080796104, 12831.743365132832, 12976.416061277454, 12990.0, 13335.733285334432, 11640.592579259684, 11694.064474842162, 11912.880871191524, 12012.819807702717, 12057.698557536132], "value": 10515.470657068514, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1186.9525218991241, 1252.4489224741112, 1422.7083447893183, 1440.3662388549037, 1445.4724025730604, 9186.770330741727, 10702.946485267572, 10926.399048052353, 11007.889921100797, 11019.944900275508, 10653.84019239712, 10797.5680972761, 10807.783844323129, 10821.13430925526, 10977.451127443615, 2434.4887573609562, 2481.85108893467, 2486.080150344375, 2486.627005949104, 2487.440325926661, 2074.408793493855, 2163.3401812447196, 2383.761623837611, 2463.174836429789, 2485.3662316109367, 2422.4549476367765, 2444.352246654629, 2447.449323902126, 2450.4608986023045, 2452.730199678034, 2401.1835975768236, 2419.8669073200967, 2441.5727247731666, 2444.181199298237, 2467.592847180213, 2053.414776788608, 2396.6405039244078, 2415.5048215115858, 2444.5477586646525, 2453.9754602453927, 9026.0, 10805.189610779144, 10815.297005694683, 10916.181286403473, 10961.177911656658, 6147.323794382659, 6397.200349956255, 6400.0, 6411.134496842913, 6419.711112999927, 3798.1264309209037, 3857.768533887948, 4070.8778736637946, 4118.0, 4118.361653943643, 1191.7795207886568, 1210.3222195570509, 1525.1078119300223, 1585.5560443075958, 1638.86069684077, 12928.806067908989, 14578.489752858599, 15203.163825989566, 15224.086554806747, 15236.162011089385, 5355.437679043722, 5412.675239485643, 5627.887442251132, 5873.442023007808, 5911.201987731602, 11387.259828111146, 11449.54201831934, 11474.713132171595, 11485.368304743319, 11501.309921404743, 6246.625202487865, 6248.125262463259, 6250.9062364064575, 6252.906206406908, 6270.2475702915945, 4922.827701030446, 5733.913991290134, 5826.096954971986, 5885.588008839341, 5983.760649574056, 3232.660570640096, 3773.52830896138, 3880.7089468289428, 4236.322188449833, 4274.6962176535835, 1237.7153254751277, 1250.4373032135575, 1257.8050402187675, 1275.043717212091, 1275.0500876846809, 697.8499622581068, 743.1824992508198, 749.1609397474863, 774.7598244544209, 779.0729032451364, 5990.400959903975, 6127.0, 6665.833354166088, 6728.562643428259, 6904.620245886525, 825.5005721538503, 849.3757088539918, 876.838592868582, 891.9821603568022, 905.0406569036842, 4003.319435695939, 4160.794778767258, 4212.347086201644, 4288.442502474721, 4315.654747620183, 13467.543916445391, 14001.089929154572, 14032.649183770283, 14333.140011599337, 14428.783568246276, 10479.371237725762, 13993.090449120775, 14088.366023528966, 14206.786898196535, 14238.572842814732, 10261.28171028021, 10326.896731032744, 10373.118284945864, 10396.272260941661, 10507.369557826401, 6435.613863168225, 6523.575967562093, 6537.771178008747, 6577.50668699859, 6650.135482387259, 12511.186772859735, 12564.685882853002, 12879.678008049874, 12883.35583220854, 12887.742245155232, 12385.0, 12696.492140314653, 12727.554535591209, 12744.362781861055, 12865.614031579067, 8345.165483451607, 8811.779705507157, 8867.866981995405, 8991.235744961752, 9096.044915284065, 8712.520811355436, 8726.083761204982, 8971.28229741606, 10040.748981275527, 10151.390916544735, 7221.638918053971, 8450.915490845013, 8505.447145935494, 8566.52884091381, 8571.4000019998, 8299.83400332002, 8409.831803363775, 8499.57502124879, 8579.871301930601, 8673.653053878026, 7608.2391760823475, 7631.54210747357, 7690.0, 7719.228077192236, 7735.535867847947, 4623.42207224097, 5815.883682326245, 5960.731767070405, 6043.486303664062, 6081.361457046946, 5892.1161825726485, 5987.43119403656, 6063.727132279147, 6146.846328841814, 6242.937570624235], "value": 5154.360075125418, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.8533757172275, 388.1305874840357, 392.9332013557695, 397.25712916845504, 398.6770715720266, 5055.721935293557, 5979.641221526712, 5989.910151347728, 6062.4543791058795, 6133.080037994292, 5637.238972738679, 5786.755847492788, 5804.361520232771, 5874.3244526879425, 5970.492508136814, 4653.16243076247, 4679.391679081715, 4718.221493453576, 4718.598919091882, 4720.881977950561, 4092.386142078697, 4679.766011699418, 4711.269753188244, 4713.481517033124, 4781.848168324251, 4462.531434199411, 4633.142868569325, 4667.7199368037855, 4669.9066018679605, 4684.625229981611, 4192.287311157111, 4282.272013757669, 4646.97766491373, 4648.628109751212, 4669.229577119771, 3085.4291955988215, 3928.204206722386, 4104.835806567734, 4161.417401563784, 4171.311733563958, 4527.818887244506, 4808.75956202192, 4986.5013498650205, 5847.356790753014, 5939.792107276225, 4515.638748900113, 4732.858014259543, 5387.353517577876, 5388.43421440747, 5415.702136382497, 4690.671652984292, 5729.598928075036, 6350.682465876687, 6404.903926441107, 6504.837379065514, 3970.761754294723, 4378.255696531597, 4499.842505512292, 4595.253803554628, 4664.486906440289, 5616.297962754656, 6417.582857114319, 6701.195856497252, 6766.627835469048, 6882.0, 4663.253879379351, 4721.881952951135, 4753.168195565831, 4796.040791841646, 4822.132016237111, 4567.0, 4583.908321833612, 4863.683860549053, 4872.244802055687, 4898.90202195954, 5157.974210128926, 5385.515303622615, 5403.945960540423, 5421.069724449742, 5498.395176530578, 4981.800727970841, 5626.409227031185, 6501.9843848407145, 6503.6097834130105, 6506.512011599149, 1355.3194039391085, 1658.7428948513, 1660.4271526323398, 1667.2664027827716, 1681.9495415137321, 1016.517154351677, 1101.5979167603766, 1127.4869934179928, 1143.3254379915888, 1150.930944143354, 5737.799177028867, 5962.195103661037, 6030.698465076729, 6043.395660434009, 6064.48451881595, 5561.916571251434, 6572.507061970371, 6578.144841170713, 6591.110200123018, 6603.70283337251, 5663.348714897757, 5803.825885223366, 5964.0, 5987.832372687284, 6062.636241825505, 3047.481928072233, 3297.9835100824343, 3441.296558203705, 3470.375332440185, 3507.8772242971886, 5666.121751128581, 5714.828555143353, 6424.903626445607, 6590.209174898947, 6752.932470675329, 6215.658138802319, 6487.086309538698, 6858.245592984767, 6862.588244705334, 6895.5173137879865, 5833.795817146464, 6421.229452465734, 6618.7352505899225, 6813.318668133147, 6837.427391699937, 5447.824566661767, 5624.240727501735, 6584.374484423972, 6613.338666133348, 6697.330266973264, 5596.888062238651, 5773.16289138081, 6613.636250006296, 6652.634105124071, 6666.400023997863, 6626.337366263335, 6727.663616819237, 6808.65956702153, 6925.411340036153, 6971.457691135458, 3557.697595704394, 4251.808668609981, 4344.543822898677, 4397.516273209881, 4424.690271680952, 5489.066858633962, 6339.746410143543, 6685.76599819004, 6686.130802995774, 6751.0, 6332.873342533216, 6414.390632889868, 6430.549861509649, 6457.483401327976, 6463.418292353711, 6057.515398768176, 6172.358308597769, 6442.8389290268115, 6443.743470023488, 6489.415952564292, 5199.558037566698, 5221.634485586124, 5436.3476382834315, 6243.500519958302, 6305.464035557029, 4818.855434336975, 5037.722925239147, 6325.746970121328, 6410.1987251593555, 6494.6103233806125, 5994.6403215807195, 6199.0, 6258.311585725475, 6270.968645156745, 6284.308726040041], "value": 4705.819141450293, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.597424532403, 716.3767522255636, 731.9707211711531, 733.2703959560237, 738.3613174603969, 17294.567635809097, 17709.82290177098, 17936.103194840238, 18258.178381972793, 18313.901165930038, 16983.57541061474, 17317.0, 17551.297948082094, 17579.38472153475, 17581.29674813006, 919.6137622198676, 952.3666761603525, 953.909378609031, 954.1079091049891, 957.1768279279814, 968.8837339519267, 1001.1940387987667, 1003.7841863999239, 1006.7936073105033, 1011.716719318592, 1055.5091882274742, 1056.10758908722, 1057.7249915022073, 1057.9682609521726, 1069.7432616172102, 1045.764702941832, 1047.9056884880397, 1050.0864248104122, 1051.363924825481, 1063.0538820449806, 785.8899754034383, 787.0083694544915, 797.0634504457239, 800.8918795962529, 800.9439339246255, 15905.443309484228, 16167.595810104724, 16367.42714005016, 16606.335746570123, 16890.915545422195, 14380.568582942424, 14562.854371456362, 14586.635334116625, 14737.705245895022, 14746.631334216623, 11542.769144617061, 11689.824652630217, 11762.94118529402, 11962.0, 12213.511459541607, 2977.330100727341, 3099.4575949208797, 3132.1856317357424, 3143.6070491188602, 3166.8891588794513, 104718.95281047169, 107942.46028769885, 112055.0, 112464.4376778119, 114280.42859785732, 11372.715682108013, 11445.198836081394, 11461.197716159955, 11642.592509262135, 11903.69059403465, 22484.988175531813, 24323.878380608337, 25361.61957570638, 25365.239042828376, 25464.745352546237, 6891.827704307432, 7148.678309476086, 7746.57393843344, 7754.302112809762, 7811.984442022501, 4040.454538637305, 4136.0, 4138.896527586834, 4141.751494910315, 4144.647704945113, 4114.897127571775, 4142.337226043818, 4217.093324935154, 4244.978775106167, 4255.680823938217, 744.5458270455018, 759.6277823866266, 778.7352300217954, 793.2662287384146, 807.184743409161, 479.4701854450782, 547.8301726464808, 550.402812947948, 573.850798792317, 616.51295476573, 9660.565274562661, 9992.950035249778, 9996.200303975664, 10051.949740251252, 10197.847032294521, 531.6225479909231, 564.1284215886466, 575.3670961941914, 598.308953159102, 601.4649542449965, 4651.99605864264, 4737.502562230985, 4810.577878532129, 4819.2530157825595, 4905.23968784839, 70681.8193181308, 73692.0, 76121.0, 95260.57109143368, 99537.0, 53720.0, 62233.0, 86987.56506217555, 87964.68052979211, 89268.0, 65605.42353899972, 69717.95423068658, 69961.0, 69981.65009174819, 70611.0, 10238.897611023795, 10655.786884262427, 10797.514111864828, 10899.29154604948, 10921.344719316867, 35068.0, 35852.0, 36751.632483675894, 36758.63241367552, 37276.62723372732, 41398.79300603598, 49676.75161624169, 50155.0, 51047.489525104276, 51347.0, 13561.79657305161, 13621.523246686318, 13733.519326823513, 13782.862171378158, 13985.510507132201, 11947.426567121238, 14205.857941420454, 14584.416623334948, 14702.485413010494, 14828.258587070819, 12736.0, 13500.189988600716, 13564.253966031569, 13675.247861367323, 14027.859721403063, 13245.0, 13482.19106853592, 13636.0, 13886.861131388558, 13944.58166255014, 12879.88999382228, 13007.089503734647, 13010.739785204432, 13140.671483212996, 13534.052616316763, 9600.381156572052, 9825.0, 9987.450690212108, 10022.3485473444, 10918.29031113007, 8740.984360395654, 10180.42455764864, 10506.789864202827, 11226.494807733836, 11241.55033798639], "value": 7501.8947190120925, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1604.1000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 124.0, 124.0, 125.0, 121.0, 122.0, 125.0, 122.0, 122.0, 122.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 135.0, 153.0, 200.0, 120.0, 121.0, 121.0, 119.0, 149.0, 120.0], "value": 140.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [212.0, 211.0, 216.0, 210.0, 214.0, 209.0, 214.0, 235.0, 229.0, 212.0], "value": 216.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 65.0, 65.0, 99.0, 67.0, 66.0, 66.0, 61.0, 83.0, 65.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 75.0, 95.0, 75.0, 75.0, 75.0, 91.0, 74.0, 74.0, 91.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 94.0, 92.0, 92.0, 93.0, 92.0, 92.0, 94.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 102.0, 101.0, 101.0, 101.0, 100.0, 103.0, 102.0, 101.0, 103.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 101.0, 101.0, 102.0, 103.0, 103.0, 103.0, 101.0, 102.0, 102.0], "value": 102.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 94.0, 104.0, 105.0, 65.0, 67.0, 66.0, 67.0, 66.0], "value": 74.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 74.0, 80.0, 71.0, 67.0, 64.0, 66.0, 67.0, 67.0, 68.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 93.0, 92.0, 91.0, 91.0, 92.0, 92.0, 92.0, 90.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 156.0, 155.0, 156.0, 170.0, 152.0, 161.0, 152.0, 190.0, 172.0], "value": 160.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [208.0, 201.0, 204.0, 200.0, 199.0, 211.0, 195.0, 201.0, 241.0, 192.0], "value": 205.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 74.0, 73.0, 74.0, 75.0, 75.0, 74.0, 73.0, 74.0], "value": 74.2, "unit": "score"}]}]} 05:15:37 INFO - Return code: 0 05:15:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:15:37 ERROR - # TBPL SUCCESS # 05:15:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:15:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:15:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:15:37 INFO - 2017-03-29 05:15:37.239 screenresolution[1938:14816] starting screenresolution argv=screenresolution get 05:15:37 INFO - 2017-03-29 05:15:37.242 screenresolution[1938:14816] Display 0: 1600x1200x32@60 05:15:37 INFO - 2017-03-29 05:15:37.248 screenresolution[1939:14819] starting screenresolution argv=screenresolution list 05:15:37 INFO - Available Modes on Display 0 05:15:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:15:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:15:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:15:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:15:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:15:37 INFO - Intel Iris: 05:15:37 INFO - Chipset Model: Intel Iris 05:15:37 INFO - Type: GPU 05:15:37 INFO - Bus: Built-In 05:15:37 INFO - VRAM (Dynamic, Max): 1536 MB 05:15:37 INFO - Vendor: Intel (0x8086) 05:15:37 INFO - Device ID: 0x0a2e 05:15:37 INFO - Revision ID: 0x0009 05:15:37 INFO - Displays: 05:15:37 INFO - Display: 05:15:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:15:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:15:37 INFO - Display Serial Number: 1600x1200 60 05:15:37 INFO - Main Display: Yes 05:15:37 INFO - Mirror: Off 05:15:37 INFO - Online: Yes 05:15:37 INFO - Rotation: Supported 05:15:37 INFO - Return code: 0 05:15:37 INFO - Running post-action listener: _resource_record_post_action 05:15:37 INFO - [mozharness: 2017-03-29 12:15:37.490595Z] Finished run-tests step (success) 05:15:37 INFO - Running post-run listener: _resource_record_post_run 05:15:37 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 1200128; Write bytes: 511139840; Read time: 15; Write time: 1757 05:15:37 INFO - TinderboxPrint: CPU usage
53.5% 05:15:37 INFO - TinderboxPrint: I/O read bytes / time
1,200,128 / 15 05:15:37 INFO - TinderboxPrint: I/O write bytes / time
511,139,840 / 1,757 05:15:37 INFO - TinderboxPrint: CPU idle
2,246.2 (46.5%) 05:15:37 INFO - TinderboxPrint: CPU system
960.8 (19.9%) 05:15:37 INFO - TinderboxPrint: CPU user
1,618.6 (33.5%) 05:15:37 INFO - TinderboxPrint: Swap in / out
213,291,008 / 0 05:15:37 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 200155648; Write bytes: 194287616; Read time: 15420; Write time: 372 05:15:37 INFO - run-tests - Wall time: 1186s; CPU: 53.0%; Read bytes: 1200128; Write bytes: 297019392; Read time: 15; Write time: 1313 05:15:37 INFO - Running post-run listener: _upload_blobber_files 05:15:37 INFO - Blob upload gear active. 05:15:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:15:37 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 05:15:37 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'] 05:15:37 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 05:15:38 INFO - (blobuploader) - INFO - Open directory for files ... 05:15:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:15:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:15:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:39 INFO - (blobuploader) - INFO - Done attempting. 05:15:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:15:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:15:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:40 INFO - (blobuploader) - INFO - Done attempting. 05:15:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:15:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:15:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:41 INFO - (blobuploader) - INFO - Done attempting. 05:15:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:15:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:15:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:15:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:15:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:15:43 INFO - (blobuploader) - INFO - Done attempting. 05:15:43 INFO - (blobuploader) - INFO - Iteration through files over. 05:15:43 INFO - Return code: 0 05:15:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:15:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:15:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0ffcc388c1ce39b81a8a67606dc36fea67a1247ae992553cc6a84598a7841770c658f0b8c1267735b5c6cdf9b1b85b0362e9f9fd30cfb04c14c87c612555551", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d424046008cd29e42acd5fe4972ad82ff2a1314c34e56de70f9f461aebd64ee6d7d04493f18448166e60f8c008abd79bc7143ee823a1ce9b1bf44774bb42058e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78487a7a327aa4c8b854ca688a9201e1b3f8de878bd7791b65e3fd107adbae8c8bc03059812c82f73b0026e69182592947c983f03e986bb3a1a9f4acc3038505", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f83e3322f199bd116b9916c3ec878b511efae0c66dd1b20d7a85e5dbd23c079d2b691bae3568106cf884c1aa20975eba714c9f7cb0367e0baedb3751fc4a378d"} 05:15:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:15:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:15:43 INFO - Contents: 05:15:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d0ffcc388c1ce39b81a8a67606dc36fea67a1247ae992553cc6a84598a7841770c658f0b8c1267735b5c6cdf9b1b85b0362e9f9fd30cfb04c14c87c612555551", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d424046008cd29e42acd5fe4972ad82ff2a1314c34e56de70f9f461aebd64ee6d7d04493f18448166e60f8c008abd79bc7143ee823a1ce9b1bf44774bb42058e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78487a7a327aa4c8b854ca688a9201e1b3f8de878bd7791b65e3fd107adbae8c8bc03059812c82f73b0026e69182592947c983f03e986bb3a1a9f4acc3038505", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f83e3322f199bd116b9916c3ec878b511efae0c66dd1b20d7a85e5dbd23c079d2b691bae3568106cf884c1aa20975eba714c9f7cb0367e0baedb3751fc4a378d"} 05:15:43 INFO - Running post-run listener: copy_logs_to_upload_dir 05:15:43 INFO - Copying logs to upload dir... 05:15:43 INFO - mkdir: /builds/slave/test/build/upload/logs 05:15:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.539379 ========= master_lag: 1.33 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-03-29 05:15:44.715181) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 05:15:44.719295) ========= 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.ElO4dylnAX/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.fuTKoJvTrd/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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/d0ffcc388c1ce39b81a8a67606dc36fea67a1247ae992553cc6a84598a7841770c658f0b8c1267735b5c6cdf9b1b85b0362e9f9fd30cfb04c14c87c612555551", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d424046008cd29e42acd5fe4972ad82ff2a1314c34e56de70f9f461aebd64ee6d7d04493f18448166e60f8c008abd79bc7143ee823a1ce9b1bf44774bb42058e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78487a7a327aa4c8b854ca688a9201e1b3f8de878bd7791b65e3fd107adbae8c8bc03059812c82f73b0026e69182592947c983f03e986bb3a1a9f4acc3038505", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f83e3322f199bd116b9916c3ec878b511efae0c66dd1b20d7a85e5dbd23c079d2b691bae3568106cf884c1aa20975eba714c9f7cb0367e0baedb3751fc4a378d"} build_url:https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011128 build_url: 'https://queue.taskcluster.net/v1/task/PbsZ6iQCQ9m2R7BBzLUTNw/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/d0ffcc388c1ce39b81a8a67606dc36fea67a1247ae992553cc6a84598a7841770c658f0b8c1267735b5c6cdf9b1b85b0362e9f9fd30cfb04c14c87c612555551", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d424046008cd29e42acd5fe4972ad82ff2a1314c34e56de70f9f461aebd64ee6d7d04493f18448166e60f8c008abd79bc7143ee823a1ce9b1bf44774bb42058e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/78487a7a327aa4c8b854ca688a9201e1b3f8de878bd7791b65e3fd107adbae8c8bc03059812c82f73b0026e69182592947c983f03e986bb3a1a9f4acc3038505", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f83e3322f199bd116b9916c3ec878b511efae0c66dd1b20d7a85e5dbd23c079d2b691bae3568106cf884c1aa20975eba714c9f7cb0367e0baedb3751fc4a378d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-29 05:15:44.757680) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2017-03-29 05:15:44.758002) ========= 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.ElO4dylnAX/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.fuTKoJvTrd/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005277 ========= master_lag: 2.71 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2017-03-29 05:15:47.476787) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 05:15:47.477118) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-29 05:15:47.482679) ========= ========= Total master_lag: 4.15 =========