builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0383 starttime: 1471342223.2 results: success (0) buildid: 20160816011124 builduid: ccc9fc3713c5429ab689fd513308d0b7 revision: 9bf5770798c9fc30bc741437d9d94ab47afa54f1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:23.200254) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:23.200675) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:23.200957) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:24.059623) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:24.060012) ========= 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.u86dsvcOLg/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.1plRLoCWZz/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 --2016-08-16 03:10:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.6M=0.001s 2016-08-16 03:10:24 (15.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109348 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:24.196316) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:24.196694) ========= 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.u86dsvcOLg/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.1plRLoCWZz/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.026514 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:24.245149) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-16 03:10:24.245536) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9bf5770798c9fc30bc741437d9d94ab47afa54f1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9bf5770798c9fc30bc741437d9d94ab47afa54f1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u86dsvcOLg/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.1plRLoCWZz/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 2016-08-16 03:10:24,331 truncating revision to first 12 chars 2016-08-16 03:10:24,331 Setting DEBUG logging. 2016-08-16 03:10:24,331 attempt 1/10 2016-08-16 03:10:24,331 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9bf5770798c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 03:10:26,400 unpacking tar archive at: autoland-9bf5770798c9/testing/mozharness/ program finished with exit code 0 elapsedTime=2.334276 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-16 03:10:26.606292) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:26.606991) ========= script_repo_revision: 9bf5770798c9fc30bc741437d9d94ab47afa54f1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:26.607489) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:26.607849) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 03:10:26.627704) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-16 03:10:26.628230) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u86dsvcOLg/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.1plRLoCWZz/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:10:26 INFO - MultiFileLogger online at 20160816 03:10:26 in /builds/slave/test 03:10:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 03:10:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:10:26 INFO - {'append_to_log': False, 03:10:26 INFO - 'base_work_dir': '/builds/slave/test', 03:10:26 INFO - 'blob_upload_branch': 'Autoland', 03:10:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:10:26 INFO - 'branch': 'Autoland', 03:10:26 INFO - 'buildbot_json_path': 'buildprops.json', 03:10:26 INFO - 'config_files': ('talos/mac_config.py',), 03:10:26 INFO - 'default_actions': ('clobber', 03:10:26 INFO - 'read-buildbot-config', 03:10:26 INFO - 'download-and-extract', 03:10:26 INFO - 'populate-webroot', 03:10:26 INFO - 'create-virtualenv', 03:10:26 INFO - 'install', 03:10:26 INFO - 'run-tests'), 03:10:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:10:26 INFO - 'download_minidump_stackwalk': True, 03:10:26 INFO - 'download_symbols': 'ondemand', 03:10:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:10:26 INFO - 'tooltool.py': '/tools/tooltool.py', 03:10:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:10:26 INFO - '/tools/misc-python/virtualenv.py')}, 03:10:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:10:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:10:26 INFO - 'installer_path': 'installer.exe', 03:10:26 INFO - 'log_level': 'info', 03:10:26 INFO - 'log_name': 'talos', 03:10:26 INFO - 'log_to_console': True, 03:10:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:10:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:10:26 INFO - 'opt_config_files': (), 03:10:26 INFO - 'pip_index': False, 03:10:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:10:26 INFO - 'cmd': ('bash', 03:10:26 INFO - '-c', 03:10:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:10:26 INFO - 'enabled': True, 03:10:26 INFO - 'halt_on_failure': False, 03:10:26 INFO - 'name': 'check_screen_resolution'},), 03:10:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:10:26 INFO - 'cmd': ('bash', 03:10:26 INFO - '-c', 03:10:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:10:26 INFO - 'enabled': True, 03:10:26 INFO - 'halt_on_failure': False, 03:10:26 INFO - 'name': 'check_screen_resolution'},), 03:10:26 INFO - 'run_cmd_checks_enabled': True, 03:10:26 INFO - 'sps_profile': False, 03:10:26 INFO - 'sps_profile_interval': 0, 03:10:26 INFO - 'suite': 'dromaeojs', 03:10:26 INFO - 'system_bits': '32', 03:10:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:10:26 INFO - 'title': 't-yosemite-r7-0383', 03:10:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:10:26 INFO - 'use_talos_json': True, 03:10:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:10:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:10:26 INFO - 'work_dir': 'build'} 03:10:26 INFO - [mozharness: 2016-08-16 10:10:26.845100Z] Running clobber step. 03:10:26 INFO - Running pre-action listener: _resource_record_pre_action 03:10:26 INFO - Running main action method: clobber 03:10:26 INFO - rmtree: /builds/slave/test/build 03:10:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:10:29 INFO - Running post-action listener: _resource_record_post_action 03:10:29 INFO - [mozharness: 2016-08-16 10:10:29.026455Z] Finished clobber step (success) 03:10:29 INFO - [mozharness: 2016-08-16 10:10:29.026697Z] Running read-buildbot-config step. 03:10:29 INFO - Running pre-action listener: _resource_record_pre_action 03:10:29 INFO - Running main action method: read_buildbot_config 03:10:29 INFO - Using buildbot properties: 03:10:29 INFO - { 03:10:29 INFO - "project": "", 03:10:29 INFO - "product": "firefox", 03:10:29 INFO - "script_repo_revision": "production", 03:10:29 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:10:29 INFO - "repository": "", 03:10:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:10:29 INFO - "buildid": "20160816011124", 03:10:29 INFO - "pgo_build": "False", 03:10:29 INFO - "basedir": "/builds/slave/test", 03:10:29 INFO - "buildnumber": 91, 03:10:29 INFO - "slavename": "t-yosemite-r7-0383", 03:10:29 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:10:29 INFO - "platform": "yosemite_r7", 03:10:29 INFO - "scheduler": "tests-autoland-macosx64-talos", 03:10:29 INFO - "branch": "autoland", 03:10:29 INFO - "repo_path": "integration/autoland", 03:10:29 INFO - "revision": "9bf5770798c9fc30bc741437d9d94ab47afa54f1", 03:10:29 INFO - "stage_platform": "macosx64", 03:10:29 INFO - "builduid": "ccc9fc3713c5429ab689fd513308d0b7", 03:10:29 INFO - "slavebuilddir": "test" 03:10:29 INFO - } 03:10:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:10:29 INFO - Running post-action listener: _resource_record_post_action 03:10:29 INFO - [mozharness: 2016-08-16 10:10:29.032425Z] Finished read-buildbot-config step (success) 03:10:29 INFO - [mozharness: 2016-08-16 10:10:29.032625Z] Running download-and-extract step. 03:10:29 INFO - Running pre-action listener: _resource_record_pre_action 03:10:29 INFO - Running main action method: download_and_extract 03:10:29 INFO - mkdir: /builds/slave/test/build/tests 03:10: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')]} 03:10:29 INFO - trying https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:10:29 INFO - Downloading https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:10:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 03:10:30 INFO - Downloaded 1199 bytes. 03:10:30 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:10:30 INFO - Using the following test package requirements: 03:10:30 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:10:30 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:10:30 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:10:30 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:10:30 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:10:30 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:10:30 INFO - u'jsshell-mac.zip'], 03:10:30 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:10:30 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:10:30 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:10:30 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:10:30 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:10:30 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:10:30 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:10:30 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:10:30 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:10:30 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:10:30 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:10:30 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:10:30 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')]} 03:10:30 INFO - trying https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:10:30 INFO - Downloading https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:10:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:10:31 INFO - Downloaded 16332959 bytes. 03:10:31 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:10:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:10:38 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')]} 03:10:38 INFO - trying https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:10:38 INFO - Downloading https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:10:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:10:39 INFO - Downloaded 16332959 bytes. 03:10:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:45 INFO - trying https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:10:45 INFO - Downloading https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:10:46 INFO - Downloaded 14413719 bytes. 03:10:46 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 03:10:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:47 INFO - trying https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:10:47 INFO - Downloading https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:10:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 03:10:49 INFO - Downloaded 110993906 bytes. 03:10:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:10:49 INFO - mkdir: /builds/slave/test/properties 03:10:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:10:49 INFO - Writing to file /builds/slave/test/properties/build_url 03:10:49 INFO - Contents: 03:10:49 INFO - build_url:https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:10:50 INFO - Running post-action listener: _resource_record_post_action 03:10:50 INFO - Running post-action listener: set_extra_try_arguments 03:10:50 INFO - [mozharness: 2016-08-16 10:10:50.581105Z] Finished download-and-extract step (success) 03:10:50 INFO - [mozharness: 2016-08-16 10:10:50.581224Z] Running populate-webroot step. 03:10:50 INFO - Running pre-action listener: _resource_record_pre_action 03:10:50 INFO - Running main action method: populate_webroot 03:10:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:10:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:10:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:10:50 INFO - '--tppagecycles', 03:10:50 INFO - '7'], 03:10:50 INFO - 'tests': ['tsvgm']}}, 03:10:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:10:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:10:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:10:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:10:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:50 INFO - 'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:10:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:50 INFO - 'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['damp', 'tps']}, 03:10:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:50 INFO - 'tests': ['damp', 'tps']}, 03:10:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['dromaeo_dom']}, 03:10:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:10:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['basic_compositor_video']}, 03:10:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:10:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['a11yr', 03:10:50 INFO - 'ts_paint', 03:10:50 INFO - 'tpaint', 03:10:50 INFO - 'sessionrestore', 03:10:50 INFO - 'sessionrestore_no_auto_restore', 03:10:50 INFO - 'tabpaint']}, 03:10:50 INFO - 'other-e10s': {'tests': ['a11yr', 03:10:50 INFO - 'ts_paint', 03:10:50 INFO - 'tpaint', 03:10:50 INFO - 'sessionrestore', 03:10:50 INFO - 'sessionrestore_no_auto_restore', 03:10:50 INFO - 'tabpaint']}, 03:10:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:10:50 INFO - 'ts_paint', 03:10:50 INFO - 'tpaint', 03:10:50 INFO - 'sessionrestore', 03:10:50 INFO - 'sessionrestore_no_auto_restore', 03:10:50 INFO - 'tabpaint']}, 03:10:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:10:50 INFO - 'ts_paint', 03:10:50 INFO - 'tpaint', 03:10:50 INFO - 'sessionrestore', 03:10:50 INFO - 'sessionrestore_no_auto_restore', 03:10:50 INFO - 'tabpaint']}, 03:10:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['a11yr', 03:10:50 INFO - 'ts_paint', 03:10:50 INFO - 'tpaint', 03:10:50 INFO - 'sessionrestore', 03:10:50 INFO - 'sessionrestore_no_auto_restore', 03:10:50 INFO - 'tabpaint']}, 03:10:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['a11yr', 03:10:50 INFO - 'ts_paint', 03:10:50 INFO - 'tpaint', 03:10:50 INFO - 'sessionrestore', 03:10:50 INFO - 'sessionrestore_no_auto_restore', 03:10:50 INFO - 'tabpaint']}, 03:10:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['tsvgx', 03:10:50 INFO - 'tsvgr_opacity', 03:10:50 INFO - 'tart', 03:10:50 INFO - 'tscrollx', 03:10:50 INFO - 'cart']}, 03:10:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:10:50 INFO - 'tsvgr_opacity', 03:10:50 INFO - 'tart', 03:10:50 INFO - 'tscrollx', 03:10:50 INFO - 'cart']}, 03:10:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:50 INFO - 'talos_options': ['--disable-e10s'], 03:10:50 INFO - 'tests': ['tp5o']}, 03:10:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:50 INFO - 'tests': ['tp5o']}, 03:10:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:50 INFO - 'talos_options': ['--disable-e10s', 03:10:50 INFO - '--xperf_path', 03:10:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:50 INFO - 'tests': ['tp5n']}, 03:10:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:10:50 INFO - 'talos_options': ['--xperf_path', 03:10:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:10:50 INFO - 'tests': ['tp5n']}}, 03:10:50 INFO - 'talos.zip': {'path': '', 03:10:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:10:50 INFO - Running post-action listener: _resource_record_post_action 03:10:50 INFO - [mozharness: 2016-08-16 10:10:50.599748Z] Finished populate-webroot step (success) 03:10:50 INFO - [mozharness: 2016-08-16 10:10:50.599863Z] Running create-virtualenv step. 03:10:50 INFO - Running pre-action listener: _resource_record_pre_action 03:10:50 INFO - Running main action method: create_virtualenv 03:10:50 INFO - Creating virtualenv /builds/slave/test/build/venv 03:10:50 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 03:10:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:10:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:10:50 INFO - Using real prefix '/tools/python27' 03:10:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:10:51 INFO - Installing distribute.............................................................................................................................................................................................done. 03:10:54 INFO - Installing pip.................done. 03:10:54 INFO - Return code: 0 03:10:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:54 INFO - 'HOME': '/Users/cltbld', 03:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:54 INFO - 'LOGNAME': 'cltbld', 03:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:54 INFO - 'MOZ_NO_REMOTE': '1', 03:10:54 INFO - 'NO_EM_RESTART': '1', 03:10:54 INFO - 'PAGER': '/bin/cat', 03:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:54 INFO - 'PWD': '/builds/slave/test', 03:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:54 INFO - 'SHELL': '/bin/bash', 03:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:10:54 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:54 INFO - 'USER': 'cltbld', 03:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:54 INFO - 'XPC_FLAGS': '0x0', 03:10:54 INFO - 'XPC_SERVICE_NAME': '0', 03:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:10:54 INFO - Cleaning up... 03:10:54 INFO - Return code: 0 03:10:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:10:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:54 INFO - 'HOME': '/Users/cltbld', 03:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:54 INFO - 'LOGNAME': 'cltbld', 03:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:54 INFO - 'MOZ_NO_REMOTE': '1', 03:10:54 INFO - 'NO_EM_RESTART': '1', 03:10:54 INFO - 'PAGER': '/bin/cat', 03:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:54 INFO - 'PWD': '/builds/slave/test', 03:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:54 INFO - 'SHELL': '/bin/bash', 03:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:10:54 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:54 INFO - 'USER': 'cltbld', 03:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:54 INFO - 'XPC_FLAGS': '0x0', 03:10:54 INFO - 'XPC_SERVICE_NAME': '0', 03:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:54 INFO - Downloading/unpacking psutil>=3.1.1 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:10:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:58 INFO - Installing collected packages: psutil 03:10:58 INFO - Running setup.py install for psutil 03:10:58 INFO - building 'psutil._psutil_osx' extension 03:10:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:10:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:10:58 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:10:59 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 03:10:59 INFO - building 'psutil._psutil_posix' extension 03:10:59 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 03:10:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:10:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:10:59 INFO - ^ 03:10:59 INFO - 1 warning generated. 03:10:59 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 03:10:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:10:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:10:59 INFO - Successfully installed psutil 03:10:59 INFO - Cleaning up... 03:10:59 INFO - Return code: 0 03:10:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:10:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:10:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:10:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:10:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:10:59 INFO - 'HOME': '/Users/cltbld', 03:10:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:10:59 INFO - 'LOGNAME': 'cltbld', 03:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:10:59 INFO - 'MOZ_NO_REMOTE': '1', 03:10:59 INFO - 'NO_EM_RESTART': '1', 03:10:59 INFO - 'PAGER': '/bin/cat', 03:10:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:10:59 INFO - 'PWD': '/builds/slave/test', 03:10:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:10:59 INFO - 'SHELL': '/bin/bash', 03:10:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:10:59 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:10:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:10:59 INFO - 'USER': 'cltbld', 03:10:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:10:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:10:59 INFO - 'XPC_FLAGS': '0x0', 03:10:59 INFO - 'XPC_SERVICE_NAME': '0', 03:10:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:10:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:10:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:11:02 INFO - Installing collected packages: mozsystemmonitor 03:11:02 INFO - Running setup.py install for mozsystemmonitor 03:11:02 INFO - Successfully installed mozsystemmonitor 03:11:02 INFO - Cleaning up... 03:11:02 INFO - Return code: 0 03:11:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:11:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:02 INFO - 'HOME': '/Users/cltbld', 03:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:02 INFO - 'LOGNAME': 'cltbld', 03:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:02 INFO - 'MOZ_NO_REMOTE': '1', 03:11:02 INFO - 'NO_EM_RESTART': '1', 03:11:02 INFO - 'PAGER': '/bin/cat', 03:11:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:02 INFO - 'PWD': '/builds/slave/test', 03:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:02 INFO - 'SHELL': '/bin/bash', 03:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:11:02 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:02 INFO - 'USER': 'cltbld', 03:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:02 INFO - 'XPC_FLAGS': '0x0', 03:11:02 INFO - 'XPC_SERVICE_NAME': '0', 03:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:02 INFO - Downloading/unpacking jsonschema==2.5.1 03:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:11:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:11: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 03:11: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 03:11: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 03:11: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 03:11: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 03:11: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 03:11:05 INFO - Downloading functools32-3.2.3-2.tar.gz 03:11:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:11:05 INFO - warning: no files found matching '*.txt' 03:11:05 INFO - no previously-included directories found matching 'build' 03:11:05 INFO - no previously-included directories found matching 'dist' 03:11:05 INFO - no previously-included directories found matching '.git*' 03:11:05 INFO - Installing collected packages: jsonschema, functools32 03:11:05 INFO - Running setup.py install for functools32 03:11:05 INFO - warning: no files found matching '*.txt' 03:11:05 INFO - no previously-included directories found matching 'build' 03:11:05 INFO - no previously-included directories found matching 'dist' 03:11:05 INFO - no previously-included directories found matching '.git*' 03:11:05 INFO - Successfully installed jsonschema functools32 03:11:05 INFO - Cleaning up... 03:11:05 INFO - Return code: 0 03:11:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:11: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')]} 03:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11: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')]} 03:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:11:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:11:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:11:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:11:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:05 INFO - 'HOME': '/Users/cltbld', 03:11:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:05 INFO - 'LOGNAME': 'cltbld', 03:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:05 INFO - 'MOZ_NO_REMOTE': '1', 03:11:05 INFO - 'NO_EM_RESTART': '1', 03:11:05 INFO - 'PAGER': '/bin/cat', 03:11:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:05 INFO - 'PWD': '/builds/slave/test', 03:11:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:05 INFO - 'SHELL': '/bin/bash', 03:11:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:11:05 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:11:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:05 INFO - 'USER': 'cltbld', 03:11:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:05 INFO - 'XPC_FLAGS': '0x0', 03:11:05 INFO - 'XPC_SERVICE_NAME': '0', 03:11:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:11:06 INFO - Cleaning up... 03:11:06 INFO - Return code: 0 03:11:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:11:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:11:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:06 INFO - 'HOME': '/Users/cltbld', 03:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:06 INFO - 'LOGNAME': 'cltbld', 03:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:06 INFO - 'MOZ_NO_REMOTE': '1', 03:11:06 INFO - 'NO_EM_RESTART': '1', 03:11:06 INFO - 'PAGER': '/bin/cat', 03:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:06 INFO - 'PWD': '/builds/slave/test', 03:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:06 INFO - 'SHELL': '/bin/bash', 03:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:11:06 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:06 INFO - 'USER': 'cltbld', 03:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:06 INFO - 'XPC_FLAGS': '0x0', 03:11:06 INFO - 'XPC_SERVICE_NAME': '0', 03:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:06 INFO - Downloading/unpacking blobuploader==1.2.4 03:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:08 INFO - Downloading blobuploader-1.2.4.tar.gz 03:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:11:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:11:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:11:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:11:10 INFO - Downloading docopt-0.6.1.tar.gz 03:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:11:10 INFO - Installing collected packages: blobuploader, requests, docopt 03:11:10 INFO - Running setup.py install for blobuploader 03:11:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:11:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:11:10 INFO - Running setup.py install for requests 03:11:10 INFO - Running setup.py install for docopt 03:11:11 INFO - Successfully installed blobuploader requests docopt 03:11:11 INFO - Cleaning up... 03:11:11 INFO - Return code: 0 03:11:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:11 INFO - 'HOME': '/Users/cltbld', 03:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:11 INFO - 'LOGNAME': 'cltbld', 03:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:11 INFO - 'MOZ_NO_REMOTE': '1', 03:11:11 INFO - 'NO_EM_RESTART': '1', 03:11:11 INFO - 'PAGER': '/bin/cat', 03:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:11 INFO - 'PWD': '/builds/slave/test', 03:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:11 INFO - 'SHELL': '/bin/bash', 03:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:11:11 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:11 INFO - 'USER': 'cltbld', 03:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:11 INFO - 'XPC_FLAGS': '0x0', 03:11:11 INFO - 'XPC_SERVICE_NAME': '0', 03:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:11:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-rUTbjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:11:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:11:11 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-VUKRW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-efFPN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-iKjsEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-JFafbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-pcImPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-8w3iym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-O4pkBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-ORXJ9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-AcJRUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-P2hBgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:11:12 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-SvVvBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:11:13 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-45F84d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:11:13 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-kNsRIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:13 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-8tb9X4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:11:13 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-a5GoqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:11:13 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-xCX87i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:11:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:11:13 INFO - Running setup.py install for manifestparser 03:11:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:11:13 INFO - Running setup.py install for mozcrash 03:11:13 INFO - Running setup.py install for mozdebug 03:11:13 INFO - Running setup.py install for mozdevice 03:11:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:11:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:11:14 INFO - Running setup.py install for mozfile 03:11:14 INFO - Running setup.py install for mozhttpd 03:11:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:11:14 INFO - Running setup.py install for mozinfo 03:11:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:11:14 INFO - Running setup.py install for mozInstall 03:11:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:11:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:11:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:11:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:11:14 INFO - Running setup.py install for mozleak 03:11:14 INFO - Running setup.py install for mozlog 03:11:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:11:15 INFO - Running setup.py install for moznetwork 03:11:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:11:15 INFO - Running setup.py install for mozprocess 03:11:15 INFO - Running setup.py install for mozprofile 03:11:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:11:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:11:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:11:15 INFO - Running setup.py install for mozrunner 03:11:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:11:15 INFO - Running setup.py install for mozscreenshot 03:11:15 INFO - Running setup.py install for moztest 03:11:15 INFO - Running setup.py install for mozversion 03:11:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:11:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:11:16 INFO - Cleaning up... 03:11:16 INFO - Return code: 0 03:11:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:11: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')]} 03:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11: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')]} 03:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:16 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 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:11:16 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 03:11:16 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 03:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:16 INFO - 'HOME': '/Users/cltbld', 03:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:16 INFO - 'LOGNAME': 'cltbld', 03:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:16 INFO - 'MOZ_NO_REMOTE': '1', 03:11:16 INFO - 'NO_EM_RESTART': '1', 03:11:16 INFO - 'PAGER': '/bin/cat', 03:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:16 INFO - 'PWD': '/builds/slave/test', 03:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:16 INFO - 'SHELL': '/bin/bash', 03:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:11:16 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:16 INFO - 'USER': 'cltbld', 03:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:16 INFO - 'XPC_FLAGS': '0x0', 03:11:16 INFO - 'XPC_SERVICE_NAME': '0', 03:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:11:16 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-viVo0B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:11:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:11:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:11:16 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-usIwlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:11:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 03:11:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:11:16 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-Q70qon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:11:17 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)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-h4b1dU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-JKOpnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:11:17 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)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-HdwbIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:11:17 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)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-V2UAcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:11:17 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)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-h0Sbhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:11:17 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)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-3Fmud9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:11:17 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)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-1Eq5g9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-OxWco9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:11:17 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-rtPpCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 03:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-TWV52z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:11:18 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)) 03:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-29Dgi3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-pZn5ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:11:18 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)) 03:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-HzAPA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 03:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:11:18 INFO - Running setup.py (path:/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/pip-a_TSIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:11:18 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)) 03:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:11:18 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)) 03:11:18 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:11:18 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:11:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:11: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 03:11: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 03:11: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 03:11: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 03:11: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 03:11: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 03:11:21 INFO - Downloading blessings-1.6.tar.gz 03:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:11:21 INFO - Installing collected packages: blessings 03:11:21 INFO - Running setup.py install for blessings 03:11:21 INFO - Successfully installed blessings 03:11:21 INFO - Cleaning up... 03:11:21 INFO - Return code: 0 03:11:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:11:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:11:22 INFO - Reading from file tmpfile_stdout 03:11:22 INFO - Current package versions: 03:11:22 INFO - blessings == 1.6 03:11:22 INFO - blobuploader == 1.2.4 03:11:22 INFO - docopt == 0.6.1 03:11:22 INFO - functools32 == 3.2.3-2 03:11:22 INFO - jsonschema == 2.5.1 03:11:22 INFO - manifestparser == 1.1 03:11:22 INFO - mozInstall == 1.12 03:11:22 INFO - mozcrash == 0.17 03:11:22 INFO - mozdebug == 0.1 03:11:22 INFO - mozdevice == 0.48 03:11:22 INFO - mozfile == 1.2 03:11:22 INFO - mozhttpd == 0.7 03:11:22 INFO - mozinfo == 0.9 03:11:22 INFO - mozleak == 0.1 03:11:22 INFO - mozlog == 3.3 03:11:22 INFO - moznetwork == 0.27 03:11:22 INFO - mozprocess == 0.23 03:11:22 INFO - mozprofile == 0.28 03:11:22 INFO - mozrunner == 6.12 03:11:22 INFO - mozscreenshot == 0.1 03:11:22 INFO - mozsystemmonitor == 0.3 03:11:22 INFO - moztest == 0.7 03:11:22 INFO - mozversion == 1.4 03:11:22 INFO - psutil == 3.1.1 03:11:22 INFO - requests == 1.2.3 03:11:22 INFO - wsgiref == 0.1.2 03:11:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:22 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 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:11:22 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 03:11:22 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 03:11:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:11:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:22 INFO - 'HOME': '/Users/cltbld', 03:11:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:22 INFO - 'LOGNAME': 'cltbld', 03:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:22 INFO - 'MOZ_NO_REMOTE': '1', 03:11:22 INFO - 'NO_EM_RESTART': '1', 03:11:22 INFO - 'PAGER': '/bin/cat', 03:11:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:22 INFO - 'PWD': '/builds/slave/test', 03:11:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:22 INFO - 'SHELL': '/bin/bash', 03:11:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:11:22 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:11:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:22 INFO - 'USER': 'cltbld', 03:11:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:22 INFO - 'XPC_FLAGS': '0x0', 03:11:22 INFO - 'XPC_SERVICE_NAME': '0', 03:11:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:11:22 INFO - Cleaning up... 03:11:22 INFO - Return code: 0 03:11:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:11:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eee9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef7e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad695b3b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, '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.u86dsvcOLg/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.1plRLoCWZz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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 03:11:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:11:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:11:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:22 INFO - 'HOME': '/Users/cltbld', 03:11:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:22 INFO - 'LOGNAME': 'cltbld', 03:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:22 INFO - 'MOZ_NO_REMOTE': '1', 03:11:22 INFO - 'NO_EM_RESTART': '1', 03:11:22 INFO - 'PAGER': '/bin/cat', 03:11:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:22 INFO - 'PWD': '/builds/slave/test', 03:11:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:22 INFO - 'SHELL': '/bin/bash', 03:11:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:11:22 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:11:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:22 INFO - 'USER': 'cltbld', 03:11:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:22 INFO - 'XPC_FLAGS': '0x0', 03:11:22 INFO - 'XPC_SERVICE_NAME': '0', 03:11:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:11:22 INFO - Cleaning up... 03:11:22 INFO - Return code: 0 03:11:22 INFO - Running post-action listener: _resource_record_post_action 03:11:22 INFO - Running post-action listener: _start_resource_monitoring 03:11:22 INFO - Starting resource monitoring. 03:11:22 INFO - [mozharness: 2016-08-16 10:11:22.655096Z] Finished create-virtualenv step (success) 03:11:22 INFO - [mozharness: 2016-08-16 10:11:22.656059Z] Running install step. 03:11:22 INFO - Running pre-action listener: _resource_record_pre_action 03:11:22 INFO - Running main action method: install 03:11:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:11:22 INFO - Reading from file tmpfile_stdout 03:11:22 INFO - Detecting whether we're running mozinstall >=1.0... 03:11:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:11:23 INFO - Reading from file tmpfile_stdout 03:11:23 INFO - Output received: 03:11:23 INFO - Usage: mozinstall [options] installer 03:11:23 INFO - Options: 03:11:23 INFO - -h, --help show this help message and exit 03:11:23 INFO - -d DEST, --destination=DEST 03:11:23 INFO - Directory to install application into. [default: 03:11:23 INFO - "/builds/slave/test"] 03:11:23 INFO - --app=APP Application being installed. [default: firefox] 03:11:23 INFO - mkdir: /builds/slave/test/build/application 03:11:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:11:57 INFO - Reading from file tmpfile_stdout 03:11:57 INFO - Output received: 03:11:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:11:57 INFO - Running post-action listener: _resource_record_post_action 03:11:57 INFO - [mozharness: 2016-08-16 10:11:57.135043Z] Finished install step (success) 03:11:57 INFO - [mozharness: 2016-08-16 10:11:57.135215Z] Running run-tests step. 03:11:57 INFO - Running pre-action listener: _resource_record_pre_action 03:11:57 INFO - Running main action method: run_tests 03:11:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:11:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:11:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:11:57 INFO - 2016-08-16 03:11:57.150 screenresolution[1635:7442] starting screenresolution argv=screenresolution get 03:11:57 INFO - 2016-08-16 03:11:57.152 screenresolution[1635:7442] Display 0: 1600x1200x32@60 03:11:57 INFO - 2016-08-16 03:11:57.158 screenresolution[1636:7444] starting screenresolution argv=screenresolution list 03:11:57 INFO - Available Modes on Display 0 03:11:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:11:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:11:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:11:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:11:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:11:57 INFO - Intel Iris: 03:11:57 INFO - Chipset Model: Intel Iris 03:11:57 INFO - Type: GPU 03:11:57 INFO - Bus: Built-In 03:11:57 INFO - VRAM (Dynamic, Max): 1536 MB 03:11:57 INFO - Vendor: Intel (0x8086) 03:11:57 INFO - Device ID: 0x0a2e 03:11:57 INFO - Revision ID: 0x0009 03:11:57 INFO - Displays: 03:11:57 INFO - Display: 03:11:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:11:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:11:57 INFO - Display Serial Number: 1600x1200 60 03:11:57 INFO - Main Display: Yes 03:11:57 INFO - Mirror: Off 03:11:57 INFO - Online: Yes 03:11:57 INFO - Rotation: Supported 03:11:57 INFO - Return code: 0 03:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:11:57 INFO - Python 2.7.3 03:11:57 INFO - Return code: 0 03:11:57 INFO - grabbing minidump binary from tooltool 03:11:57 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')]} 03:11:57 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 0x10ee4dd30>, '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 0x10ebb2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ee45b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:11:57 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 03:11:57 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 03:11:57 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 03:11:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:11:57 INFO - Return code: 0 03:11:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:11:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:11:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:11:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:11:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 03:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0383 --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 03:11:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u86dsvcOLg/Render', 03:11:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:11:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:11:57 INFO - 'HOME': '/Users/cltbld', 03:11:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:11:57 INFO - 'LOGNAME': 'cltbld', 03:11:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:11:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:11:57 INFO - 'MOZ_NO_REMOTE': '1', 03:11:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:11:57 INFO - 'NO_EM_RESTART': '1', 03:11:57 INFO - 'PAGER': '/bin/cat', 03:11:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:11:57 INFO - 'PWD': '/builds/slave/test', 03:11:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:11:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:11:57 INFO - 'SHELL': '/bin/bash', 03:11:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1plRLoCWZz/Listeners', 03:11:57 INFO - 'TMPDIR': '/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/', 03:11:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:11:57 INFO - 'USER': 'cltbld', 03:11:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:11:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:11:57 INFO - 'XPC_FLAGS': '0x0', 03:11:57 INFO - 'XPC_SERVICE_NAME': '0', 03:11:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:11:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0383', '--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 03:11:57 INFO - mozversion application_buildid: 20160816011124 03:11:57 INFO - mozversion application_changeset: 9bf5770798c9fc30bc741437d9d94ab47afa54f1 03:11:57 INFO - mozversion application_display_name: Nightly 03:11:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:11:57 INFO - mozversion application_name: Firefox 03:11:57 INFO - mozversion application_remotingname: firefox 03:11:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:11:57 INFO - mozversion application_vendor: Mozilla 03:11:57 INFO - mozversion application_version: 51.0a1 03:11:57 INFO - mozversion platform_buildid: 20160816011124 03:11:57 INFO - mozversion platform_changeset: 9bf5770798c9fc30bc741437d9d94ab47afa54f1 03:11:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:11:57 INFO - mozversion platform_version: 51.0a1 03:11:57 INFO - using testdate: 1471342317 03:11:57 INFO - actual date: 1471342317 03:11:57 INFO - starting webserver on 'localhost:49267' 03:11:57 INFO - SUITE-START | Running 2 tests 03:11:57 INFO - TEST-START | dromaeo_css 03:11:57 INFO - operating with platform_type : mac_ 03:11:57 INFO - Initialising browser for dromaeo_css test... 03:11:57 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpUlTFiP/profile http://localhost:49267/getInfo.html) 03:12:00 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 03:12:00 INFO - PROCESS | 1646 | colorDepth:24 03:12:00 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 03:12:00 INFO - PROCESS | 1646 | __metrics 03:12:00 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:12:00 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:12:00 INFO - TEST-INFO | 1646: exit 0 03:12:00 INFO - Browser initialized. 03:12:00 INFO - Running cycle 1/1 for dromaeo_css test... 03:12:00 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpUlTFiP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:12:12 INFO - PROCESS | 1648 | RSS: Main: 228306944 03:12:12 INFO - PROCESS | 1648 | 03:13:04 INFO - PROCESS | 1648 | 1471342384315 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:15:16 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 03:15:17 INFO - PROCESS | 1648 | RSS: Main: 362446848 03:15:17 INFO - PROCESS | 1648 | 03:18:20 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 03:18:21 INFO - PROCESS | 1648 | RSS: Main: 373755904 03:18:21 INFO - PROCESS | 1648 | 03:21:23 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 03:21:24 INFO - PROCESS | 1648 | RSS: Main: 365645824 03:21:24 INFO - PROCESS | 1648 | 03:24:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 03:24:27 INFO - PROCESS | 1648 | RSS: Main: 357896192 03:24:27 INFO - PROCESS | 1648 | 03:27:30 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 03:27:31 INFO - PROCESS | 1648 | RSS: Main: 330407936 03:27:31 INFO - PROCESS | 1648 | 03:30:34 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 03:30:35 INFO - PROCESS | 1648 | RSS: Main: 569126912 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - PROCESS | 1648 | __start_tp_report 03:30:35 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 03:30:35 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 03:30:35 INFO - PROCESS | 1648 | |i|pagename|runs| 03:30:35 INFO - PROCESS | 1648 | |0;dojo.html;3036.1043492169806;3313.90058298251;3316.369889720953;3324.5345651608795;3354.765166438349;5530.917036244449;5626.943730562693;5675.2338434311405;5701.458361455665;5714.885702285952;5668.631538949965;5743.339515955667;5748.655080695151;5753.712314384285;5758.769649214037;5102.846914592568;5146.768395422197;5185.688858668492;5194.896102077956;5235.162374020158;5279.524842764151;5283.8150664726745;5286.286351342558;5293.15309550472;5297.788088476456;5152.974235128819;5191.818286359978;5193.948060519384;5200.0379929712935;5275.28783614211;5237.9214311785545;5266.315379000744;5278.81524146653;5286.392064912546;5318.042752304584;5068.265101560253;5109;5109.105906466353;5190.792368305263;5206.505381988705;5595.8880822383735;5642.548596112301;5643.6049476536655;5673.120666296731;5736.168255602956;6193.4425901669065;6203.7828675996125;6204.348543402923;6215.968920155416;6232.283287421914;6100.054491553772;6195.535334849904;6262.185915830958;6294.716737746812;6329.968350158266;5810.651360918274;5812.738426770763;5837.445442682938;5840.561957853178;5843.2695913010875;6266.530010249249;6299.275583307886;6309.495240380758;6315.052742088724;6316.557840951089;5132.230165475208;5927.2590926134235;6023.247094113236;6032.939670603238;6085.756569737249;5195.948040519622;6125.272869866689;6126.356732543108;6170.290416602058;6206.596571222856;5310.230016647564;6076.969615151985;6159.3224745277985;6164.506839452833;6192.504599632019;6137.9079313810325;6164.321924588292;6205.472534834499;6216.968915155395;6235.282942461584;4799.712017278975;4859.3682821233015;4873.049755297683;4881.4630390657;4926.704397736147;4397.890052748643;4416.050549131952;4447;4457;4461.353103799996;5732.426757324318;5749.080147176515;5769.365369809318;5801.448862358069;5814.30228372598;6092.51259899207;6189.226346706661;6194.132821405007;6222.173636508157;6290;5747.827565173052;5768.538516918637;5778.191053252548;5782;5810.070388737866;5402.486763757437;5420;5420.322459692538;5431.755570999315;5439;6232.345603711545;6269.7178626962805;6308.684565771601;6320.6839658016;6350.2062242220645;6270.216222972129;6284.402981716729;6301.779437719658;6306.9684651576445;6313.526485513697;6247.937520624736;6294.181756371642;6313.589616674902;6316.115743795965;6318.431341179132;5907.4683278505145;6078.270607527125;6081.361457046946;6114.266288045463;6145.539084568586;6268.561200715906;6271.3728627136925;6273.843153921189;6290.24517057956;6301.275353334425;6101.816945491642;6110.694465276807;6113.266408031065;6114.2357205349335;6115.969420152871;4647.047355292089;5917.822465326047;6028.517718582415;6088.330283668704;6105.267367915879;5931.851703707442;5959.940400595939;6024.578279520391;6028.668423236764;6031.638101713911;6106.9083963739695;6121.816345509642;6130.969345153424;6151.3541078186145;6169.259688837368;5732.16883551891;5819.534437244925;5847.44449277318;5972.462478376967;5975.342712301556 03:30:35 INFO - PROCESS | 1648 | |1;ext.html;478.4593409447324;483.62277423609584;532.1512188060045;541.5586297167808;552.7043031977893;30315.24211894701;32098.037058888243;40260.1947961041;41662.58337416625;41679.54121605744;42548.57451425485;42898;42953.57046429535;42964.785176074154;43263.35104973428;9663.130318271355;9842.852357214648;9851.605935762562;9863.704088877344;9876.061774131482;9784.315097943147;9907.950460247688;9912.653057143001;9916.553755081039;9918.256130790183;9869.259805514615;9886.357386769872;9939.900600994006;9944.701658950207;9945.60217591299;9836.213102951746;9873.259505537042;9886.357386769872;9890.653827116017;9899.554520046615;8397.6221070052;9620.71137865865;9757.756056098655;9779.657711980046;9789.510524473819;40404.59595404038;40638.59361406378;40734.38898416526;40775.184496310205;40887.7955610223;3425.143714071482;3497.3704733148024;3551.786892786441;3606.9098272543133;3853.3064048471265;30768.53847192294;31259;31350.52974205389;31903;32620.836895815606;2686.7716244119274;3230.8061516308862;3237.8057316560844;3248.3016151527536;3372.8313584320686;376195;377128;377612;377967;380357;55150.72424637851;55512.72629100229;55672;55694;55800.72099639476;33024.50463243053;33462.3307533848;33610;34517.82741086279;34972.65027349694;15966.323536028549;16047.438339658056;16084.356625735074;16098;16106.033637981758;12315.93842030802;12386.81419778704;12394.194377365442;12431.440585173688;12435.44040518179;18283.360082397052;18284.817151828312;18286.268549258148;18549.721754173697;18661.32048115676;7528;7630.427717921179;7638.427117966175;7674.961625191839;7788.337991270805;901.5176880368971;953.6042542344987;980.8136454073704;1009.268280496634;1025.74869157057;27902.89616669151;27910;28503.71496285052;29268.707312927025;30055.69944300529;888.6667499687617;1030.6135199300263;1081.6322450366913;1107.9556817727362;1114.5764609448358;12506.374681265901;15201.923990380199;15377.461788837561;15396.996780837064;15859.682806343808;674648;683218;685570;685649;687448;47133.76433117812;56369;56780.43219567834;56834;57034.14448783272;1238.789405801016;1277.9169353992147;1345.77121889281;1357.966050848717;1387.6392138044182;29241.122766317043;30796.692033079384;30823.38353232878;31040.844795775876;31565.684343156274;47058.29412558746;47301;47434.76282618565;47439.52560474351;47513.76243118762;533897;535734;537241;537332;537802;22903.19838805634;25385;25473.23580292594;25962;26016.8699156503;44807.551924480336;44968.100637986405;46693;47205.81985450391;47605.28592071052;31525.527117092803;31923.84038079795;35280.82359588288;37363.43954840734;38087.61912380841;37286.62713372832;37451.81274093612;37602.683906063154;37759;38635.23883320464;32328.35343293168;33049.50425743664;37909;38001.23997519979;38229.61770382261;30842.691573083983;33167.17082072871;33885;33893.98318050463;33944.15139621529;21932.574382665076;24790.38024049413;25113.74886251114;25289.62065568981;25936.61095083613 03:30:35 INFO - PROCESS | 1648 | |2;jquery.html;355.2610570014369;376.0636016319364;379.4952712891853;384.02266232438444;396.898790808344;19392.806071939278;20699.172033118695;20779.584408311843;20854.478638034037;20971;20239.344950661358;20528.692069618966;21060.78939210611;21104.0503177357;21389.144434222613;5704.11586204139;5706.971465142678;5811.099279611667;5886.735096920649;5889.234399528055;4126.133511962492;4357.520672726014;5748.166515855199;5856.32652244993;5867.911981320262;4180.602842729936;4266.509351424579;6227.750889964419;6538.509611779135;6612.933870661304;6423.747369262996;6434.324395938406;6543.083968244449;6576.868836365643;6657.667116644173;5052.494750524955;5670.319561652587;5673.602847800656;5676.886462270772;5679.630823996427;25140;25825.483490330087;25951.961921523118;26013.95944162231;26266.60600091;2213.2917466410754;2337.544178885118;2431;2775.5281602127484;3152.7162555369896;15361.692766144615;15534.145621990787;15824.050556966504;16160.19199040055;16202.594935126715;1778.6887294723372;1807.701729214678;1864.3381599532242;1900.1449347793548;1936.8547358948135;111984;112496.43751781271;115474.42262788716;115699;117457;10390.064894159563;10556.524956376828;10688.51901664427;10721.731956701145;10843.024127828534;11163.106951443864;13177.670558236121;13182.670433239246;13198.934005329911;13218.405171767101;8207.261346478846;8344.958275208584;8358.707445239508;8369.95815020921;8510.063892971768;8418.536980466011;8452.78868028292;8595.785105372415;8619.353548483889;8654.22112009922;5860.501857342088;5921.151251686352;6289.5911765736;6398.168238129107;6405.167328247298;1678.9432944642715;1792.6504331655462;1834.284628994693;1842.68674325365;1873.5105591055144;665.5973136252594;748.4461498491078;879.3800370738614;881.1893058386172;934.8831396075491;19929.60140797176;22256.55486890253;22283.44291392728;22339.77660223377;22458.55082898333;946.4794363100267;952.1288021460286;957.7988622389377;974.8050389922043;979.808937257228;8036;11687.415629218505;11822.763544729229;11842.993345565552;11845.933865951933;611067;617662;617734;617966;637315;101439;134634;140430.97845107704;141138;143340.28329858283;993.4833886379042;1006.6275478073013;1022.192467950311;1042.5517027678172;1042.5829668132808;18194.545136371697;18973.71539426882;19039.90480047591;19097.039958806407;19142.521436964187;55850.44149558452;55923.440765591826;56699.71650141723;57521.13718294145;57949.71025145016;83515.58242208751;83776.58111709403;83961;84590;84806.57596711977;7167;7188.8921666174;7703.114141873532;8028.759137225892;8106.635201416068;9686.46724430135;12104.757904841677;12431.875681243071;12598.433070512032;12861.485540578646;11003.899610038932;11068.034140696693;11147.996680298813;11157.21899467062;11314.321140731583;10769.784604308026;10841.945790270998;10935.17986151026;11024.228304018641;11346.092312614806;10831.67504974852;11143.944280278547;11281.661550153509;11290.322580645188;11620.360880151673;7764.145943946049;7933.920660793549;7937.523748575104;8018.3184429324165;8221.136780638186;8242.711505097293;10300.433476158883;10497.422641754776;10715;10819.404932728776 03:30:35 INFO - PROCESS | 1648 | |3;mootools.html;1484.1466156959748;1531.9846801531985;1680.428799072867;1828.8536917046638;1855.8051404602525;8345.666173353065;8877.733667989962;8882.200601945831;8888.466691998485;8939.597718102685;7792.181820908808;7989.880151797728;7991.9600401997905;8098.55457949814;8682.088380720028;2109.778473260301;2148.215901196064;2314.36355002374;2358.50471401006;2391.1391898916463;2355.305184970438;2371.691680081587;2378.3102900158933;2378.9762102378927;2380.059876348841;2384.7972922301624;2388.1880160745345;2394.616861302192;2394.916177933773;2395.149721848745;1996.8302694271003;2380;2381.821363397752;2383.6067048936907;2392.031227352925;2331.3239160643393;2346.343023953296;2376.6553849691873;2380.071772008918;2380.47629521505;8737.563121843947;8864.556772161366;8869.866951995726;8881;8881.866771998426;5212.947870521284;5349.812756553541;5354.223637572569;5356.598255130842;5362.142057270857;3526.770759900598;3624.0577449862963;3688.2623475305045;3696.2792255510185;3700.888973330804;1050.1808589300358;1087.9728006799894;1111.6331610568493;1116.9553217871276;1118.3178261260625;11608.187426880122;11619.302841829518;11621;11628.94185529067;11661.0088142508;4618.538146185422;4740.834070807574;4891.584215341664;4970.577500912391;4973.626977976666;9184.357095003419;9229.492377919145;9233.630654773733;9251.629934802533;9255.49094799779;5297.5232229099565;5297.973510132501;5304.496072873071;5307.734613269321;5310.654807437504;4458.866234012985;4857.635677324215;4900.387451568554;4921.803127874917;4966.950330496721;3237.2554312508255;3526.083218363244;3718.758280711745;3769.6607305342104;3861.227754449121;1176.3059794721155;1180.2859270141441;1183.319591235026;1190.988090119105;1193.4927655746274;759.449399185597;857.2670366836389;903.6430609909049;903.7876099116702;951.471933077135;4806.879828004258;5572.606848287928;5572.721363931787;6078.665673388006;6149.600275982137;971.1745016735719;984.675057231112;989.8515222716507;1059.7668512927144;1077.6120596585224;3451.188970591909;3661.377565813818;3927.3323534999117;3939.625735555118;4113.4446849675505;10617.946910265398;10908.454577271104;10994.450277486254;10997.12023038155;11046.502907369028;10926.235163538477;10930.836037459445;10939.12487001038;10944.4527773611;10956.671299861016;8005.719799806979;8147.9185208147155;8173;8315.212229370714;8337.166283371615;5790.62360946554;5857.033589457692;5861.794837180849;5940;6017.217761691128;10014.098731114234;10106.191504679755;10186.08325250731;10209;10271.178305735673;9828.213742900694;10323.380597164194;10404.034850067694;10405.115565176742;10437.739056523647;6618.867622647616;7116.25279345682;7246.60143692102;7251.456140789356;7254.927450725425;7892.145345843607;8245;8359;8480;8578.65685372578;7405.888911666437;7537.924620753723;7547.396208303213;7602.50583712057;7675.385969122569;7404.481686281909;7408.62956852166;7415.629218539159;7443.292887175709;7470.3276705096805;6970;6991.930080699128;7065.929340706527;7075.610841403773;7092.680829362588;4939.851804445872;4971.9254211186135;5001.124803159505;5018.799248030184;5086.338775959101;4947.183714687037;4982.078315511642;5051.545360917535;5102.846914592568;5254.213567387107 03:30:35 INFO - PROCESS | 1648 | |4;prototype.html;289.29700826990415;302.8183090145912;310.3173019357414;315.21511436523065;316.4225288847852;4265.594768496988;4836.540528649782;5105.651400355836;5375.381831089426;5376.118358574497;4350.956490435095;4487.596116349528;4842.1768299389105;4847.466778654345;4945.752712364385;3551.5738111426663;3649.5802982656937;4041.4948131483566;4064.5732198119217;4093.3450647896343;3155.668654791248;3205.4069997050506;3432.7597068205237;3581.9283614327696;3800.3159431302356;3618.710503159754;3884.892805550419;3935.5080614923136;4057.655099316547;4066.5360470230694;3395.049386171876;3736.9723326085273;3987.202559488085;3990.4413382126527;4031.7984100794843;2934.0611004478574;3209.245827230622;3479.930401391983;3488.7034602058857;3491.633378495272;5200.92198617021;5212.609054320903;5235.214717792323;5261.473852614746;5267.736613169364;4304.289792184286;4445.999650078738;4541.250693635546;4613.215753321943;4615.446146462446;4991.550760431543;5104.897902041976;5556.944430555724;5559.777608895639;5632.239647647556;3788.6021967693273;3791.962080379216;3827.9234415311817;3846.8649899305874;3861.5366156061173;4757.028904794498;4875.463698993072;5446.700431476267;5551.447073872725;5677.261955945742;3849.7882616455804;4015.859444919414;4028.91942161161;4045.3325201342036;4107.342825148022;4108.103786830277;4138.317177665712;4145.66834653227;4147.52303485097;4149.8962525937095;4433.113377324548;4497.694943667823;4696.506866779007;4735.171345014609;4735.881602959885;5249.790008399697;5599.608027438122;5614.85962850932;5648.350439699487;5660.584853786553;1230.6431134970937;1304.0569551368583;1368.1449094316054;1389.1526169036879;1391.867772561605;803.9718609848627;846.204567706354;864.8097418567905;866.3199388480012;871.8796346694467;4138.730982486129;4308.46144231971;4319.136172765459;4343.1096625192395;4412.977935110368;4304.78476076195;4735.4373056891145;4748.881277968009;4757.597413306258;4850.563449289581;3537.3809583322814;4054.6483039837412;4266.616004559604;4282.700210985264;4302.9569704303185;2293.7018187635836;2324.0006797077417;2389.673731079268;2559.6800399950007;2576.458943621823;4818.723268177673;5047.899042019139;5050.368703912011;5064.5948324134715;5066.6453348266;4409.184300904343;4442.933355999663;4842.757862106881;4846.854594362175;4960.792447360962;4138.627523522898;4155.459790227251;4267.314410807714;4660.390181498372;4863.756812159378;4023.4970628671417;4421.9216968024975;4717.716936983792;4739.502352253102;4775.808967641256;4532.660050496292;4534.231649656571;4628.94012164597;4722.905541889074;4848.854534363974;4274.572542745701;4653.371794807657;4663.743494107721;4688.953110468851;5035.899282014266;2973.464776340193;3317.4526203176206;3419.401604719214;3456.9308613828084;3466.9653303467653;4098.774567398703;4292.390353617394;4564.977175114103;4717.221658426459;4863.862808488576;4258.936115958325;4311.956880431281;4321.654267658642;4476.597106260452;4909.509049095205;5675.31896172462;5680.460356266148;5695.294735001007;5718.571107167062;5773.624714393551;4421.314696222091;4452;4883.389576302962;5184.662996905189;5581.581381396492;4851.975740121276;5195.415398303554;5477.945220547744;5641.548676105819;5724.4561766632105;5542.3903370630005;5559.2217089606675;5562.582806289625;5572.554195664256;5595.664260144405 03:30:35 INFO - PROCESS | 1648 | |5;yui.html;669.5413641655466;723.7611588175906;769.3229957637278;774.9499428274688;791.4815795653845;14680.632984175401;15836.970596911193;15866.84133158668;15916.045037297756;15919.442819501306;14064.15615063097;14336.068155569881;14860.925695371508;15034.924825375885;15080.396784128621;841.9999999999985;865.7489328094845;866.1295398124876;867.8741582470537;869.8129902071054;691.4641153106332;713.6253466929877;717.7595505505661;867.822096470222;873.1530415496958;747.3946103656028;749.0935967479356;902.4224496322358;938.2962777916563;941.4821847983616;928.3501548915718;928.5403725156024;929.4609126706528;930.1210356213381;930.4463844012802;682.7234969837224;682.9795106146773;683.2791405067652;683.5898460923456;684.305429988562;12134.332611706353;14709.485168019068;14713.779293310608;15044.323005464781;15068.397264109422;14499.56501304952;14991.475298364614;15047.924760376129;15090.849091509164;15222.695546089128;12078.577249796215;12133.393330333536;12226.327551984637;12230.789948826474;12267.57063502773;3422.9731080675947;3668.174660701321;3842.9615703842883;3865.9806700966315;3910.7262491625597;87633;114881.4255928715;115526;115862.68274634544;116668;11176.888231117584;11482.368469734078;11607.129465289969;11745.29528228309;11791.11566632506;23361.649575256386;23385.532289354116;23405;23410.53178936412;23438.179663712028;6830.21452532965;8013.998250218722;8039.598020099089;8269.95865020671;8331.416800824005;4345.717528360709;4593.196190666687;4651.720896746206;4664.137134630104;4711.905761884743;3498.4927185557954;3568.518250036212;3659.158393569494;3668;3674.6325367463573;649.6978904809287;652.2499126005132;656.579788935082;656.7176114270909;659.4197106546209;540.5675459632279;595.8361450601033;613.6747523812387;653.1509038250256;693.6254422611734;7424.888626670604;8394.160583941679;9068.775715278485;9169.679061232959;9295.442273463614;532.6830535831174;603.6136872401667;621.5990785240487;635.2821311917504;667.893137098062;3749.278793545968;3775.8489660413825;4294.914101717948;4376.321670141133;4396.164728701537;83244;83645;85323.57338213269;94261.58607620891;136839;128441.86981578838;130606.34696826455;132241;134098;135770.21148942513;112120.43939780412;112970;114292;114668;114877;8771.429857059271;8812.339074569305;8821.250193733604;8886.155815197546;9103.271738261055;45390.77304613588;60931.38357705287;61492.77014459596;61746.76506469755;63974.04038939327;62562.68718656377;92589.5370523143;96812.51593741986;98907;110846.89153108366;9719.805603888024;11118;11497.712557186138;11813.054955603702;11838.82241766357;12638.684032899251;12656.620301390973;12665.430055647703;12842.807357889453;13068;11891.167618266638;11974.782621635466;12248.632541023784;12323;12478.126531142732;10211.336263142872;12213.633590992284;12404.131710780159;12569.99440044776;12577.119601627799;11656.242344247597;12052.819207712068;12081.395930203278;12451;12460.003199743818;9393.906060939304;9841.360311579725;10046.497675116361;10118.898811011795;10250.38497690141;8510.872336914827;9400.05999400082;9481.905180948102;10245.282830201815;10270.948645256725 03:30:35 INFO - PROCESS | 1648 | __end_tp_report 03:30:35 INFO - PROCESS | 1648 | __start_cc_report 03:30:35 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1260 03:30:35 INFO - PROCESS | 1648 | __end_cc_report 03:30:35 INFO - PROCESS | 1648 | __startTimestamp1471343435020__endTimestamp 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - PROCESS | 1648 | ------- Summary: start ------- 03:30:35 INFO - PROCESS | 1648 | Number of tests: 6 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - PROCESS | 1648 | [#0] dojo.html Cycles:165 Average:5712.89 Median:5907.47 stddev:649.77 (11.0%) stddev-sans-first:616.90 03:30:35 INFO - PROCESS | 1648 | Values: 3036.1 3313.9 3316.4 3324.5 3354.8 5530.9 5626.9 5675.2 5701.5 5714.9 5668.6 5743.3 5748.7 5753.7 5758.8 5102.8 5146.8 5185.7 5194.9 5235.2 5279.5 5283.8 5286.3 5293.2 5297.8 5153.0 5191.8 5193.9 5200.0 5275.3 5237.9 5266.3 5278.8 5286.4 5318.0 5068.3 5109.0 5109.1 5190.8 5206.5 5595.9 5642.5 5643.6 5673.1 5736.2 6193.4 6203.8 6204.3 6216.0 6232.3 6100.1 6195.5 6262.2 6294.7 6330.0 5810.7 5812.7 5837.4 5840.6 5843.3 6266.5 6299.3 6309.5 6315.1 6316.6 5132.2 5927.3 6023.2 6032.9 6085.8 5195.9 6125.3 6126.4 6170.3 6206.6 5310.2 6077.0 6159.3 6164.5 6192.5 6137.9 6164.3 6205.5 6217.0 6235.3 4799.7 4859.4 4873.0 4881.5 4926.7 4397.9 4416.1 4447.0 4457.0 4461.4 5732.4 5749.1 5769.4 5801.4 5814.3 6092.5 6189.2 6194.1 6222.2 6290.0 5747.8 5768.5 5778.2 5782.0 5810.1 5402.5 5420.0 5420.3 5431.8 5439.0 6232.3 6269.7 6308.7 6320.7 6350.2 6270.2 6284.4 6301.8 6307.0 6313.5 6247.9 6294.2 6313.6 6316.1 6318.4 5907.5 6078.3 6081.4 6114.3 6145.5 6268.6 6271.4 6273.8 6290.2 6301.3 6101.8 6110.7 6113.3 6114.2 6116.0 4647.0 5917.8 6028.5 6088.3 6105.3 5931.9 5959.9 6024.6 6028.7 6031.6 6106.9 6121.8 6131.0 6151.4 6169.3 5732.2 5819.5 5847.4 5972.5 5975.3 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - PROCESS | 1648 | [#1] ext.html Cycles:180 Average:65768.05 Median:207938.00 stddev:146548.10 (70.5%) stddev-sans-first:146877.21 03:30:35 INFO - PROCESS | 1648 | Values: 478.5 483.6 532.2 541.6 552.7 30315.2 32098.0 40260.2 41662.6 41679.5 42548.6 42898.0 42953.6 42964.8 43263.4 9663.1 9842.9 9851.6 9863.7 9876.1 9784.3 9908.0 9912.7 9916.6 9918.3 9869.3 9886.4 9939.9 9944.7 9945.6 9836.2 9873.3 9886.4 9890.7 9899.6 8397.6 9620.7 9757.8 9779.7 9789.5 40404.6 40638.6 40734.4 40775.2 40887.8 3425.1 3497.4 3551.8 3606.9 3853.3 30768.5 31259.0 31350.5 31903.0 32620.8 2686.8 3230.8 3237.8 3248.3 3372.8 376195.0 377128.0 377612.0 377967.0 380357.0 55150.7 55512.7 55672.0 55694.0 55800.7 33024.5 33462.3 33610.0 34517.8 34972.7 15966.3 16047.4 16084.4 16098.0 16106.0 12315.9 12386.8 12394.2 12431.4 12435.4 18283.4 18284.8 18286.3 18549.7 18661.3 7528.0 7630.4 7638.4 7675.0 7788.3 901.5 953.6 980.8 1009.3 1025.7 27902.9 27910.0 28503.7 29268.7 30055.7 888.7 1030.6 1081.6 1108.0 1114.6 12506.4 15201.9 15377.5 15397.0 15859.7 674648.0 683218.0 685570.0 685649.0 687448.0 47133.8 56369.0 56780.4 56834.0 57034.1 1238.8 1277.9 1345.8 1358.0 1387.6 29241.1 30796.7 30823.4 31040.8 31565.7 47058.3 47301.0 47434.8 47439.5 47513.8 533897.0 535734.0 537241.0 537332.0 537802.0 22903.2 25385.0 25473.2 25962.0 26016.9 44807.6 44968.1 46693.0 47205.8 47605.3 31525.5 31923.8 35280.8 37363.4 38087.6 37286.6 37451.8 37602.7 37759.0 38635.2 32328.4 33049.5 37909.0 38001.2 38229.6 30842.7 33167.2 33885.0 33894.0 33944.2 21932.6 24790.4 25113.7 25289.6 25936.6 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - PROCESS | 1648 | [#2] jquery.html Cycles:180 Average:36070.23 Median:25888.72 stddev:103480.98 (399.7%) stddev-sans-first:103736.52 03:30:35 INFO - PROCESS | 1648 | Values: 355.3 376.1 379.5 384.0 396.9 19392.8 20699.2 20779.6 20854.5 20971.0 20239.3 20528.7 21060.8 21104.1 21389.1 5704.1 5707.0 5811.1 5886.7 5889.2 4126.1 4357.5 5748.2 5856.3 5867.9 4180.6 4266.5 6227.8 6538.5 6612.9 6423.7 6434.3 6543.1 6576.9 6657.7 5052.5 5670.3 5673.6 5676.9 5679.6 25140.0 25825.5 25952.0 26014.0 26266.6 2213.3 2337.5 2431.0 2775.5 3152.7 15361.7 15534.1 15824.1 16160.2 16202.6 1778.7 1807.7 1864.3 1900.1 1936.9 111984.0 112496.4 115474.4 115699.0 117457.0 10390.1 10556.5 10688.5 10721.7 10843.0 11163.1 13177.7 13182.7 13198.9 13218.4 8207.3 8345.0 8358.7 8370.0 8510.1 8418.5 8452.8 8595.8 8619.4 8654.2 5860.5 5921.2 6289.6 6398.2 6405.2 1678.9 1792.7 1834.3 1842.7 1873.5 665.6 748.4 879.4 881.2 934.9 19929.6 22256.6 22283.4 22339.8 22458.6 946.5 952.1 957.8 974.8 979.8 8036.0 11687.4 11822.8 11843.0 11845.9 611067.0 617662.0 617734.0 617966.0 637315.0 101439.0 134634.0 140431.0 141138.0 143340.3 993.5 1006.6 1022.2 1042.6 1042.6 18194.5 18973.7 19039.9 19097.0 19142.5 55850.4 55923.4 56699.7 57521.1 57949.7 83515.6 83776.6 83961.0 84590.0 84806.6 7167.0 7188.9 7703.1 8028.8 8106.6 9686.5 12104.8 12431.9 12598.4 12861.5 11003.9 11068.0 11148.0 11157.2 11314.3 10769.8 10841.9 10935.2 11024.2 11346.1 10831.7 11143.9 11281.7 11290.3 11620.4 7764.1 7933.9 7937.5 8018.3 8221.1 8242.7 10300.4 10497.4 10715.0 10819.4 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - PROCESS | 1648 | [#3] mootools.html Cycles:180 Average:5695.75 Median:4957.07 stddev:3200.20 (64.6%) stddev-sans-first:3193.53 03:30:35 INFO - PROCESS | 1648 | Values: 1484.1 1532.0 1680.4 1828.9 1855.8 8345.7 8877.7 8882.2 8888.5 8939.6 7792.2 7989.9 7992.0 8098.6 8682.1 2109.8 2148.2 2314.4 2358.5 2391.1 2355.3 2371.7 2378.3 2379.0 2380.1 2384.8 2388.2 2394.6 2394.9 2395.1 1996.8 2380.0 2381.8 2383.6 2392.0 2331.3 2346.3 2376.7 2380.1 2380.5 8737.6 8864.6 8869.9 8881.0 8881.9 5212.9 5349.8 5354.2 5356.6 5362.1 3526.8 3624.1 3688.3 3696.3 3700.9 1050.2 1088.0 1111.6 1117.0 1118.3 11608.2 11619.3 11621.0 11628.9 11661.0 4618.5 4740.8 4891.6 4970.6 4973.6 9184.4 9229.5 9233.6 9251.6 9255.5 5297.5 5298.0 5304.5 5307.7 5310.7 4458.9 4857.6 4900.4 4921.8 4967.0 3237.3 3526.1 3718.8 3769.7 3861.2 1176.3 1180.3 1183.3 1191.0 1193.5 759.4 857.3 903.6 903.8 951.5 4806.9 5572.6 5572.7 6078.7 6149.6 971.2 984.7 989.9 1059.8 1077.6 3451.2 3661.4 3927.3 3939.6 4113.4 10617.9 10908.5 10994.5 10997.1 11046.5 10926.2 10930.8 10939.1 10944.5 10956.7 8005.7 8147.9 8173.0 8315.2 8337.2 5790.6 5857.0 5861.8 5940.0 6017.2 10014.1 10106.2 10186.1 10209.0 10271.2 9828.2 10323.4 10404.0 10405.1 10437.7 6618.9 7116.3 7246.6 7251.5 7254.9 7892.1 8245.0 8359.0 8480.0 8578.7 7405.9 7537.9 7547.4 7602.5 7675.4 7404.5 7408.6 7415.6 7443.3 7470.3 6970.0 6991.9 7065.9 7075.6 7092.7 4939.9 4971.9 5001.1 5018.8 5086.3 4947.2 4982.1 5051.5 5102.8 5254.2 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - PROCESS | 1648 | [#4] prototype.html Cycles:180 Average:4177.85 Median:4464.30 stddev:1255.66 (28.1%) stddev-sans-first:1224.79 03:30:35 INFO - PROCESS | 1648 | Values: 289.3 302.8 310.3 315.2 316.4 4265.6 4836.5 5105.7 5375.4 5376.1 4351.0 4487.6 4842.2 4847.5 4945.8 3551.6 3649.6 4041.5 4064.6 4093.3 3155.7 3205.4 3432.8 3581.9 3800.3 3618.7 3884.9 3935.5 4057.7 4066.5 3395.0 3737.0 3987.2 3990.4 4031.8 2934.1 3209.2 3479.9 3488.7 3491.6 5200.9 5212.6 5235.2 5261.5 5267.7 4304.3 4446.0 4541.3 4613.2 4615.4 4991.6 5104.9 5556.9 5559.8 5632.2 3788.6 3792.0 3827.9 3846.9 3861.5 4757.0 4875.5 5446.7 5551.4 5677.3 3849.8 4015.9 4028.9 4045.3 4107.3 4108.1 4138.3 4145.7 4147.5 4149.9 4433.1 4497.7 4696.5 4735.2 4735.9 5249.8 5599.6 5614.9 5648.4 5660.6 1230.6 1304.1 1368.1 1389.2 1391.9 804.0 846.2 864.8 866.3 871.9 4138.7 4308.5 4319.1 4343.1 4413.0 4304.8 4735.4 4748.9 4757.6 4850.6 3537.4 4054.6 4266.6 4282.7 4303.0 2293.7 2324.0 2389.7 2559.7 2576.5 4818.7 5047.9 5050.4 5064.6 5066.6 4409.2 4442.9 4842.8 4846.9 4960.8 4138.6 4155.5 4267.3 4660.4 4863.8 4023.5 4421.9 4717.7 4739.5 4775.8 4532.7 4534.2 4628.9 4722.9 4848.9 4274.6 4653.4 4663.7 4689.0 5035.9 2973.5 3317.5 3419.4 3456.9 3467.0 4098.8 4292.4 4565.0 4717.2 4863.9 4258.9 4312.0 4321.7 4476.6 4909.5 5675.3 5680.5 5695.3 5718.6 5773.6 4421.3 4452.0 4883.4 5184.7 5581.6 4852.0 5195.4 5477.9 5641.5 5724.5 5542.4 5559.2 5562.6 5572.6 5595.7 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - PROCESS | 1648 | [#5] yui.html Cycles:180 Average:23296.37 Median:3888.35 stddev:36664.16 (942.9%) stddev-sans-first:36727.65 03:30:35 INFO - PROCESS | 1648 | Values: 669.5 723.8 769.3 774.9 791.5 14680.6 15837.0 15866.8 15916.0 15919.4 14064.2 14336.1 14860.9 15034.9 15080.4 842.0 865.7 866.1 867.9 869.8 691.5 713.6 717.8 867.8 873.2 747.4 749.1 902.4 938.3 941.5 928.4 928.5 929.5 930.1 930.4 682.7 683.0 683.3 683.6 684.3 12134.3 14709.5 14713.8 15044.3 15068.4 14499.6 14991.5 15047.9 15090.8 15222.7 12078.6 12133.4 12226.3 12230.8 12267.6 3423.0 3668.2 3843.0 3866.0 3910.7 87633.0 114881.4 115526.0 115862.7 116668.0 11176.9 11482.4 11607.1 11745.3 11791.1 23361.6 23385.5 23405.0 23410.5 23438.2 6830.2 8014.0 8039.6 8270.0 8331.4 4345.7 4593.2 4651.7 4664.1 4711.9 3498.5 3568.5 3659.2 3668.0 3674.6 649.7 652.2 656.6 656.7 659.4 540.6 595.8 613.7 653.2 693.6 7424.9 8394.2 9068.8 9169.7 9295.4 532.7 603.6 621.6 635.3 667.9 3749.3 3775.8 4294.9 4376.3 4396.2 83244.0 83645.0 85323.6 94261.6 136839.0 128441.9 130606.3 132241.0 134098.0 135770.2 112120.4 112970.0 114292.0 114668.0 114877.0 8771.4 8812.3 8821.3 8886.2 9103.3 45390.8 60931.4 61492.8 61746.8 63974.0 62562.7 92589.5 96812.5 98907.0 110846.9 9719.8 11118.0 11497.7 11813.1 11838.8 12638.7 12656.6 12665.4 12842.8 13068.0 11891.2 11974.8 12248.6 12323.0 12478.1 10211.3 12213.6 12404.1 12570.0 12577.1 11656.2 12052.8 12081.4 12451.0 12460.0 9393.9 9841.4 10046.5 10118.9 10250.4 8510.9 9400.1 9481.9 10245.3 10270.9 03:30:35 INFO - PROCESS | 1648 | -------- Summary: end -------- 03:30:35 INFO - PROCESS | 1648 | 03:30:35 INFO - TEST-INFO | 1648: exit 0 03:30:35 INFO - TEST-OK | dromaeo_css | took 1117915ms 03:30:35 INFO - TEST-START | kraken 03:30:35 INFO - operating with platform_type : mac_ 03:30:35 INFO - Initialising browser for kraken test... 03:30:35 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpcUZ7oB/profile http://localhost:49267/getInfo.html) 03:30:37 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 03:30:37 INFO - PROCESS | 1658 | colorDepth:24 03:30:37 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 03:30:37 INFO - PROCESS | 1658 | __metrics 03:30:38 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:30:38 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:30:38 INFO - TEST-INFO | 1658: exit 0 03:30:38 INFO - Browser initialized. 03:30:38 INFO - Running cycle 1/1 for kraken test... 03:30:38 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/T/tmpcUZ7oB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:30:50 INFO - PROCESS | 1659 | RSS: Main: 228204544 03:30:50 INFO - PROCESS | 1659 | 03:30:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 03:30:54 INFO - PROCESS | 1659 | RSS: Main: 286621696 03:30:54 INFO - PROCESS | 1659 | 03:30:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 03:30:57 INFO - PROCESS | 1659 | RSS: Main: 293089280 03:30:57 INFO - PROCESS | 1659 | 03:31:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 03:31:01 INFO - PROCESS | 1659 | RSS: Main: 386539520 03:31:01 INFO - PROCESS | 1659 | 03:31:03 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 03:31:04 INFO - PROCESS | 1659 | RSS: Main: 290656256 03:31:04 INFO - PROCESS | 1659 | 03:31:06 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:31:06 INFO - PROCESS | 1659 | RSS: Main: 295055360 03:31:06 INFO - PROCESS | 1659 | 03:31:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 03:31:10 INFO - PROCESS | 1659 | RSS: Main: 331108352 03:31:10 INFO - PROCESS | 1659 | 03:31:13 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 03:31:13 INFO - PROCESS | 1659 | RSS: Main: 333705216 03:31:13 INFO - PROCESS | 1659 | 03:31:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 03:31:17 INFO - PROCESS | 1659 | RSS: Main: 332357632 03:31:17 INFO - PROCESS | 1659 | 03:31:18 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:31:19 INFO - PROCESS | 1659 | RSS: Main: 301998080 03:31:19 INFO - PROCESS | 1659 | 03:31:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:31:22 INFO - PROCESS | 1659 | RSS: Main: 308727808 03:31:22 INFO - PROCESS | 1659 | 03:31:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:31:25 INFO - PROCESS | 1659 | RSS: Main: 306249728 03:31:25 INFO - PROCESS | 1659 | 03:31:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:31:28 INFO - PROCESS | 1659 | RSS: Main: 316260352 03:31:28 INFO - PROCESS | 1659 | 03:31:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:31:32 INFO - PROCESS | 1659 | RSS: Main: 317566976 03:31:32 INFO - PROCESS | 1659 | 03:31:33 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 03:31:34 INFO - PROCESS | 1659 | RSS: Main: 361009152 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | __start_tp_report 03:31:34 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 03:31:34 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 03:31:34 INFO - PROCESS | 1659 | |i|pagename|runs| 03:31:34 INFO - PROCESS | 1659 | |0;ai-astar;102;113;113;112;109;114;112;115;113;113 03:31:34 INFO - PROCESS | 1659 | |1;audio-beat-detection;143;109;106;166;107;102;127;106;140;107 03:31:34 INFO - PROCESS | 1659 | |2;audio-dft;186;187;184;184;184;181;181;181;186;184 03:31:34 INFO - PROCESS | 1659 | |3;audio-fft;79;57;60;86;59;58;67;61;82;57 03:31:34 INFO - PROCESS | 1659 | |4;audio-oscillator;86;69;102;71;69;68;96;70;68;102 03:31:34 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;90;87;89;91;89;91;89;86;89;87 03:31:34 INFO - PROCESS | 1659 | |6;imaging-darkroom;95;96;96;95;95;95;96;95;107;149 03:31:34 INFO - PROCESS | 1659 | |7;imaging-desaturate;85;85;89;70;112;68;113;137;142;85 03:31:34 INFO - PROCESS | 1659 | |8;json-parse-financial;56;55;57;57;55;58;58;57;58;56 03:31:34 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;64;66;75;74;71;67;68;69;69;68 03:31:34 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;85;88;87;86;98;102;106;79;88;82 03:31:34 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;138;138;139;138;123;171;151;150;159;138 03:31:34 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;166;166;167;164;166;166;165;169;167;167 03:31:34 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;67;67;66;66;68;81;83;84;66 03:31:34 INFO - PROCESS | 1659 | __end_tp_report 03:31:34 INFO - PROCESS | 1659 | __start_cc_report 03:31:34 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2029 03:31:34 INFO - PROCESS | 1659 | __end_cc_report 03:31:34 INFO - PROCESS | 1659 | __startTimestamp1471343494158__endTimestamp 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | ------- Summary: start ------- 03:31:34 INFO - PROCESS | 1659 | Number of tests: 14 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:3.72 (3.3%) stddev-sans-first:1.66 03:31:34 INFO - PROCESS | 1659 | Values: 102.0 113.0 113.0 112.0 109.0 114.0 112.0 115.0 113.0 113.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:118.00 stddev:21.74 (18.4%) stddev-sans-first:21.59 03:31:34 INFO - PROCESS | 1659 | Values: 143.0 109.0 106.0 166.0 107.0 102.0 127.0 106.0 140.0 107.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:2.20 (1.2%) stddev-sans-first:2.19 03:31:34 INFO - PROCESS | 1659 | Values: 186.0 187.0 184.0 184.0 184.0 181.0 181.0 181.0 186.0 184.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:66.60 Median:64.00 stddev:11.35 (17.7%) stddev-sans-first:11.11 03:31:34 INFO - PROCESS | 1659 | Values: 79.0 57.0 60.0 86.0 59.0 58.0 67.0 61.0 82.0 57.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:80.10 Median:69.50 stddev:14.80 (21.3%) stddev-sans-first:15.54 03:31:34 INFO - PROCESS | 1659 | Values: 86.0 69.0 102.0 71.0 69.0 68.0 96.0 70.0 68.0 102.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:89.00 stddev:1.69 (1.9%) stddev-sans-first:1.73 03:31:34 INFO - PROCESS | 1659 | Values: 90.0 87.0 89.0 91.0 89.0 91.0 89.0 86.0 89.0 87.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:95.00 stddev:16.95 (17.8%) stddev-sans-first:17.80 03:31:34 INFO - PROCESS | 1659 | Values: 95.0 96.0 96.0 95.0 95.0 95.0 96.0 95.0 107.0 149.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:98.60 Median:77.50 stddev:26.13 (33.7%) stddev-sans-first:27.25 03:31:34 INFO - PROCESS | 1659 | Values: 85.0 85.0 89.0 70.0 112.0 68.0 113.0 137.0 142.0 85.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:57.00 stddev:1.16 (2.0%) stddev-sans-first:1.20 03:31:34 INFO - PROCESS | 1659 | Values: 56.0 55.0 57.0 57.0 55.0 58.0 58.0 57.0 58.0 56.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.00 stddev:3.41 (4.9%) stddev-sans-first:3.08 03:31:34 INFO - PROCESS | 1659 | Values: 64.0 66.0 75.0 74.0 71.0 67.0 68.0 69.0 69.0 68.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:86.50 stddev:8.86 (10.2%) stddev-sans-first:9.21 03:31:34 INFO - PROCESS | 1659 | Values: 85.0 88.0 87.0 86.0 98.0 102.0 106.0 79.0 88.0 82.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:144.50 stddev:13.53 (9.4%) stddev-sans-first:14.14 03:31:34 INFO - PROCESS | 1659 | Values: 138.0 138.0 139.0 138.0 123.0 171.0 151.0 150.0 159.0 138.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.30 Median:166.50 stddev:1.34 (0.8%) stddev-sans-first:1.41 03:31:34 INFO - PROCESS | 1659 | Values: 166.0 166.0 167.0 164.0 166.0 166.0 165.0 169.0 167.0 167.0 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:67.50 stddev:7.76 (11.5%) stddev-sans-first:8.06 03:31:34 INFO - PROCESS | 1659 | Values: 67.0 67.0 67.0 66.0 66.0 68.0 81.0 83.0 84.0 66.0 03:31:34 INFO - PROCESS | 1659 | -------- Summary: end -------- 03:31:34 INFO - PROCESS | 1659 | 03:31:34 INFO - TEST-INFO | 1659: exit 0 03:31:34 INFO - TEST-OK | kraken | took 59339ms 03:31:34 INFO - SUITE-END | took 1177s 03:31:35 INFO - Completed test suite (00:19:37) 03:31:35 INFO - javascript benchmark 03:31:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3036.1043492169806, 3313.90058298251, 3316.369889720953, 3324.5345651608795, 3354.765166438349, 5530.917036244449, 5626.943730562693, 5675.2338434311405, 5701.458361455665, 5714.885702285952, 5668.631538949965, 5743.339515955667, 5748.655080695151, 5753.712314384285, 5758.769649214037, 5102.846914592568, 5146.768395422197, 5185.688858668492, 5194.896102077956, 5235.162374020158, 5279.524842764151, 5283.8150664726745, 5286.286351342558, 5293.15309550472, 5297.788088476456, 5152.974235128819, 5191.818286359978, 5193.948060519384, 5200.0379929712935, 5275.28783614211, 5237.9214311785545, 5266.315379000744, 5278.81524146653, 5286.392064912546, 5318.042752304584, 5068.265101560253, 5109.0, 5109.105906466353, 5190.792368305263, 5206.505381988705, 5595.8880822383735, 5642.548596112301, 5643.6049476536655, 5673.120666296731, 5736.168255602956, 6193.4425901669065, 6203.7828675996125, 6204.348543402923, 6215.968920155416, 6232.283287421914, 6100.054491553772, 6195.535334849904, 6262.185915830958, 6294.716737746812, 6329.968350158266, 5810.651360918274, 5812.738426770763, 5837.445442682938, 5840.561957853178, 5843.2695913010875, 6266.530010249249, 6299.275583307886, 6309.495240380758, 6315.052742088724, 6316.557840951089, 5132.230165475208, 5927.2590926134235, 6023.247094113236, 6032.939670603238, 6085.756569737249, 5195.948040519622, 6125.272869866689, 6126.356732543108, 6170.290416602058, 6206.596571222856, 5310.230016647564, 6076.969615151985, 6159.3224745277985, 6164.506839452833, 6192.504599632019, 6137.9079313810325, 6164.321924588292, 6205.472534834499, 6216.968915155395, 6235.282942461584, 4799.712017278975, 4859.3682821233015, 4873.049755297683, 4881.4630390657, 4926.704397736147, 4397.890052748643, 4416.050549131952, 4447.0, 4457.0, 4461.353103799996, 5732.426757324318, 5749.080147176515, 5769.365369809318, 5801.448862358069, 5814.30228372598, 6092.51259899207, 6189.226346706661, 6194.132821405007, 6222.173636508157, 6290.0, 5747.827565173052, 5768.538516918637, 5778.191053252548, 5782.0, 5810.070388737866, 5402.486763757437, 5420.0, 5420.322459692538, 5431.755570999315, 5439.0, 6232.345603711545, 6269.7178626962805, 6308.684565771601, 6320.6839658016, 6350.2062242220645, 6270.216222972129, 6284.402981716729, 6301.779437719658, 6306.9684651576445, 6313.526485513697, 6247.937520624736, 6294.181756371642, 6313.589616674902, 6316.115743795965, 6318.431341179132, 5907.4683278505145, 6078.270607527125, 6081.361457046946, 6114.266288045463, 6145.539084568586, 6268.561200715906, 6271.3728627136925, 6273.843153921189, 6290.24517057956, 6301.275353334425, 6101.816945491642, 6110.694465276807, 6113.266408031065, 6114.2357205349335, 6115.969420152871, 4647.047355292089, 5917.822465326047, 6028.517718582415, 6088.330283668704, 6105.267367915879, 5931.851703707442, 5959.940400595939, 6024.578279520391, 6028.668423236764, 6031.638101713911, 6106.9083963739695, 6121.816345509642, 6130.969345153424, 6151.3541078186145, 6169.259688837368, 5732.16883551891, 5819.534437244925, 5847.44449277318, 5972.462478376967, 5975.342712301556], "value": 5670.910856791438, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.4593409447324, 483.62277423609584, 532.1512188060045, 541.5586297167808, 552.7043031977893, 30315.24211894701, 32098.037058888243, 40260.1947961041, 41662.58337416625, 41679.54121605744, 42548.57451425485, 42898.0, 42953.57046429535, 42964.785176074154, 43263.35104973428, 9663.130318271355, 9842.852357214648, 9851.605935762562, 9863.704088877344, 9876.061774131482, 9784.315097943147, 9907.950460247688, 9912.653057143001, 9916.553755081039, 9918.256130790183, 9869.259805514615, 9886.357386769872, 9939.900600994006, 9944.701658950207, 9945.60217591299, 9836.213102951746, 9873.259505537042, 9886.357386769872, 9890.653827116017, 9899.554520046615, 8397.6221070052, 9620.71137865865, 9757.756056098655, 9779.657711980046, 9789.510524473819, 40404.59595404038, 40638.59361406378, 40734.38898416526, 40775.184496310205, 40887.7955610223, 3425.143714071482, 3497.3704733148024, 3551.786892786441, 3606.9098272543133, 3853.3064048471265, 30768.53847192294, 31259.0, 31350.52974205389, 31903.0, 32620.836895815606, 2686.7716244119274, 3230.8061516308862, 3237.8057316560844, 3248.3016151527536, 3372.8313584320686, 376195.0, 377128.0, 377612.0, 377967.0, 380357.0, 55150.72424637851, 55512.72629100229, 55672.0, 55694.0, 55800.72099639476, 33024.50463243053, 33462.3307533848, 33610.0, 34517.82741086279, 34972.65027349694, 15966.323536028549, 16047.438339658056, 16084.356625735074, 16098.0, 16106.033637981758, 12315.93842030802, 12386.81419778704, 12394.194377365442, 12431.440585173688, 12435.44040518179, 18283.360082397052, 18284.817151828312, 18286.268549258148, 18549.721754173697, 18661.32048115676, 7528.0, 7630.427717921179, 7638.427117966175, 7674.961625191839, 7788.337991270805, 901.5176880368971, 953.6042542344987, 980.8136454073704, 1009.268280496634, 1025.74869157057, 27902.89616669151, 27910.0, 28503.71496285052, 29268.707312927025, 30055.69944300529, 888.6667499687617, 1030.6135199300263, 1081.6322450366913, 1107.9556817727362, 1114.5764609448358, 12506.374681265901, 15201.923990380199, 15377.461788837561, 15396.996780837064, 15859.682806343808, 674648.0, 683218.0, 685570.0, 685649.0, 687448.0, 47133.76433117812, 56369.0, 56780.43219567834, 56834.0, 57034.14448783272, 1238.789405801016, 1277.9169353992147, 1345.77121889281, 1357.966050848717, 1387.6392138044182, 29241.122766317043, 30796.692033079384, 30823.38353232878, 31040.844795775876, 31565.684343156274, 47058.29412558746, 47301.0, 47434.76282618565, 47439.52560474351, 47513.76243118762, 533897.0, 535734.0, 537241.0, 537332.0, 537802.0, 22903.19838805634, 25385.0, 25473.23580292594, 25962.0, 26016.8699156503, 44807.551924480336, 44968.100637986405, 46693.0, 47205.81985450391, 47605.28592071052, 31525.527117092803, 31923.84038079795, 35280.82359588288, 37363.43954840734, 38087.61912380841, 37286.62713372832, 37451.81274093612, 37602.683906063154, 37759.0, 38635.23883320464, 32328.35343293168, 33049.50425743664, 37909.0, 38001.23997519979, 38229.61770382261, 30842.691573083983, 33167.17082072871, 33885.0, 33893.98318050463, 33944.15139621529, 21932.574382665076, 24790.38024049413, 25113.74886251114, 25289.62065568981, 25936.61095083613], "value": 19217.53985472504, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.2610570014369, 376.0636016319364, 379.4952712891853, 384.02266232438444, 396.898790808344, 19392.806071939278, 20699.172033118695, 20779.584408311843, 20854.478638034037, 20971.0, 20239.344950661358, 20528.692069618966, 21060.78939210611, 21104.0503177357, 21389.144434222613, 5704.11586204139, 5706.971465142678, 5811.099279611667, 5886.735096920649, 5889.234399528055, 4126.133511962492, 4357.520672726014, 5748.166515855199, 5856.32652244993, 5867.911981320262, 4180.602842729936, 4266.509351424579, 6227.750889964419, 6538.509611779135, 6612.933870661304, 6423.747369262996, 6434.324395938406, 6543.083968244449, 6576.868836365643, 6657.667116644173, 5052.494750524955, 5670.319561652587, 5673.602847800656, 5676.886462270772, 5679.630823996427, 25140.0, 25825.483490330087, 25951.961921523118, 26013.95944162231, 26266.60600091, 2213.2917466410754, 2337.544178885118, 2431.0, 2775.5281602127484, 3152.7162555369896, 15361.692766144615, 15534.145621990787, 15824.050556966504, 16160.19199040055, 16202.594935126715, 1778.6887294723372, 1807.701729214678, 1864.3381599532242, 1900.1449347793548, 1936.8547358948135, 111984.0, 112496.43751781271, 115474.42262788716, 115699.0, 117457.0, 10390.064894159563, 10556.524956376828, 10688.51901664427, 10721.731956701145, 10843.024127828534, 11163.106951443864, 13177.670558236121, 13182.670433239246, 13198.934005329911, 13218.405171767101, 8207.261346478846, 8344.958275208584, 8358.707445239508, 8369.95815020921, 8510.063892971768, 8418.536980466011, 8452.78868028292, 8595.785105372415, 8619.353548483889, 8654.22112009922, 5860.501857342088, 5921.151251686352, 6289.5911765736, 6398.168238129107, 6405.167328247298, 1678.9432944642715, 1792.6504331655462, 1834.284628994693, 1842.68674325365, 1873.5105591055144, 665.5973136252594, 748.4461498491078, 879.3800370738614, 881.1893058386172, 934.8831396075491, 19929.60140797176, 22256.55486890253, 22283.44291392728, 22339.77660223377, 22458.55082898333, 946.4794363100267, 952.1288021460286, 957.7988622389377, 974.8050389922043, 979.808937257228, 8036.0, 11687.415629218505, 11822.763544729229, 11842.993345565552, 11845.933865951933, 611067.0, 617662.0, 617734.0, 617966.0, 637315.0, 101439.0, 134634.0, 140430.97845107704, 141138.0, 143340.28329858283, 993.4833886379042, 1006.6275478073013, 1022.192467950311, 1042.5517027678172, 1042.5829668132808, 18194.545136371697, 18973.71539426882, 19039.90480047591, 19097.039958806407, 19142.521436964187, 55850.44149558452, 55923.440765591826, 56699.71650141723, 57521.13718294145, 57949.71025145016, 83515.58242208751, 83776.58111709403, 83961.0, 84590.0, 84806.57596711977, 7167.0, 7188.8921666174, 7703.114141873532, 8028.759137225892, 8106.635201416068, 9686.46724430135, 12104.757904841677, 12431.875681243071, 12598.433070512032, 12861.485540578646, 11003.899610038932, 11068.034140696693, 11147.996680298813, 11157.21899467062, 11314.321140731583, 10769.784604308026, 10841.945790270998, 10935.17986151026, 11024.228304018641, 11346.092312614806, 10831.67504974852, 11143.944280278547, 11281.661550153509, 11290.322580645188, 11620.360880151673, 7764.145943946049, 7933.920660793549, 7937.523748575104, 8018.3184429324165, 8221.136780638186, 8242.711505097293, 10300.433476158883, 10497.422641754776, 10715.0, 10819.404932728776], "value": 9713.879101473944, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1484.1466156959748, 1531.9846801531985, 1680.428799072867, 1828.8536917046638, 1855.8051404602525, 8345.666173353065, 8877.733667989962, 8882.200601945831, 8888.466691998485, 8939.597718102685, 7792.181820908808, 7989.880151797728, 7991.9600401997905, 8098.55457949814, 8682.088380720028, 2109.778473260301, 2148.215901196064, 2314.36355002374, 2358.50471401006, 2391.1391898916463, 2355.305184970438, 2371.691680081587, 2378.3102900158933, 2378.9762102378927, 2380.059876348841, 2384.7972922301624, 2388.1880160745345, 2394.616861302192, 2394.916177933773, 2395.149721848745, 1996.8302694271003, 2380.0, 2381.821363397752, 2383.6067048936907, 2392.031227352925, 2331.3239160643393, 2346.343023953296, 2376.6553849691873, 2380.071772008918, 2380.47629521505, 8737.563121843947, 8864.556772161366, 8869.866951995726, 8881.0, 8881.866771998426, 5212.947870521284, 5349.812756553541, 5354.223637572569, 5356.598255130842, 5362.142057270857, 3526.770759900598, 3624.0577449862963, 3688.2623475305045, 3696.2792255510185, 3700.888973330804, 1050.1808589300358, 1087.9728006799894, 1111.6331610568493, 1116.9553217871276, 1118.3178261260625, 11608.187426880122, 11619.302841829518, 11621.0, 11628.94185529067, 11661.0088142508, 4618.538146185422, 4740.834070807574, 4891.584215341664, 4970.577500912391, 4973.626977976666, 9184.357095003419, 9229.492377919145, 9233.630654773733, 9251.629934802533, 9255.49094799779, 5297.5232229099565, 5297.973510132501, 5304.496072873071, 5307.734613269321, 5310.654807437504, 4458.866234012985, 4857.635677324215, 4900.387451568554, 4921.803127874917, 4966.950330496721, 3237.2554312508255, 3526.083218363244, 3718.758280711745, 3769.6607305342104, 3861.227754449121, 1176.3059794721155, 1180.2859270141441, 1183.319591235026, 1190.988090119105, 1193.4927655746274, 759.449399185597, 857.2670366836389, 903.6430609909049, 903.7876099116702, 951.471933077135, 4806.879828004258, 5572.606848287928, 5572.721363931787, 6078.665673388006, 6149.600275982137, 971.1745016735719, 984.675057231112, 989.8515222716507, 1059.7668512927144, 1077.6120596585224, 3451.188970591909, 3661.377565813818, 3927.3323534999117, 3939.625735555118, 4113.4446849675505, 10617.946910265398, 10908.454577271104, 10994.450277486254, 10997.12023038155, 11046.502907369028, 10926.235163538477, 10930.836037459445, 10939.12487001038, 10944.4527773611, 10956.671299861016, 8005.719799806979, 8147.9185208147155, 8173.0, 8315.212229370714, 8337.166283371615, 5790.62360946554, 5857.033589457692, 5861.794837180849, 5940.0, 6017.217761691128, 10014.098731114234, 10106.191504679755, 10186.08325250731, 10209.0, 10271.178305735673, 9828.213742900694, 10323.380597164194, 10404.034850067694, 10405.115565176742, 10437.739056523647, 6618.867622647616, 7116.25279345682, 7246.60143692102, 7251.456140789356, 7254.927450725425, 7892.145345843607, 8245.0, 8359.0, 8480.0, 8578.65685372578, 7405.888911666437, 7537.924620753723, 7547.396208303213, 7602.50583712057, 7675.385969122569, 7404.481686281909, 7408.62956852166, 7415.629218539159, 7443.292887175709, 7470.3276705096805, 6970.0, 6991.930080699128, 7065.929340706527, 7075.610841403773, 7092.680829362588, 4939.851804445872, 4971.9254211186135, 5001.124803159505, 5018.799248030184, 5086.338775959101, 4947.183714687037, 4982.078315511642, 5051.545360917535, 5102.846914592568, 5254.213567387107], "value": 4579.197745981315, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.29700826990415, 302.8183090145912, 310.3173019357414, 315.21511436523065, 316.4225288847852, 4265.594768496988, 4836.540528649782, 5105.651400355836, 5375.381831089426, 5376.118358574497, 4350.956490435095, 4487.596116349528, 4842.1768299389105, 4847.466778654345, 4945.752712364385, 3551.5738111426663, 3649.5802982656937, 4041.4948131483566, 4064.5732198119217, 4093.3450647896343, 3155.668654791248, 3205.4069997050506, 3432.7597068205237, 3581.9283614327696, 3800.3159431302356, 3618.710503159754, 3884.892805550419, 3935.5080614923136, 4057.655099316547, 4066.5360470230694, 3395.049386171876, 3736.9723326085273, 3987.202559488085, 3990.4413382126527, 4031.7984100794843, 2934.0611004478574, 3209.245827230622, 3479.930401391983, 3488.7034602058857, 3491.633378495272, 5200.92198617021, 5212.609054320903, 5235.214717792323, 5261.473852614746, 5267.736613169364, 4304.289792184286, 4445.999650078738, 4541.250693635546, 4613.215753321943, 4615.446146462446, 4991.550760431543, 5104.897902041976, 5556.944430555724, 5559.777608895639, 5632.239647647556, 3788.6021967693273, 3791.962080379216, 3827.9234415311817, 3846.8649899305874, 3861.5366156061173, 4757.028904794498, 4875.463698993072, 5446.700431476267, 5551.447073872725, 5677.261955945742, 3849.7882616455804, 4015.859444919414, 4028.91942161161, 4045.3325201342036, 4107.342825148022, 4108.103786830277, 4138.317177665712, 4145.66834653227, 4147.52303485097, 4149.8962525937095, 4433.113377324548, 4497.694943667823, 4696.506866779007, 4735.171345014609, 4735.881602959885, 5249.790008399697, 5599.608027438122, 5614.85962850932, 5648.350439699487, 5660.584853786553, 1230.6431134970937, 1304.0569551368583, 1368.1449094316054, 1389.1526169036879, 1391.867772561605, 803.9718609848627, 846.204567706354, 864.8097418567905, 866.3199388480012, 871.8796346694467, 4138.730982486129, 4308.46144231971, 4319.136172765459, 4343.1096625192395, 4412.977935110368, 4304.78476076195, 4735.4373056891145, 4748.881277968009, 4757.597413306258, 4850.563449289581, 3537.3809583322814, 4054.6483039837412, 4266.616004559604, 4282.700210985264, 4302.9569704303185, 2293.7018187635836, 2324.0006797077417, 2389.673731079268, 2559.6800399950007, 2576.458943621823, 4818.723268177673, 5047.899042019139, 5050.368703912011, 5064.5948324134715, 5066.6453348266, 4409.184300904343, 4442.933355999663, 4842.757862106881, 4846.854594362175, 4960.792447360962, 4138.627523522898, 4155.459790227251, 4267.314410807714, 4660.390181498372, 4863.756812159378, 4023.4970628671417, 4421.9216968024975, 4717.716936983792, 4739.502352253102, 4775.808967641256, 4532.660050496292, 4534.231649656571, 4628.94012164597, 4722.905541889074, 4848.854534363974, 4274.572542745701, 4653.371794807657, 4663.743494107721, 4688.953110468851, 5035.899282014266, 2973.464776340193, 3317.4526203176206, 3419.401604719214, 3456.9308613828084, 3466.9653303467653, 4098.774567398703, 4292.390353617394, 4564.977175114103, 4717.221658426459, 4863.862808488576, 4258.936115958325, 4311.956880431281, 4321.654267658642, 4476.597106260452, 4909.509049095205, 5675.31896172462, 5680.460356266148, 5695.294735001007, 5718.571107167062, 5773.624714393551, 4421.314696222091, 4452.0, 4883.389576302962, 5184.662996905189, 5581.581381396492, 4851.975740121276, 5195.415398303554, 5477.945220547744, 5641.548676105819, 5724.4561766632105, 5542.3903370630005, 5559.2217089606675, 5562.582806289625, 5572.554195664256, 5595.664260144405], "value": 3789.8016089742346, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.5413641655466, 723.7611588175906, 769.3229957637278, 774.9499428274688, 791.4815795653845, 14680.632984175401, 15836.970596911193, 15866.84133158668, 15916.045037297756, 15919.442819501306, 14064.15615063097, 14336.068155569881, 14860.925695371508, 15034.924825375885, 15080.396784128621, 841.9999999999985, 865.7489328094845, 866.1295398124876, 867.8741582470537, 869.8129902071054, 691.4641153106332, 713.6253466929877, 717.7595505505661, 867.822096470222, 873.1530415496958, 747.3946103656028, 749.0935967479356, 902.4224496322358, 938.2962777916563, 941.4821847983616, 928.3501548915718, 928.5403725156024, 929.4609126706528, 930.1210356213381, 930.4463844012802, 682.7234969837224, 682.9795106146773, 683.2791405067652, 683.5898460923456, 684.305429988562, 12134.332611706353, 14709.485168019068, 14713.779293310608, 15044.323005464781, 15068.397264109422, 14499.56501304952, 14991.475298364614, 15047.924760376129, 15090.849091509164, 15222.695546089128, 12078.577249796215, 12133.393330333536, 12226.327551984637, 12230.789948826474, 12267.57063502773, 3422.9731080675947, 3668.174660701321, 3842.9615703842883, 3865.9806700966315, 3910.7262491625597, 87633.0, 114881.4255928715, 115526.0, 115862.68274634544, 116668.0, 11176.888231117584, 11482.368469734078, 11607.129465289969, 11745.29528228309, 11791.11566632506, 23361.649575256386, 23385.532289354116, 23405.0, 23410.53178936412, 23438.179663712028, 6830.21452532965, 8013.998250218722, 8039.598020099089, 8269.95865020671, 8331.416800824005, 4345.717528360709, 4593.196190666687, 4651.720896746206, 4664.137134630104, 4711.905761884743, 3498.4927185557954, 3568.518250036212, 3659.158393569494, 3668.0, 3674.6325367463573, 649.6978904809287, 652.2499126005132, 656.579788935082, 656.7176114270909, 659.4197106546209, 540.5675459632279, 595.8361450601033, 613.6747523812387, 653.1509038250256, 693.6254422611734, 7424.888626670604, 8394.160583941679, 9068.775715278485, 9169.679061232959, 9295.442273463614, 532.6830535831174, 603.6136872401667, 621.5990785240487, 635.2821311917504, 667.893137098062, 3749.278793545968, 3775.8489660413825, 4294.914101717948, 4376.321670141133, 4396.164728701537, 83244.0, 83645.0, 85323.57338213269, 94261.58607620891, 136839.0, 128441.86981578838, 130606.34696826455, 132241.0, 134098.0, 135770.21148942513, 112120.43939780412, 112970.0, 114292.0, 114668.0, 114877.0, 8771.429857059271, 8812.339074569305, 8821.250193733604, 8886.155815197546, 9103.271738261055, 45390.77304613588, 60931.38357705287, 61492.77014459596, 61746.76506469755, 63974.04038939327, 62562.68718656377, 92589.5370523143, 96812.51593741986, 98907.0, 110846.89153108366, 9719.805603888024, 11118.0, 11497.712557186138, 11813.054955603702, 11838.82241766357, 12638.684032899251, 12656.620301390973, 12665.430055647703, 12842.807357889453, 13068.0, 11891.167618266638, 11974.782621635466, 12248.632541023784, 12323.0, 12478.126531142732, 10211.336263142872, 12213.633590992284, 12404.131710780159, 12569.99440044776, 12577.119601627799, 11656.242344247597, 12052.819207712068, 12081.395930203278, 12451.0, 12460.003199743818, 9393.906060939304, 9841.360311579725, 10046.497675116361, 10118.898811011795, 10250.38497690141, 8510.872336914827, 9400.05999400082, 9481.905180948102, 10245.282830201815, 10270.948645256725], "value": 7525.3135175670905, "unit": "score"}], "name": "dromaeo_css", "value": 7190.963301687308, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 113.0, 112.0, 109.0, 114.0, 112.0, 115.0, 113.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 106.0, 166.0, 107.0, 102.0, 127.0, 106.0, 140.0, 107.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 184.0, 184.0, 184.0, 181.0, 181.0, 181.0, 186.0, 184.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 60.0, 86.0, 59.0, 58.0, 67.0, 61.0, 82.0, 57.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 102.0, 71.0, 69.0, 68.0, 96.0, 70.0, 68.0, 102.0], "value": 80.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 91.0, 89.0, 91.0, 89.0, 86.0, 89.0, 87.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 107.0, 149.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 89.0, 70.0, 112.0, 68.0, 113.0, 137.0, 142.0, 85.0], "value": 98.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 57.0, 55.0, 58.0, 58.0, 57.0, 58.0, 56.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 75.0, 74.0, 71.0, 67.0, 68.0, 69.0, 69.0, 68.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 87.0, 86.0, 98.0, 102.0, 106.0, 79.0, 88.0, 82.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 139.0, 138.0, 123.0, 171.0, 151.0, 150.0, 159.0, 138.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 167.0, 164.0, 166.0, 166.0, 165.0, 169.0, 167.0, 167.0], "value": 166.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 66.0, 68.0, 81.0, 83.0, 84.0, 66.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1450.8999999999999, "alertThreshold": 2.0}]} 03:31:35 INFO - Return code: 0 03:31:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:31:35 INFO - # TBPL SUCCESS # 03:31:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:31:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:31:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:31:35 INFO - 2016-08-16 03:31:35.481 screenresolution[1663:9779] starting screenresolution argv=screenresolution get 03:31:35 INFO - 2016-08-16 03:31:35.484 screenresolution[1663:9779] Display 0: 1600x1200x32@60 03:31:35 INFO - 2016-08-16 03:31:35.490 screenresolution[1664:9782] starting screenresolution argv=screenresolution list 03:31:35 INFO - Available Modes on Display 0 03:31:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:31:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:31:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:31:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:31:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:31:35 INFO - Intel Iris: 03:31:35 INFO - Chipset Model: Intel Iris 03:31:35 INFO - Type: GPU 03:31:35 INFO - Bus: Built-In 03:31:35 INFO - VRAM (Dynamic, Max): 1536 MB 03:31:35 INFO - Vendor: Intel (0x8086) 03:31:35 INFO - Device ID: 0x0a2e 03:31:35 INFO - Revision ID: 0x0009 03:31:35 INFO - Displays: 03:31:35 INFO - Display: 03:31:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:31:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:31:35 INFO - Display Serial Number: 1600x1200 60 03:31:35 INFO - Main Display: Yes 03:31:35 INFO - Mirror: Off 03:31:35 INFO - Online: Yes 03:31:35 INFO - Rotation: Supported 03:31:35 INFO - Return code: 0 03:31:35 INFO - Running post-action listener: _resource_record_post_action 03:31:35 INFO - [mozharness: 2016-08-16 10:31:35.733870Z] Finished run-tests step (success) 03:31:35 INFO - Running post-run listener: _resource_record_post_run 03:31:35 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37200896; Write bytes: 704484352; Read time: 251; Write time: 1939 03:31:35 INFO - TinderboxPrint: CPU usage
53.9% 03:31:35 INFO - TinderboxPrint: I/O read bytes / time
37,200,896 / 251 03:31:35 INFO - TinderboxPrint: I/O write bytes / time
704,484,352 / 1,939 03:31:35 INFO - TinderboxPrint: CPU idle
2,237.8 (46.1%) 03:31:35 INFO - TinderboxPrint: CPU system
975.4 (20.1%) 03:31:35 INFO - TinderboxPrint: CPU user
1,636.5 (33.7%) 03:31:35 INFO - TinderboxPrint: Swap in / out
442,032,128 / 0 03:31:35 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 396343808; Write bytes: 390120960; Read time: 29577; Write time: 777 03:31:35 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35181568; Write bytes: 296386048; Read time: 232; Write time: 1112 03:31:36 INFO - Running post-run listener: _upload_blobber_files 03:31:36 INFO - Blob upload gear active. 03:31:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:31:36 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 03:31:36 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:31:36 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:31:36 INFO - (blobuploader) - INFO - Open directory for files ... 03:31:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:31:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:31:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:37 INFO - (blobuploader) - INFO - Done attempting. 03:31:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:31:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:31:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:38 INFO - (blobuploader) - INFO - Done attempting. 03:31:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:31:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:31:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:31:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:31:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:31:40 INFO - (blobuploader) - INFO - Done attempting. 03:31:40 INFO - (blobuploader) - INFO - Iteration through files over. 03:31:40 INFO - Return code: 0 03:31:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:31:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:31:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7e0d830ec9c327534267ed1296f6fdb7f816095ca85325e987b33db93ab1927dd34964495855c34875f11dc69a58c553f474dd587322d051fc828d7b0d1fc76a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e112126791409fa7d7cea1c05b2050d48cc1a87bbd22d133eb9f6fa462fee0009104183302205474e2b0a9076603df2b23e1139c7b23aae8c9013d6dc7a36fd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9a46621bb8393211438a84b37cfd5b5e9fd7c9f6257d6f901500ca3645e60f1eecf32c0f1b0a84be514b210d570cc9efe99b448fafee4e9b8d938333566ba8c2"} 03:31:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:31:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:31:40 INFO - Contents: 03:31:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7e0d830ec9c327534267ed1296f6fdb7f816095ca85325e987b33db93ab1927dd34964495855c34875f11dc69a58c553f474dd587322d051fc828d7b0d1fc76a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e112126791409fa7d7cea1c05b2050d48cc1a87bbd22d133eb9f6fa462fee0009104183302205474e2b0a9076603df2b23e1139c7b23aae8c9013d6dc7a36fd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9a46621bb8393211438a84b37cfd5b5e9fd7c9f6257d6f901500ca3645e60f1eecf32c0f1b0a84be514b210d570cc9efe99b448fafee4e9b8d938333566ba8c2"} 03:31:40 INFO - Running post-run listener: copy_logs_to_upload_dir 03:31:40 INFO - Copying logs to upload dir... 03:31:40 INFO - mkdir: /builds/slave/test/build/upload/logs 03:31:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.367768 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-16 03:31:41.047047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 03:31:41.051248) ========= 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.u86dsvcOLg/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.1plRLoCWZz/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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/Autoland/sha512/7e0d830ec9c327534267ed1296f6fdb7f816095ca85325e987b33db93ab1927dd34964495855c34875f11dc69a58c553f474dd587322d051fc828d7b0d1fc76a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e112126791409fa7d7cea1c05b2050d48cc1a87bbd22d133eb9f6fa462fee0009104183302205474e2b0a9076603df2b23e1139c7b23aae8c9013d6dc7a36fd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9a46621bb8393211438a84b37cfd5b5e9fd7c9f6257d6f901500ca3645e60f1eecf32c0f1b0a84be514b210d570cc9efe99b448fafee4e9b8d938333566ba8c2"} build_url:https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011295 build_url: 'https://queue.taskcluster.net/v1/task/DLaL_f19S12yIzZ54Nrqkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/7e0d830ec9c327534267ed1296f6fdb7f816095ca85325e987b33db93ab1927dd34964495855c34875f11dc69a58c553f474dd587322d051fc828d7b0d1fc76a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e112126791409fa7d7cea1c05b2050d48cc1a87bbd22d133eb9f6fa462fee0009104183302205474e2b0a9076603df2b23e1139c7b23aae8c9013d6dc7a36fd2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/9a46621bb8393211438a84b37cfd5b5e9fd7c9f6257d6f901500ca3645e60f1eecf32c0f1b0a84be514b210d570cc9efe99b448fafee4e9b8d938333566ba8c2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 03:31:41.087867) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 03:31:41.088189) ========= 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.u86dsvcOLg/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.1plRLoCWZz/Listeners TMPDIR=/var/folders/_4/5kgy7ct120n7q2p9437gq4mw00000w/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.005101 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 03:31:41.137430) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 03:31:41.137751) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 03:31:41.138070) ========= ========= Total master_lag: 0.20 =========