builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0305 starttime: 1472771544.9 results: success (0) buildid: 20160901133621 builduid: 572be86af11f4c8fbf18ead5edeecc43 revision: fd96f3fee9754562392a3877464b10aa90f82b71 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:24.900802) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:24.901257) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:24.901541) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:24.924997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:24.925285) ========= 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.89QxAKv03E/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.E9LB1smlKs/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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-09-01 16:12:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.13M=0.001s 2016-09-01 16:12:25 (8.13 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126339 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:25.074298) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:25.074667) ========= 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.89QxAKv03E/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.E9LB1smlKs/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.019956 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:25.122670) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-01 16:12:25.123059) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev fd96f3fee9754562392a3877464b10aa90f82b71 --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 fd96f3fee9754562392a3877464b10aa90f82b71 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.89QxAKv03E/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.E9LB1smlKs/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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-09-01 16:12:25,202 truncating revision to first 12 chars 2016-09-01 16:12:25,202 Setting DEBUG logging. 2016-09-01 16:12:25,202 attempt 1/10 2016-09-01 16:12:25,203 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/fd96f3fee975?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 16:12:27,357 unpacking tar archive at: autoland-fd96f3fee975/testing/mozharness/ program finished with exit code 0 elapsedTime=2.417223 ========= master_lag: 1.96 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2016-09-01 16:12:29.501962) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:29.502294) ========= script_repo_revision: fd96f3fee9754562392a3877464b10aa90f82b71 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:29.502686) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:29.502949) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 16:12:30.142149) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-01 16:12:30.142903) ========= /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.89QxAKv03E/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.E9LB1smlKs/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:12:30 INFO - MultiFileLogger online at 20160901 16:12:30 in /builds/slave/test 16:12:30 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 16:12:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:12:30 INFO - {'append_to_log': False, 16:12:30 INFO - 'base_work_dir': '/builds/slave/test', 16:12:30 INFO - 'blob_upload_branch': 'Autoland', 16:12:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:12:30 INFO - 'branch': 'Autoland', 16:12:30 INFO - 'buildbot_json_path': 'buildprops.json', 16:12:30 INFO - 'config_files': ('talos/mac_config.py',), 16:12:30 INFO - 'default_actions': ('clobber', 16:12:30 INFO - 'read-buildbot-config', 16:12:30 INFO - 'download-and-extract', 16:12:30 INFO - 'populate-webroot', 16:12:30 INFO - 'create-virtualenv', 16:12:30 INFO - 'install', 16:12:30 INFO - 'run-tests'), 16:12:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:12:30 INFO - 'download_minidump_stackwalk': True, 16:12:30 INFO - 'download_symbols': 'ondemand', 16:12:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:12:30 INFO - 'tooltool.py': '/tools/tooltool.py', 16:12:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:12:30 INFO - '/tools/misc-python/virtualenv.py')}, 16:12:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:12:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:12:30 INFO - 'installer_path': 'installer.exe', 16:12:30 INFO - 'log_level': 'info', 16:12:30 INFO - 'log_name': 'talos', 16:12:30 INFO - 'log_to_console': True, 16:12:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:12:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:12:30 INFO - 'opt_config_files': (), 16:12:30 INFO - 'pip_index': False, 16:12:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:12:30 INFO - 'cmd': ('bash', 16:12:30 INFO - '-c', 16:12:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:12:30 INFO - 'enabled': True, 16:12:30 INFO - 'halt_on_failure': False, 16:12:30 INFO - 'name': 'check_screen_resolution'},), 16:12:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:12:30 INFO - 'cmd': ('bash', 16:12:30 INFO - '-c', 16:12:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:12:30 INFO - 'enabled': True, 16:12:30 INFO - 'halt_on_failure': False, 16:12:30 INFO - 'name': 'check_screen_resolution'},), 16:12:30 INFO - 'run_cmd_checks_enabled': True, 16:12:30 INFO - 'sps_profile': False, 16:12:30 INFO - 'sps_profile_interval': 0, 16:12:30 INFO - 'suite': 'dromaeojs', 16:12:30 INFO - 'system_bits': '32', 16:12:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:12:30 INFO - 'title': 't-yosemite-r7-0305', 16:12:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:12:30 INFO - 'use_talos_json': True, 16:12:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:12:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:12:30 INFO - 'work_dir': 'build'} 16:12:30 INFO - [mozharness: 2016-09-01 23:12:30.331803Z] Running clobber step. 16:12:30 INFO - Running pre-action listener: _resource_record_pre_action 16:12:30 INFO - Running main action method: clobber 16:12:30 INFO - rmtree: /builds/slave/test/build 16:12:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:12:31 INFO - Running post-action listener: _resource_record_post_action 16:12:31 INFO - [mozharness: 2016-09-01 23:12:31.719952Z] Finished clobber step (success) 16:12:31 INFO - [mozharness: 2016-09-01 23:12:31.720139Z] Running read-buildbot-config step. 16:12:31 INFO - Running pre-action listener: _resource_record_pre_action 16:12:31 INFO - Running main action method: read_buildbot_config 16:12:31 INFO - Using buildbot properties: 16:12:31 INFO - { 16:12:31 INFO - "project": "", 16:12:31 INFO - "product": "firefox", 16:12:31 INFO - "script_repo_revision": "production", 16:12:31 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:12:31 INFO - "repository": "", 16:12:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:12:31 INFO - "buildid": "20160901133621", 16:12:31 INFO - "pgo_build": "False", 16:12:31 INFO - "basedir": "/builds/slave/test", 16:12:31 INFO - "buildnumber": 214, 16:12:31 INFO - "slavename": "t-yosemite-r7-0305", 16:12:31 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 16:12:31 INFO - "platform": "yosemite_r7", 16:12:31 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:12:31 INFO - "branch": "autoland", 16:12:31 INFO - "repo_path": "integration/autoland", 16:12:31 INFO - "revision": "fd96f3fee9754562392a3877464b10aa90f82b71", 16:12:31 INFO - "stage_platform": "macosx64", 16:12:31 INFO - "builduid": "572be86af11f4c8fbf18ead5edeecc43", 16:12:31 INFO - "slavebuilddir": "test" 16:12:31 INFO - } 16:12:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 16:12:31 INFO - Running post-action listener: _resource_record_post_action 16:12:31 INFO - [mozharness: 2016-09-01 23:12:31.723357Z] Finished read-buildbot-config step (success) 16:12:31 INFO - [mozharness: 2016-09-01 23:12:31.723494Z] Running download-and-extract step. 16:12:31 INFO - Running pre-action listener: _resource_record_pre_action 16:12:31 INFO - Running main action method: download_and_extract 16:12:31 INFO - mkdir: /builds/slave/test/build/tests 16:12:31 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')]} 16:12:31 INFO - trying https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 16:12:31 INFO - Downloading https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:33 INFO - Downloaded 1199 bytes. 16:12:33 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 16:12:33 INFO - Using the following test package requirements: 16:12:33 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:12:33 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:33 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 16:12:33 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:33 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 16:12:33 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:33 INFO - u'jsshell-mac.zip'], 16:12:33 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:33 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 16:12:33 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 16:12:33 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:33 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 16:12:33 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:33 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 16:12:33 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:33 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 16:12:33 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 16:12:33 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 16:12:33 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:12:33 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')]} 16:12:33 INFO - trying https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:12:33 INFO - Downloading https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:34 INFO - Downloaded 16399331 bytes. 16:12:34 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:12:40 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 16:12:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:12:40 INFO - trying https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 16:12:40 INFO - Downloading https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:41 INFO - Downloaded 16399331 bytes. 16:12:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 16:12:48 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')]} 16:12:48 INFO - trying https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 16:12:48 INFO - Downloading https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:49 INFO - Downloaded 14413998 bytes. 16:12:49 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 16:12:50 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')]} 16:12:50 INFO - trying https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:12:50 INFO - Downloading https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 16:12:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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 16:12:53 INFO - Downloaded 111579921 bytes. 16:12:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:12:53 INFO - mkdir: /builds/slave/test/properties 16:12:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:12:53 INFO - Writing to file /builds/slave/test/properties/build_url 16:12:53 INFO - Contents: 16:12:53 INFO - build_url:https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 16:12:53 INFO - Running post-action listener: _resource_record_post_action 16:12:53 INFO - Running post-action listener: set_extra_try_arguments 16:12:53 INFO - [mozharness: 2016-09-01 23:12:53.933079Z] Finished download-and-extract step (success) 16:12:53 INFO - [mozharness: 2016-09-01 23:12:53.933217Z] Running populate-webroot step. 16:12:53 INFO - Running pre-action listener: _resource_record_pre_action 16:12:53 INFO - Running main action method: populate_webroot 16:12:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:12:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:12:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:12:53 INFO - '--tppagecycles', 16:12:53 INFO - '7'], 16:12:53 INFO - 'tests': ['tsvgm']}}, 16:12:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:12:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:12:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:12:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:12:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:53 INFO - 'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:12:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:53 INFO - 'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['damp', 'tps']}, 16:12:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:53 INFO - 'tests': ['damp', 'tps']}, 16:12:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['dromaeo_dom']}, 16:12:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:12:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['basic_compositor_video']}, 16:12:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:12:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['a11yr', 16:12:53 INFO - 'ts_paint', 16:12:53 INFO - 'tpaint', 16:12:53 INFO - 'sessionrestore', 16:12:53 INFO - 'sessionrestore_no_auto_restore', 16:12:53 INFO - 'tabpaint']}, 16:12:53 INFO - 'other-e10s': {'tests': ['a11yr', 16:12:53 INFO - 'ts_paint', 16:12:53 INFO - 'tpaint', 16:12:53 INFO - 'sessionrestore', 16:12:53 INFO - 'sessionrestore_no_auto_restore', 16:12:53 INFO - 'tabpaint']}, 16:12:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:12:53 INFO - 'ts_paint', 16:12:53 INFO - 'tpaint', 16:12:53 INFO - 'sessionrestore', 16:12:53 INFO - 'sessionrestore_no_auto_restore', 16:12:53 INFO - 'tabpaint']}, 16:12:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:12:53 INFO - 'ts_paint', 16:12:53 INFO - 'tpaint', 16:12:53 INFO - 'sessionrestore', 16:12:53 INFO - 'sessionrestore_no_auto_restore', 16:12:53 INFO - 'tabpaint']}, 16:12:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['a11yr', 16:12:53 INFO - 'ts_paint', 16:12:53 INFO - 'tpaint', 16:12:53 INFO - 'sessionrestore', 16:12:53 INFO - 'sessionrestore_no_auto_restore', 16:12:53 INFO - 'tabpaint']}, 16:12:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['a11yr', 16:12:53 INFO - 'ts_paint', 16:12:53 INFO - 'tpaint', 16:12:53 INFO - 'sessionrestore', 16:12:53 INFO - 'sessionrestore_no_auto_restore', 16:12:53 INFO - 'tabpaint']}, 16:12:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['tsvgx', 16:12:53 INFO - 'tsvgr_opacity', 16:12:53 INFO - 'tart', 16:12:53 INFO - 'tscrollx', 16:12:53 INFO - 'cart']}, 16:12:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:12:53 INFO - 'tsvgr_opacity', 16:12:53 INFO - 'tart', 16:12:53 INFO - 'tscrollx', 16:12:53 INFO - 'cart']}, 16:12:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:53 INFO - 'talos_options': ['--disable-e10s'], 16:12:53 INFO - 'tests': ['tp5o']}, 16:12:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:53 INFO - 'tests': ['tp5o']}, 16:12:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:53 INFO - 'talos_options': ['--disable-e10s', 16:12:53 INFO - '--xperf_path', 16:12:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:53 INFO - 'tests': ['tp5n']}, 16:12:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:12:53 INFO - 'talos_options': ['--xperf_path', 16:12:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:12:53 INFO - 'tests': ['tp5n']}}, 16:12:53 INFO - 'talos.zip': {'path': '', 16:12:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:12:53 INFO - Running post-action listener: _resource_record_post_action 16:12:53 INFO - [mozharness: 2016-09-01 23:12:53.951822Z] Finished populate-webroot step (success) 16:12:53 INFO - [mozharness: 2016-09-01 23:12:53.951953Z] Running create-virtualenv step. 16:12:53 INFO - Running pre-action listener: _resource_record_pre_action 16:12:53 INFO - Running main action method: create_virtualenv 16:12:53 INFO - Creating virtualenv /builds/slave/test/build/venv 16:12:53 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 16:12:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:12:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:12:54 INFO - Using real prefix '/tools/python27' 16:12:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:12:54 INFO - Installing distribute.............................................................................................................................................................................................done. 16:12:57 INFO - Installing pip.................done. 16:12:57 INFO - Return code: 0 16:12:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:57 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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:12:57 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 16:12:57 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 16:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:12:57 INFO - 'HOME': '/Users/cltbld', 16:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:12:57 INFO - 'LOGNAME': 'cltbld', 16:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:57 INFO - 'MOZ_NO_REMOTE': '1', 16:12:57 INFO - 'NO_EM_RESTART': '1', 16:12:57 INFO - 'PAGER': '/bin/cat', 16:12:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:57 INFO - 'PWD': '/builds/slave/test', 16:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:12:57 INFO - 'SHELL': '/bin/bash', 16:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:12:57 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:12:57 INFO - 'USER': 'cltbld', 16:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:57 INFO - 'XPC_FLAGS': '0x0', 16:12:57 INFO - 'XPC_SERVICE_NAME': '0', 16:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:57 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 16:12:57 INFO - Cleaning up... 16:12:57 INFO - Return code: 0 16:12:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:12: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')]} 16:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12: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')]} 16:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:12:57 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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:12:57 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 16:12:57 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 16:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:12:57 INFO - 'HOME': '/Users/cltbld', 16:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:12:57 INFO - 'LOGNAME': 'cltbld', 16:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:12:57 INFO - 'MOZ_NO_REMOTE': '1', 16:12:57 INFO - 'NO_EM_RESTART': '1', 16:12:57 INFO - 'PAGER': '/bin/cat', 16:12:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:12:57 INFO - 'PWD': '/builds/slave/test', 16:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:12:57 INFO - 'SHELL': '/bin/bash', 16:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:12:57 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:12:57 INFO - 'USER': 'cltbld', 16:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:12:57 INFO - 'XPC_FLAGS': '0x0', 16:12:57 INFO - 'XPC_SERVICE_NAME': '0', 16:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:12:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:12:58 INFO - Downloading/unpacking psutil>=3.1.1 16:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:12:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:12:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:12:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:13:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:01 INFO - Installing collected packages: psutil 16:13:01 INFO - Running setup.py install for psutil 16:13:01 INFO - building 'psutil._psutil_osx' extension 16:13:01 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 16:13:02 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 16:13:02 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 16:13:02 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 16:13:02 INFO - building 'psutil._psutil_posix' extension 16:13:02 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 16:13:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:13:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:13:02 INFO - ^ 16:13:02 INFO - 1 warning generated. 16:13:02 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 16:13:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:13:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:13:02 INFO - Successfully installed psutil 16:13:02 INFO - Cleaning up... 16:13:02 INFO - Return code: 0 16:13:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:13: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')]} 16:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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')]} 16:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:13: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:13: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 mozsystemmonitor==0.3 16:13:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:13:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:02 INFO - 'HOME': '/Users/cltbld', 16:13:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:02 INFO - 'LOGNAME': 'cltbld', 16:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:02 INFO - 'MOZ_NO_REMOTE': '1', 16:13:02 INFO - 'NO_EM_RESTART': '1', 16:13:02 INFO - 'PAGER': '/bin/cat', 16:13:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:02 INFO - 'PWD': '/builds/slave/test', 16:13:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:02 INFO - 'SHELL': '/bin/bash', 16:13:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:13:02 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:02 INFO - 'USER': 'cltbld', 16:13:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:02 INFO - 'XPC_FLAGS': '0x0', 16:13:02 INFO - 'XPC_SERVICE_NAME': '0', 16:13:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:13:05 INFO - Installing collected packages: mozsystemmonitor 16:13:05 INFO - Running setup.py install for mozsystemmonitor 16:13:05 INFO - Successfully installed mozsystemmonitor 16:13:05 INFO - Cleaning up... 16:13:05 INFO - Return code: 0 16:13:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:13: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')]} 16:13:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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')]} 16:13:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13: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', '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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:13: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:13: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 jsonschema==2.5.1 16:13:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:13:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:05 INFO - 'HOME': '/Users/cltbld', 16:13:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:05 INFO - 'LOGNAME': 'cltbld', 16:13:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:05 INFO - 'MOZ_NO_REMOTE': '1', 16:13:05 INFO - 'NO_EM_RESTART': '1', 16:13:05 INFO - 'PAGER': '/bin/cat', 16:13:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:05 INFO - 'PWD': '/builds/slave/test', 16:13:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:05 INFO - 'SHELL': '/bin/bash', 16:13:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:13:05 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:05 INFO - 'USER': 'cltbld', 16:13:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:05 INFO - 'XPC_FLAGS': '0x0', 16:13:05 INFO - 'XPC_SERVICE_NAME': '0', 16:13:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:05 INFO - Downloading/unpacking jsonschema==2.5.1 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:13:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:13:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:13:08 INFO - Downloading functools32-3.2.3-2.tar.gz 16:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:13:08 INFO - warning: no files found matching '*.txt' 16:13:08 INFO - no previously-included directories found matching 'build' 16:13:08 INFO - no previously-included directories found matching 'dist' 16:13:08 INFO - no previously-included directories found matching '.git*' 16:13:08 INFO - Installing collected packages: jsonschema, functools32 16:13:08 INFO - Running setup.py install for functools32 16:13:08 INFO - warning: no files found matching '*.txt' 16:13:08 INFO - no previously-included directories found matching 'build' 16:13:08 INFO - no previously-included directories found matching 'dist' 16:13:08 INFO - no previously-included directories found matching '.git*' 16:13:08 INFO - Successfully installed jsonschema functools32 16:13:08 INFO - Cleaning up... 16:13:08 INFO - Return code: 0 16:13:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:13:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:13:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:13:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:08 INFO - 'HOME': '/Users/cltbld', 16:13:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:08 INFO - 'LOGNAME': 'cltbld', 16:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:08 INFO - 'MOZ_NO_REMOTE': '1', 16:13:08 INFO - 'NO_EM_RESTART': '1', 16:13:08 INFO - 'PAGER': '/bin/cat', 16:13:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:08 INFO - 'PWD': '/builds/slave/test', 16:13:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:08 INFO - 'SHELL': '/bin/bash', 16:13:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:13:08 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:08 INFO - 'USER': 'cltbld', 16:13:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:08 INFO - 'XPC_FLAGS': '0x0', 16:13:08 INFO - 'XPC_SERVICE_NAME': '0', 16:13:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:13:09 INFO - Cleaning up... 16:13:09 INFO - Return code: 0 16:13:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:09 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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:13:09 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 16:13:09 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 16:13:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:13:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:09 INFO - 'HOME': '/Users/cltbld', 16:13:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:09 INFO - 'LOGNAME': 'cltbld', 16:13:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:09 INFO - 'MOZ_NO_REMOTE': '1', 16:13:09 INFO - 'NO_EM_RESTART': '1', 16:13:09 INFO - 'PAGER': '/bin/cat', 16:13:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:09 INFO - 'PWD': '/builds/slave/test', 16:13:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:09 INFO - 'SHELL': '/bin/bash', 16:13:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:13:09 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:09 INFO - 'USER': 'cltbld', 16:13:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:09 INFO - 'XPC_FLAGS': '0x0', 16:13:09 INFO - 'XPC_SERVICE_NAME': '0', 16:13:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:09 INFO - Downloading/unpacking blobuploader==1.2.4 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13: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 16:13:11 INFO - Downloading blobuploader-1.2.4.tar.gz 16:13:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:13:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:13:11 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 16:13:11 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 16:13:11 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 16:13:11 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 16:13:11 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 16:13:11 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 16:13:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:13:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:13:12 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 16:13:12 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 16:13:12 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 16:13:12 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 16:13:12 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 16:13:12 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 16:13:13 INFO - Downloading docopt-0.6.1.tar.gz 16:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:13:13 INFO - Installing collected packages: blobuploader, requests, docopt 16:13:13 INFO - Running setup.py install for blobuploader 16:13:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:13:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:13:13 INFO - Running setup.py install for requests 16:13:13 INFO - Running setup.py install for docopt 16:13:14 INFO - Successfully installed blobuploader requests docopt 16:13:14 INFO - Cleaning up... 16:13:14 INFO - Return code: 0 16:13:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:14 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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:13:14 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 16:13:14 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 16:13:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:13:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:14 INFO - 'HOME': '/Users/cltbld', 16:13:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:14 INFO - 'LOGNAME': 'cltbld', 16:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:14 INFO - 'MOZ_NO_REMOTE': '1', 16:13:14 INFO - 'NO_EM_RESTART': '1', 16:13:14 INFO - 'PAGER': '/bin/cat', 16:13:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:14 INFO - 'PWD': '/builds/slave/test', 16:13:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:14 INFO - 'SHELL': '/bin/bash', 16:13:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:13:14 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:14 INFO - 'USER': 'cltbld', 16:13:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:14 INFO - 'XPC_FLAGS': '0x0', 16:13:14 INFO - 'XPC_SERVICE_NAME': '0', 16:13:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:14 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-CE2cBX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:14 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-ZKyUBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:14 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-kZTcnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:14 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-3o4ZBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:14 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-GhC2ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-tWvN97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-a652ZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-FOKUvG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-jpPGVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-tH7dZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-8zFB62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-72yHlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-lfe8GM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-gR66bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:15 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-paNn7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:13:16 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-cahJQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:13:16 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-SDOZ9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:13:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:13:16 INFO - Running setup.py install for manifestparser 16:13:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:13:16 INFO - Running setup.py install for mozcrash 16:13:16 INFO - Running setup.py install for mozdebug 16:13:16 INFO - Running setup.py install for mozdevice 16:13:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:13:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:13:16 INFO - Running setup.py install for mozfile 16:13:16 INFO - Running setup.py install for mozhttpd 16:13:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:13:17 INFO - Running setup.py install for mozinfo 16:13:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:13:17 INFO - Running setup.py install for mozInstall 16:13:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:13:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:13:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:13:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:13:17 INFO - Running setup.py install for mozleak 16:13:17 INFO - Running setup.py install for mozlog 16:13:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:13:17 INFO - Running setup.py install for moznetwork 16:13:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:13:17 INFO - Running setup.py install for mozprocess 16:13:18 INFO - Running setup.py install for mozprofile 16:13:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:13:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:13:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:13:18 INFO - Running setup.py install for mozrunner 16:13:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:13:18 INFO - Running setup.py install for mozscreenshot 16:13:18 INFO - Running setup.py install for moztest 16:13:18 INFO - Running setup.py install for mozversion 16:13:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:13:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:13:18 INFO - Cleaning up... 16:13:18 INFO - Return code: 0 16:13:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:18 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')]} 16:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:18 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')]} 16:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:18 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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:13:18 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 16:13:18 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 16:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:18 INFO - 'HOME': '/Users/cltbld', 16:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:18 INFO - 'LOGNAME': 'cltbld', 16:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:18 INFO - 'MOZ_NO_REMOTE': '1', 16:13:18 INFO - 'NO_EM_RESTART': '1', 16:13:18 INFO - 'PAGER': '/bin/cat', 16:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:18 INFO - 'PWD': '/builds/slave/test', 16:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:18 INFO - 'SHELL': '/bin/bash', 16:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:13:18 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:18 INFO - 'USER': 'cltbld', 16:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:18 INFO - 'XPC_FLAGS': '0x0', 16:13:18 INFO - 'XPC_SERVICE_NAME': '0', 16:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-b6uwMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:13:19 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)) 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-o1zEXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:13:19 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)) 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-N3xuEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:13:19 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)) 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-a9eloC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:13:19 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)) 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-nk39Qw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:13:19 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)) 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-YcdAwg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:13:19 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)) 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-7fC8Hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:13:19 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)) 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-0hQ2VP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:13:19 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)) 16:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:13:19 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-RWHJN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:13:20 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)) 16:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:13:20 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-aExpao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:13:20 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)) 16:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:13:20 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-6p2xTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:13:20 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)) 16:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:13:20 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-PMrhKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:13:20 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)) 16:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:13:20 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-CAGPyw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:13:20 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)) 16:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:13:20 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-AIGQaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:13:20 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)) 16:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:20 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-5JX3ee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:13:20 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)) 16:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:13:20 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-hnje5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:13:20 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)) 16:13:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:13:20 INFO - Running setup.py (path:/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/pip-WT7xPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:13:20 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)) 16:13:20 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)) 16:13:20 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)) 16:13:20 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)) 16:13:20 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)) 16:13:20 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)) 16:13:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:13:20 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 16:13:20 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 16:13:20 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 16:13:20 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 16:13:20 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 16:13:20 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 16:13:23 INFO - Downloading blessings-1.6.tar.gz 16:13:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:13:23 INFO - Installing collected packages: blessings 16:13:23 INFO - Running setup.py install for blessings 16:13:24 INFO - Successfully installed blessings 16:13:24 INFO - Cleaning up... 16:13:24 INFO - Return code: 0 16:13:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:13:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:24 INFO - Reading from file tmpfile_stdout 16:13:24 INFO - Current package versions: 16:13:24 INFO - blessings == 1.6 16:13:24 INFO - blobuploader == 1.2.4 16:13:24 INFO - docopt == 0.6.1 16:13:24 INFO - functools32 == 3.2.3-2 16:13:24 INFO - jsonschema == 2.5.1 16:13:24 INFO - manifestparser == 1.1 16:13:24 INFO - mozInstall == 1.12 16:13:24 INFO - mozcrash == 0.17 16:13:24 INFO - mozdebug == 0.1 16:13:24 INFO - mozdevice == 0.48 16:13:24 INFO - mozfile == 1.2 16:13:24 INFO - mozhttpd == 0.7 16:13:24 INFO - mozinfo == 0.9 16:13:24 INFO - mozleak == 0.1 16:13:24 INFO - mozlog == 3.3 16:13:24 INFO - moznetwork == 0.27 16:13:24 INFO - mozprocess == 0.23 16:13:24 INFO - mozprofile == 0.28 16:13:24 INFO - mozrunner == 6.12 16:13:24 INFO - mozscreenshot == 0.1 16:13:24 INFO - mozsystemmonitor == 0.3 16:13:24 INFO - moztest == 0.7 16:13:24 INFO - mozversion == 1.4 16:13:24 INFO - psutil == 3.1.1 16:13:24 INFO - requests == 1.2.3 16:13:24 INFO - wsgiref == 0.1.2 16:13:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:13:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:24 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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:13:24 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 16:13:24 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 16:13:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:13:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:24 INFO - 'HOME': '/Users/cltbld', 16:13:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:24 INFO - 'LOGNAME': 'cltbld', 16:13:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:24 INFO - 'MOZ_NO_REMOTE': '1', 16:13:24 INFO - 'NO_EM_RESTART': '1', 16:13:24 INFO - 'PAGER': '/bin/cat', 16:13:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:24 INFO - 'PWD': '/builds/slave/test', 16:13:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:24 INFO - 'SHELL': '/bin/bash', 16:13:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:13:24 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:24 INFO - 'USER': 'cltbld', 16:13:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:24 INFO - 'XPC_FLAGS': '0x0', 16:13:24 INFO - 'XPC_SERVICE_NAME': '0', 16:13:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 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)) 16:13:25 INFO - Cleaning up... 16:13:25 INFO - Return code: 0 16:13:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:13:25 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')]} 16:13:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:13:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:25 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')]} 16:13:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:13:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:13:25 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 0x1047c8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f91dac490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, '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.89QxAKv03E/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.E9LB1smlKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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 16:13:25 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 16:13:25 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 16:13:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:13:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:13:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:13:25 INFO - 'HOME': '/Users/cltbld', 16:13:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:13:25 INFO - 'LOGNAME': 'cltbld', 16:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:13:25 INFO - 'MOZ_NO_REMOTE': '1', 16:13:25 INFO - 'NO_EM_RESTART': '1', 16:13:25 INFO - 'PAGER': '/bin/cat', 16:13:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:13:25 INFO - 'PWD': '/builds/slave/test', 16:13:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:13:25 INFO - 'SHELL': '/bin/bash', 16:13:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:13:25 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:13:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:13:25 INFO - 'USER': 'cltbld', 16:13:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:13:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:13:25 INFO - 'XPC_FLAGS': '0x0', 16:13:25 INFO - 'XPC_SERVICE_NAME': '0', 16:13:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:13:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:13:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:13:25 INFO - Cleaning up... 16:13:25 INFO - Return code: 0 16:13:25 INFO - Running post-action listener: _resource_record_post_action 16:13:25 INFO - Running post-action listener: _start_resource_monitoring 16:13:25 INFO - Starting resource monitoring. 16:13:25 INFO - [mozharness: 2016-09-01 23:13:25.436466Z] Finished create-virtualenv step (success) 16:13:25 INFO - [mozharness: 2016-09-01 23:13:25.437465Z] Running install step. 16:13:25 INFO - Running pre-action listener: _resource_record_pre_action 16:13:25 INFO - Running main action method: install 16:13:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:13:25 INFO - Reading from file tmpfile_stdout 16:13:25 INFO - Detecting whether we're running mozinstall >=1.0... 16:13:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:13:25 INFO - Reading from file tmpfile_stdout 16:13:25 INFO - Output received: 16:13:25 INFO - Usage: mozinstall [options] installer 16:13:25 INFO - Options: 16:13:25 INFO - -h, --help show this help message and exit 16:13:25 INFO - -d DEST, --destination=DEST 16:13:25 INFO - Directory to install application into. [default: 16:13:25 INFO - "/builds/slave/test"] 16:13:25 INFO - --app=APP Application being installed. [default: firefox] 16:13:25 INFO - mkdir: /builds/slave/test/build/application 16:13:25 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'] 16:13:25 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 16:13:59 INFO - Reading from file tmpfile_stdout 16:13:59 INFO - Output received: 16:13:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:13:59 INFO - Running post-action listener: _resource_record_post_action 16:13:59 INFO - [mozharness: 2016-09-01 23:13:59.725798Z] Finished install step (success) 16:13:59 INFO - [mozharness: 2016-09-01 23:13:59.725938Z] Running run-tests step. 16:13:59 INFO - Running pre-action listener: _resource_record_pre_action 16:13:59 INFO - Running main action method: run_tests 16:13:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:13:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:13:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:13:59 INFO - 2016-09-01 16:13:59.740 screenresolution[1931:18958] starting screenresolution argv=screenresolution get 16:13:59 INFO - 2016-09-01 16:13:59.743 screenresolution[1931:18958] Display 0: 1600x1200x32@60 16:13:59 INFO - 2016-09-01 16:13:59.749 screenresolution[1932:18960] starting screenresolution argv=screenresolution list 16:13:59 INFO - Available Modes on Display 0 16:13:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:13:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:13:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:13:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:13:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:13:59 INFO - Intel Iris: 16:13:59 INFO - Chipset Model: Intel Iris 16:13:59 INFO - Type: GPU 16:13:59 INFO - Bus: Built-In 16:13:59 INFO - VRAM (Dynamic, Max): 1536 MB 16:13:59 INFO - Vendor: Intel (0x8086) 16:13:59 INFO - Device ID: 0x0a2e 16:13:59 INFO - Revision ID: 0x0009 16:13:59 INFO - Displays: 16:13:59 INFO - Display: 16:13:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:13:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:13:59 INFO - Display Serial Number: 1600x1200 60 16:13:59 INFO - Main Display: Yes 16:13:59 INFO - Mirror: Off 16:13:59 INFO - Online: Yes 16:13:59 INFO - Rotation: Supported 16:13:59 INFO - Return code: 0 16:13:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:13:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:13:59 INFO - Python 2.7.3 16:13:59 INFO - Return code: 0 16:13:59 INFO - grabbing minidump binary from tooltool 16:13: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')]} 16:13:59 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 0x10472cd30>, '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 0x104491db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104724b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:13:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:13:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:13:59 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:14:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:14:00 INFO - Return code: 0 16:14:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:14:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:14:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:14:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:14:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:14:00 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/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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 16:14:00 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/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0305 --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 16:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.89QxAKv03E/Render', 16:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:14:00 INFO - 'HOME': '/Users/cltbld', 16:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:14:00 INFO - 'LOGNAME': 'cltbld', 16:14:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:14:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:14:00 INFO - 'MOZ_NO_REMOTE': '1', 16:14:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:14:00 INFO - 'NO_EM_RESTART': '1', 16:14:00 INFO - 'PAGER': '/bin/cat', 16:14:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:14:00 INFO - 'PWD': '/builds/slave/test', 16:14:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:14:00 INFO - 'SHELL': '/bin/bash', 16:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.E9LB1smlKs/Listeners', 16:14:00 INFO - 'TMPDIR': '/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/', 16:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:14:00 INFO - 'USER': 'cltbld', 16:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:14:00 INFO - 'XPC_FLAGS': '0x0', 16:14:00 INFO - 'XPC_SERVICE_NAME': '0', 16:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:14:00 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/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0305', '--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 16:14:00 INFO - mozversion application_buildid: 20160901133621 16:14:00 INFO - mozversion application_changeset: fd96f3fee9754562392a3877464b10aa90f82b71 16:14:00 INFO - mozversion application_display_name: Nightly 16:14:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:14:00 INFO - mozversion application_name: Firefox 16:14:00 INFO - mozversion application_remotingname: firefox 16:14:00 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:14:00 INFO - mozversion application_vendor: Mozilla 16:14:00 INFO - mozversion application_version: 51.0a1 16:14:00 INFO - mozversion platform_buildid: 20160901133621 16:14:00 INFO - mozversion platform_changeset: fd96f3fee9754562392a3877464b10aa90f82b71 16:14:00 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:14:00 INFO - mozversion platform_version: 51.0a1 16:14:00 INFO - using testdate: 1472771640 16:14:00 INFO - actual date: 1472771640 16:14:00 INFO - starting webserver on 'localhost:55747' 16:14:00 INFO - SUITE-START | Running 2 tests 16:14:00 INFO - TEST-START | dromaeo_css 16:14:00 INFO - operating with platform_type : mac_ 16:14:00 INFO - Initialising browser for dromaeo_css test... 16:14:00 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpGSbPCD/profile http://localhost:55747/getInfo.html) 16:14:02 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 16:14:02 INFO - PROCESS | 1942 | colorDepth:24 16:14:02 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 16:14:02 INFO - PROCESS | 1942 | __metrics 16:14:02 INFO - PROCESS | 1942 | 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" 16:14:02 INFO - PROCESS | 1942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:14:03 INFO - TEST-INFO | 1942: exit 0 16:14:03 INFO - Browser initialized. 16:14:03 INFO - Running cycle 1/1 for dromaeo_css test... 16:14:03 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpGSbPCD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:14:15 INFO - PROCESS | 1944 | RSS: Main: 233582592 16:14:15 INFO - PROCESS | 1944 | 16:15:07 INFO - PROCESS | 1944 | 1472771707228 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] 16:17:18 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55747/tests/dromaeo/cssquery-ext.html) 16:17:20 INFO - PROCESS | 1944 | RSS: Main: 413048832 16:17:20 INFO - PROCESS | 1944 | 16:20:23 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-ext.html (next: http://localhost:55747/tests/dromaeo/cssquery-jquery.html) 16:20:24 INFO - PROCESS | 1944 | RSS: Main: 371539968 16:20:24 INFO - PROCESS | 1944 | 16:23:26 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55747/tests/dromaeo/cssquery-mootools.html) 16:23:27 INFO - PROCESS | 1944 | RSS: Main: 351481856 16:23:27 INFO - PROCESS | 1944 | 16:26:29 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55747/tests/dromaeo/cssquery-prototype.html) 16:26:30 INFO - PROCESS | 1944 | RSS: Main: 342028288 16:26:30 INFO - PROCESS | 1944 | 16:29:33 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55747/tests/dromaeo/cssquery-yui.html) 16:29:34 INFO - PROCESS | 1944 | RSS: Main: 327847936 16:29:34 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:55747/tests/dromaeo/cssquery-yui.html (next: http://localhost:55747/tests/dromaeo/cssquery-dojo.html) 16:32:37 INFO - PROCESS | 1944 | RSS: Main: 398843904 16:32:37 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | __start_tp_report 16:32:37 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 16:32:37 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 16:32:37 INFO - PROCESS | 1944 | |i|pagename|runs| 16:32:37 INFO - PROCESS | 1944 | |0;dojo.html;3322.3687499375123;3337.716294115;3362.2771104212575;3399.558057452534;3415.914602134946;5482.424345443731;5544;5688.943110568893;5767.74045167968;5769.567282453817;5586.10622300431;5619.831405057845;5625.40933202014;5634.408387119355;5646;5051.873703157423;5087.745612719368;5097.821576244833;5116.539511443969;5126.6923984561045;5222.63441559091;5229.372475302969;5231.686098834082;5240.633155679104;5251.081060814361;5172.793088276464;5193.792248310063;5216.7913283468615;5225.999999999981;5230.607704422165;5289.576833853283;5301.204819277101;5319.5744340452675;5319.760610772524;5376.327959005124;5073.619478539124;5091.210862316347;5092.821751238689;5209.843704688866;5218.947810521922;5681;5684.744186511575;5713.74288157034;5740.167675687044;5797.913031304533;6238.376162383771;6246.812595622093;6249.625022498664;6249.9062514062325;6256.343083976162;5204.9739751301;6120.264288666148;6216;6277.4664153547;6285.622862628257;5804.1293805929445;5815.098659707752;5815.302163740378;5815.680137592473;5852.414758524156;6310.179676642099;6327.525435592349;6329.303776584572;6339.302676705559;6349.492040636738;5835.766569337264;6057.848553786191;6064.636121832704;6189.96905015472;6196.256449226122;6210.658413787285;6250.874982500324;6266.686665666698;6278.6860656966965;6280.654563998933;6152.661603611844;6193.81418557444;6229.159063526366;6238.625682458976;6241.469475094577;6096.756129754849;6098.734805259241;6105.4294214941465;6231.657258850716;6259.061140828912;4861.29511220871;4883.609311255091;4884.633652476007;4889.608831293488;4892.657513974059;4348.108637729258;4360.040791025969;4419.756913369795;4457.955420445819;4465.642748580106;5624.128260119688;5625.831225063254;5630.774769009194;5632.464915832989;5683.829485115453;6116.541259405562;6175.660338681326;6192.225971753531;6203.720832562545;6211.565190436717;5593.412691667398;5627.971860140673;5657.745401456944;5690.032694441955;5694.373618901917;5057.165567681365;5169.68981861082;5403.13549832025;5408.0535906216255;5465.699386533699;5324.653897496728;6258.81223563303;6324.304326523987;6331.715072821734;6333.746650133943;6108.402780883668;6191.318954914866;6333.1766870306565;6345.238571371465;6347.301796802256;6288.622682639057;6328.335524769833;6335.65153916539;6350.555461117678;6358.523110766619;5259.368875734784;6099.207103076572;6201.844953876189;6212.534059945432;6215.440610345091;5950.672713000827;6135.386461354007;6230.532710046855;6247.375262473716;6274.529410294155;6047.304559975659;6087.634741915499;6104.298005729398;6137.508999280136;6142.437386315837;6097.329293777592;6099.573029888043;6109.2974307955155;6110.602810817282;6124.846878827887;5759.65442073477;5915.674637894957;5922.970385148046;5930;5930.466258036798;5908.734106965111;6065.181200537871;6065.3934606539;6071.514278857592;6074.696265186812;5203.635745497779;5561.304836895388;5863.237779088691;5866.2373891393845;5883.852903677271 16:32:37 INFO - PROCESS | 1944 | |1;ext.html;519.9688018718876;526.5918912842546;527.8574784808102;538.3405328472619;547.800317305102;30301.848490757544;38805.41791873124;40002.99999999996;40162.39756403656;42545.14909701804;41885.7905710471;42176;42287.788561057154;42306.36540451888;42379.57620423802;7723.72966946157;9557.474338911357;9586;9737.172340351079;9780.021997800199;8637.179467950535;11619.593314234004;11883.108766842479;11890.643280701594;12181.269123852597;11774.470148843322;11813.468393922294;11861.347625880573;11878.287302761819;11886.167968242227;11912.940435297854;11982.940085299517;11986.520539178466;11987.160898737093;11987.940060299643;11544.884551154464;11637.650870473899;11638.41807909601;11656.650300491;11665.183437159401;39328;39644.801775991225;39709;39718.60281397178;39742;3306.421376259169;3385.661433856619;3408.931821363584;3747.906302342436;3983.820728067244;30399;31128;31200;31322.84338578315;31601.8419907899;3239.9190020249684;3251.3659836331926;3260.6250281217726;3265.069455205255;3367.5285460035616;425690;426243;427431;430909;436971.81514092546;32028;56526.86946261052;57093.714531427075;57218;57268;32632.347353052806;34083.08044574906;34096.97709068732;34247.4862877057;34272.82863585666;14203.431862725376;14383.856161438462;14410.351534180987;14412;14418.567442976728;10958.397288149228;11146.052585530158;11160.441977901073;12059.336736479403;12143.696407589881;7241.348278654841;7301.89047164293;7509.924900751032;7674.386049116058;7696.230376962259;3171.8414079295944;3224.500202468621;3230.1601583588435;3245.9450678529574;3258.234314935988;909.4270609515962;914.8033172867865;1016.2835201183149;1060.81966065769;1066.088494337346;24481.265562033168;25976.176195031654;26837.693142111588;28056.579151312748;29874;984.7045886234101;1029.7734498410339;1057.3919996002176;1060.4962642744788;1060.734816295926;12713.936430317974;14852.554423367315;15356.616084597974;15752.842471575366;15754;566054;567083;568281;570300;570813;53919.73040134774;54231.72884135554;54449.72775136178;54514.45485545094;54601.45398546043;1209.4376115106518;1314.875086866727;1338.5382043195082;1354.4514471639002;1364.536057740373;29555;30575;30647.387052259277;31360.529592056595;31375.37249255048;46649;47091;47168.05663886772;47263;47322.526774731814;467682;469093;471926;472454;472840.6357968188;25598.23205303844;26407.471850563266;26485.470290593694;27429;27531.31171720723;37921.4311785329;45242;45490;46027.30959035684;46925.826854328916;38070.42894356531;38386.232275354894;38477.80761096177;38732.32782841783;38936.80531597324;37741.4338784913;37753.81123094367;38195.809020954715;38721;38784.418233725955;37658;37823.81088094542;38082.42876356912;38365.040873978374;38488;31599;32436.83781581077;33797.97212167278;33958.83020584881;34049.48925766165;21757;24357.14749983742;24658.15063870181;25001.874990624932;25169.119080832083 16:32:37 INFO - PROCESS | 1944 | |2;jquery.html;359.322676754318;374.64970252813623;375.92669429461256;388.764797297635;409.9979500102499;15312.69374612507;15385.230738463075;20238.29165979191;20257.49356266094;20323.695144572845;20142.295019674315;20560.971951402444;20693;20767.68848467274;20803.063862126204;5764.913526297109;5804.780995990836;6046.395360463963;6084.2090528231265;6103.969480152593;6078.848028799272;6113.663748493831;6116.571839971204;6122.816315510542;6130.662813545253;3923.8822835314845;5476.835694929158;6795.048693182958;6806.829829254283;6909.274526174755;6043.063325184604;6404.871902561969;6826.48801339899;6837.487188460836;6846.383825455733;5911.527077833763;5919.852003699899;5926.585139040269;5928.525717942554;5932.851678708023;13489.730205395936;18432.81567184338;24630.935895577208;25558.74441255582;25830.966761329524;1798.4874310821483;2229.3869185973826;2278.305116939333;2455.6439316299216;2543.249741326313;9862.60549578016;9871.457069861155;9936.602535898555;9960.90039099607;10116.696499104964;1788.8837225580296;1853.8331550160551;1868.3087257714596;1905.7808352039553;1909.6276226135908;78152;81409.77885331784;115756.42121789421;125434.37282813528;127543;10831.83752243717;11040.908545607243;11440.370779607201;11677.88322116785;11930.403479825975;14357.282135893163;14363.066400683922;14369.712605747827;14387.856121438861;14389.64025899361;8180.263776260165;8441.282490988211;8460.111688272766;8571.657133714707;8581.442206256574;8341.24928756415;8345.165483451729;8411.242988130974;8430.873536896952;8527;3751.268502642013;4489.910201796011;4495.887602809956;4555.681102322872;4578.2903649934315;1855.4990152658977;1902.3722171683587;1905.104600837604;1906.323255244396;1907.6661584222707;708.9012031351384;726.5681763138774;821.0475848016273;887.6050157679771;931.2177770672668;16308.510744677678;19623.607527849363;20218.898905505674;20299.28952486656;21525.79615617876;895.1898531828706;906.3881879731155;908.6002159049997;945.2532499325594;1026.3585259212991;10999.23005389615;11149.108071354272;11225.326480411202;11225.60710375133;11229.887701123047;488676.6889476625;540290.2985485126;548685;552203;555138.224308884;131671.34164329118;131709;132958;137406.3129684345;138981.30509347387;959.8704174936433;965.5124162297994;997.6558087459815;998.960041598328;998.9900100998897;17507;18783.71824422662;18935.242590296784;18991.90504047471;19071.809281907004;39837.203255935296;56750.7162464185;57178.7141064292;57600.71199643975;57691.94234605306;80251;82262;82386.58806705927;83586;84588.39272438867;7941.920580794118;7950.920490795249;8061.234182752629;8108.554029528433;8140.185981401813;11080.833787493033;12533.185342953044;12882.35588220604;12934.547290844775;13108.672283192996;10864.64191976003;10956.726081847699;11120.719387780462;11189;11248.100151987985;5033.245013247895;5090.923636145535;7237.62485127824;7491.70033198666;10798.946005269923;11247.325160490396;11254.94372528132;11419.714507137389;11453.141014424118;11579.189456737948;7847.646855891613;8052.597370131352;8105.75682729519;8195.385346098947;8285.011597216504;9826.852597211191;10051.849222261819;10816;10872.836907446554;11479.598214062467 16:32:37 INFO - PROCESS | 1944 | |3;mootools.html;1393.7769956806912;1496.1621491964502;1650.4058538925985;1800.1539276540022;1845.280340667141;7861.331786798129;8148.266656000974;8362.540060296682;8938;8940.821183576332;8428;8669.609867555959;8685.73942781716;8839.381243312971;8856.77858053549;2106.641870881946;2384.1417089847682;2406.217979156781;2414.360194548446;2417.721961974378;2107.1676687708346;2203.5372571759867;2367.9289621311386;2395.9161429349974;2425.551273014489;2338.053088499152;2411.5418070566625;2414.1671123462465;2415.975840241593;2416.7583241675866;2366.1008816649564;2370.4073981504625;2372.2764556810166;2399.076355603105;2406.1458182345364;2376.275236053003;2391.7488663690237;2394.964075538868;2399.3042017814814;2416.55293770653;8811.251043661296;8874.156955089256;8884.200421962054;8888.60001299943;8895.243904268145;4987.725675087869;5288.894222115536;5331.466853314638;5340.91988620171;5346.278252435949;3463.8441270142903;3590.281943611288;3613.1328481164333;3661.048127486846;3674.963250367489;1049.8635177426886;1092.4592326798286;1106.7011906785124;1107.197281970573;1122.180808010157;11488.483018264114;11539.48072336747;11547.595834145848;11549.769004619944;11557.422128893522;4211;4427.579379958899;4571.885702857455;4775.450823155382;4818.132736107535;9888.258380621339;9913.107820296207;9919.553620087114;9927.950360248153;9975.052370024836;5306.230596563477;5308.991291654651;5313.734313284321;5316.122839731478;5323.254744335796;4734.384530011076;4839.564439200489;4886.731229782325;4896.2410826321975;4899;3459.3427248822645;3906.628870257321;3933.1543718100747;3995.440638310639;4069.450624165759;1181.4211036592008;1224.3510939202238;1251.605744190577;1280.3982128399637;1281.557862537423;757.3070640364116;800.1798156889236;820.6102101501739;911.3939230411702;916.862470629411;5582.944170558242;5710.885782284331;5844.532437404998;5908;5994.040953447515;769.9730509432143;789.3345041961456;800.371708209053;818.0919179710636;938.9718308450621;3416.8804091856664;3485.233248685285;3765.2657731742597;3791.3554695701796;3881.8835434936996;10418.322809017389;10727.517261723242;10834.404107774148;10845.728856778485;10856.34861908288;10787.190960677981;10810.945945270381;10815.67552973412;10839.729006774736;10853.620123295646;8158.347332213409;8290.585470726439;8291.792705182419;8308.667653293922;8323.292520135736;5367.221752845738;5560.777568897199;5637.689927054052;5687.914681279702;5708.771649134154;9795.85306220393;9967.50162491858;10034.448105353984;10077.445740484343;10318.48407579603;9854.162396196403;10216.335938163995;10293;10314.587416503256;10422.947885260524;6976.860462790817;7108.644567771694;7120.465965052538;7124.501284910007;7126.180489243596;8141.22658347456;8225.588720564068;8232;8239.629216685144;8245.13426090252;7348.191698913009;7449.6275186241555;7543.245675432413;7633.6564854580565;7649.349805266392;7255.347018768336;7381.815454613463;7388.593627350548;7407.1852096268285;7439.293267139613;5777.6822274775295;6104.779044191268;6378.883695353387;6875.140607424072;6995.720171193096;4156.854510092132;4387.8464253752245;4879.56083952446;4946.653734238569;5022.84931452057;4858.78135483897;4934.901301973869;4962.181240095344;5002.17464118431;5032.043911656773 16:32:37 INFO - PROCESS | 1944 | |4;prototype.html;256.70222541851456;303.7494067394399;311.24367786279345;313.2435169066704;319.41546969046647;5156.819511317105;5257.974694934489;5485.451454854512;5505.394406615279;5508.807191748289;5237.214417837326;5419.295491586088;5469.753861076251;5483.616146869721;5491.588130890179;4145.17096580685;4148.419221309019;4152.484343214735;4172.7496350218935;4177.853775117872;3182.9045128646176;3356.7314614830757;3558.861164427384;3644.9406085561704;3726.5093962415117;3837.9040523986982;4032.3749818777987;4056.797160141996;4070.4708387909677;4075.6331930126285;3530.5939816921127;3809.657130858222;3886.805659717031;3917.569067402612;4000.0399704218958;3457.135716070982;3475.33968545978;3489.6510348965153;3493.916885765421;3498.860045598173;4363.759993200372;4999.3000979862845;5176.722242213414;5255.76349064293;5265.552428043621;3942.8422863085443;4453.506036619471;4586.472555656109;4704.506026867198;4721.480637129913;4739.502352253033;4782.665213435061;5539.556835453154;5572.637778544382;5694.800681976152;3388.0852169914233;3584.62361452049;3791.7345785795005;3792.715546334008;3810;6013.75944962201;6016.308124565643;6020.073576455536;6108.694565271719;6114.11345354921;3543.7519373644113;3672.1186915140174;3815.7328986970647;3913.2369188008065;3957.6635985941516;3365.8317084145697;3849.7305188637088;3982.2721435816557;3999.1801680655394;4017.4978127734034;4635.6986795858165;4651.2557990721325;4659.254519276899;4680.9297860532115;4687.273472611751;5548.250986116823;5758.24373566058;5768;5839.0949402842625;5885.411458854081;1168.3340495917416;1235.1415766042694;1270.4219580091221;1285.8427415326094;1312.8162057311984;761.6001599160485;804.9839003219903;903.4895284164426;907.111031189438;911.9922485653258;4264.722793018444;4446.6220371268155;4487.371767952489;5360.597955153379;5408.78364865401;4666.976665116653;4936.456989731127;5724.4561766632105;5835.679037652885;5904.110684580893;4849.75751212438;5059.229269755556;5160.225852504243;5277.577793776489;5288.910880202298;2078.0752565108683;2455.693038370204;2528.102523604131;2586.2025892004876;2609.9120703437175;4016.7389119707127;4761.262004389325;4889.804407823718;5055.646104772704;5058.974705126451;4465;4485;4643.585335625456;4897.131266299654;5504.779808807602;4597.368079217381;5809.750903555756;6028.909566356596;6177.96911015442;6200.194848992067;5867.743402411532;5966.403359663999;6035.969820151046;6079.604825686316;6080.209572755513;5196.53231209193;5245.370555533361;5395.379531353945;5875.764969401176;5892.9116063258225;5269.050451332878;5636.577256705846;5990.940090599038;6139.386061393825;6152.907706384497;3971.146203566247;3976.5228172619472;3988.387606117997;3990.6654607055198;3995.6603688686787;5056.873578160428;5631.690257035739;5799.3040835100055;5807.303123625192;6014.458698717136;4904.6566740327835;5476.481902430591;5559.749811258584;5670.8015219467125;5726.997775389374;5557.499825015606;5735.369109398046;5751.712414379348;5827.329857066492;5857.4184970058695;5408.971635636636;5543.833684989457;5588.469095435927;5598.496135347838;5659.518940889906;5776.133579963039;5789.884202316014;5806.535477161732;5810.883782324246;5826.388229235869;4526.307014059499;4661.86014419568;5608.971955140361;5725.312962444534;5736.6558006519745 16:32:37 INFO - PROCESS | 1944 | |5;yui.html;705.8235441139715;724.6738967464639;756.4629113329536;784.2588753627821;836.8744688296755;15159.848401515981;15198.620034499116;15874.285657145427;15906.761398579016;15925.203739813021;14002.159870407757;14225.14649121051;14251.643708907282;14256.287185640702;14269;702.8734827731006;708.638594316897;869.7912500999762;883.1786438612088;885.7962668586229;859.8839156713826;872.9039805621377;879.5162660536711;884.2218847414266;886.3795343259711;908.6774195160722;908.8954770201445;908.9727308180766;909.6452383570413;911.4576826788051;730.2587873308596;745.2621904314743;900.522722956834;901.6438506789781;902.6073657958776;562.980295689653;563.7350445290707;659.5910535467992;670.9261981182066;672.3175976383974;14398.064125831788;14476.710465790626;14502.1298722077;14503.347349369198;14518.128912265298;13224.603261902159;13532.391042403115;13587.59237222885;13863.168209907337;13973;11227;11699.35653539055;11812.586559470463;11954.760904781942;12084.335361555111;4569.954300456986;4609.539046095364;4611.861644150681;4676.859694209145;4819.975900120512;106825;107690.92309076966;108928;109736.61053557848;111849;10485.633002844865;10681.519331630096;10740.516676749565;10869.891301086887;11120.499577519031;20495.795042049685;20634.484137896372;20667.17331306761;20690;20830.687539686918;7753.1083925348175;7764.650590723431;7834.569098699626;7906.367490600738;8021.759347219593;4355.216061109031;4388.056567837866;4431.977840110843;4445.2887537993765;4561.908761824811;3341.281624450755;3495.615482296945;3526.964730352715;3544.9365190442763;3564;679.9286074962058;692.8198668346165;697.6476879175983;701.7999870037015;702.0452185028357;509.67380876239235;629.8897692903723;673.095592518282;699.2448155991517;701.113091938698;8554.529500877512;8903.510306933182;9194.53039225477;9325.207357374698;9613.51932403391;569.6240481282334;601.5758889982552;652.350910843704;674.5514233035075;674.7435974329821;4326.242907491176;4617;4649.680577201732;4671.766411679402;4758.04839032188;118427.81572184167;139411;143423.282883587;148917;152119;106846;134468;141747.70866857975;142417;147890;72547.63726181335;88117.55941220254;95123.52438237764;99110;107821.46089269503;9464.763380915532;9471.289653275893;9487.193588545051;9554.426734395958;9692.127708506268;61980.69009654923;62622;63214.367856322686;66282;66426;89029;95845;96374;96535.51732241294;98767;12825.935870320589;12956.740865182832;12972.740545188855;13191.944644428231;13344.532941347006;14663.48677796272;14703.705925881637;14829;14932.477363292233;14942.178180199759;13503.729925401634;13643.795343069663;13809.723805523632;13825.51610693621;14025.3688584016;13315.087579617988;13714.314284285945;13848.792268115785;13978.161310321413;14162.433502659778;11184.440777961232;12758.043146763845;13208.339583021003;13336.799948000593;13869.583912482642;10411.219158562986;10505.527251273557;10697.946510267398;10785.137189025016;10929.508172132431;10984.835227471434;11119.888801112209;11201.327920324806;11210.66368008961;11384.544618215179 16:32:37 INFO - PROCESS | 1944 | __end_tp_report 16:32:37 INFO - PROCESS | 1944 | __start_cc_report 16:32:37 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,1614 16:32:37 INFO - PROCESS | 1944 | __end_cc_report 16:32:37 INFO - PROCESS | 1944 | __startTimestamp1472772757827__endTimestamp 16:32:37 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | ------- Summary: start ------- 16:32:37 INFO - PROCESS | 1944 | Number of tests: 6 16:32:37 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5704.18 Median:5863.24 stddev:642.04 (11.0%) stddev-sans-first:616.22 16:32:37 INFO - PROCESS | 1944 | Values: 3322.4 3337.7 3362.3 3399.6 3415.9 5482.4 5544.0 5688.9 5767.7 5769.6 5586.1 5619.8 5625.4 5634.4 5646.0 5051.9 5087.7 5097.8 5116.5 5126.7 5222.6 5229.4 5231.7 5240.6 5251.1 5172.8 5193.8 5216.8 5226.0 5230.6 5289.6 5301.2 5319.6 5319.8 5376.3 5073.6 5091.2 5092.8 5209.8 5218.9 5681.0 5684.7 5713.7 5740.2 5797.9 6238.4 6246.8 6249.6 6249.9 6256.3 5205.0 6120.3 6216.0 6277.5 6285.6 5804.1 5815.1 5815.3 5815.7 5852.4 6310.2 6327.5 6329.3 6339.3 6349.5 5835.8 6057.8 6064.6 6190.0 6196.3 6210.7 6250.9 6266.7 6278.7 6280.7 6152.7 6193.8 6229.2 6238.6 6241.5 6096.8 6098.7 6105.4 6231.7 6259.1 4861.3 4883.6 4884.6 4889.6 4892.7 4348.1 4360.0 4419.8 4458.0 4465.6 5624.1 5625.8 5630.8 5632.5 5683.8 6116.5 6175.7 6192.2 6203.7 6211.6 5593.4 5628.0 5657.7 5690.0 5694.4 5057.2 5169.7 5403.1 5408.1 5465.7 5324.7 6258.8 6324.3 6331.7 6333.7 6108.4 6191.3 6333.2 6345.2 6347.3 6288.6 6328.3 6335.7 6350.6 6358.5 5259.4 6099.2 6201.8 6212.5 6215.4 5950.7 6135.4 6230.5 6247.4 6274.5 6047.3 6087.6 6104.3 6137.5 6142.4 6097.3 6099.6 6109.3 6110.6 6124.8 5759.7 5915.7 5923.0 5930.0 5930.5 5908.7 6065.2 6065.4 6071.5 6074.7 5203.6 5561.3 5863.2 5866.2 5883.9 16:32:37 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:61847.82 Median:18729.21 stddev:131416.12 (701.7%) stddev-sans-first:131704.10 16:32:37 INFO - PROCESS | 1944 | Values: 520.0 526.6 527.9 538.3 547.8 30301.8 38805.4 40003.0 40162.4 42545.1 41885.8 42176.0 42287.8 42306.4 42379.6 7723.7 9557.5 9586.0 9737.2 9780.0 8637.2 11619.6 11883.1 11890.6 12181.3 11774.5 11813.5 11861.3 11878.3 11886.2 11912.9 11982.9 11986.5 11987.2 11987.9 11544.9 11637.7 11638.4 11656.7 11665.2 39328.0 39644.8 39709.0 39718.6 39742.0 3306.4 3385.7 3408.9 3747.9 3983.8 30399.0 31128.0 31200.0 31322.8 31601.8 3239.9 3251.4 3260.6 3265.1 3367.5 425690.0 426243.0 427431.0 430909.0 436971.8 32028.0 56526.9 57093.7 57218.0 57268.0 32632.3 34083.1 34097.0 34247.5 34272.8 14203.4 14383.9 14410.4 14412.0 14418.6 10958.4 11146.1 11160.4 12059.3 12143.7 7241.3 7301.9 7509.9 7674.4 7696.2 3171.8 3224.5 3230.2 3245.9 3258.2 909.4 914.8 1016.3 1060.8 1066.1 24481.3 25976.2 26837.7 28056.6 29874.0 984.7 1029.8 1057.4 1060.5 1060.7 12713.9 14852.6 15356.6 15752.8 15754.0 566054.0 567083.0 568281.0 570300.0 570813.0 53919.7 54231.7 54449.7 54514.5 54601.5 1209.4 1314.9 1338.5 1354.5 1364.5 29555.0 30575.0 30647.4 31360.5 31375.4 46649.0 47091.0 47168.1 47263.0 47322.5 467682.0 469093.0 471926.0 472454.0 472840.6 25598.2 26407.5 26485.5 27429.0 27531.3 37921.4 45242.0 45490.0 46027.3 46925.8 38070.4 38386.2 38477.8 38732.3 38936.8 37741.4 37753.8 38195.8 38721.0 38784.4 37658.0 37823.8 38082.4 38365.0 38488.0 31599.0 32436.8 33798.0 33958.8 34049.5 21757.0 24357.1 24658.2 25001.9 25169.1 16:32:37 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:33028.79 Median:21880.54 stddev:90442.38 (413.3%) stddev-sans-first:90662.83 16:32:37 INFO - PROCESS | 1944 | Values: 359.3 374.6 375.9 388.8 410.0 15312.7 15385.2 20238.3 20257.5 20323.7 20142.3 20561.0 20693.0 20767.7 20803.1 5764.9 5804.8 6046.4 6084.2 6104.0 6078.8 6113.7 6116.6 6122.8 6130.7 3923.9 5476.8 6795.0 6806.8 6909.3 6043.1 6404.9 6826.5 6837.5 6846.4 5911.5 5919.9 5926.6 5928.5 5932.9 13489.7 18432.8 24630.9 25558.7 25831.0 1798.5 2229.4 2278.3 2455.6 2543.2 9862.6 9871.5 9936.6 9960.9 10116.7 1788.9 1853.8 1868.3 1905.8 1909.6 78152.0 81409.8 115756.4 125434.4 127543.0 10831.8 11040.9 11440.4 11677.9 11930.4 14357.3 14363.1 14369.7 14387.9 14389.6 8180.3 8441.3 8460.1 8571.7 8581.4 8341.2 8345.2 8411.2 8430.9 8527.0 3751.3 4489.9 4495.9 4555.7 4578.3 1855.5 1902.4 1905.1 1906.3 1907.7 708.9 726.6 821.0 887.6 931.2 16308.5 19623.6 20218.9 20299.3 21525.8 895.2 906.4 908.6 945.3 1026.4 10999.2 11149.1 11225.3 11225.6 11229.9 488676.7 540290.3 548685.0 552203.0 555138.2 131671.3 131709.0 132958.0 137406.3 138981.3 959.9 965.5 997.7 999.0 999.0 17507.0 18783.7 18935.2 18991.9 19071.8 39837.2 56750.7 57178.7 57600.7 57691.9 80251.0 82262.0 82386.6 83586.0 84588.4 7941.9 7950.9 8061.2 8108.6 8140.2 11080.8 12533.2 12882.4 12934.5 13108.7 10864.6 10956.7 11120.7 11189.0 11248.1 5033.2 5090.9 7237.6 7491.7 10798.9 11247.3 11254.9 11419.7 11453.1 11579.2 7847.6 8052.6 8105.8 8195.4 8285.0 9826.9 10051.8 10816.0 10872.8 11479.6 16:32:37 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5662.00 Median:4916.95 stddev:3207.12 (65.2%) stddev-sans-first:3200.08 16:32:37 INFO - PROCESS | 1944 | Values: 1393.8 1496.2 1650.4 1800.2 1845.3 7861.3 8148.3 8362.5 8938.0 8940.8 8428.0 8669.6 8685.7 8839.4 8856.8 2106.6 2384.1 2406.2 2414.4 2417.7 2107.2 2203.5 2367.9 2395.9 2425.6 2338.1 2411.5 2414.2 2416.0 2416.8 2366.1 2370.4 2372.3 2399.1 2406.1 2376.3 2391.7 2395.0 2399.3 2416.6 8811.3 8874.2 8884.2 8888.6 8895.2 4987.7 5288.9 5331.5 5340.9 5346.3 3463.8 3590.3 3613.1 3661.0 3675.0 1049.9 1092.5 1106.7 1107.2 1122.2 11488.5 11539.5 11547.6 11549.8 11557.4 4211.0 4427.6 4571.9 4775.5 4818.1 9888.3 9913.1 9919.6 9928.0 9975.1 5306.2 5309.0 5313.7 5316.1 5323.3 4734.4 4839.6 4886.7 4896.2 4899.0 3459.3 3906.6 3933.2 3995.4 4069.5 1181.4 1224.4 1251.6 1280.4 1281.6 757.3 800.2 820.6 911.4 916.9 5582.9 5710.9 5844.5 5908.0 5994.0 770.0 789.3 800.4 818.1 939.0 3416.9 3485.2 3765.3 3791.4 3881.9 10418.3 10727.5 10834.4 10845.7 10856.3 10787.2 10810.9 10815.7 10839.7 10853.6 8158.3 8290.6 8291.8 8308.7 8323.3 5367.2 5560.8 5637.7 5687.9 5708.8 9795.9 9967.5 10034.4 10077.4 10318.5 9854.2 10216.3 10293.0 10314.6 10422.9 6976.9 7108.6 7120.5 7124.5 7126.2 8141.2 8225.6 8232.0 8239.6 8245.1 7348.2 7449.6 7543.2 7633.7 7649.3 7255.3 7381.8 7388.6 7407.2 7439.3 5777.7 6104.8 6378.9 6875.1 6995.7 4156.9 4387.8 4879.6 4946.7 5022.8 4858.8 4934.9 4962.2 5002.2 5032.0 16:32:37 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:4518.70 Median:5027.47 stddev:1445.46 (28.8%) stddev-sans-first:1413.68 16:32:37 INFO - PROCESS | 1944 | Values: 256.7 303.7 311.2 313.2 319.4 5156.8 5258.0 5485.5 5505.4 5508.8 5237.2 5419.3 5469.8 5483.6 5491.6 4145.2 4148.4 4152.5 4172.7 4177.9 3182.9 3356.7 3558.9 3644.9 3726.5 3837.9 4032.4 4056.8 4070.5 4075.6 3530.6 3809.7 3886.8 3917.6 4000.0 3457.1 3475.3 3489.7 3493.9 3498.9 4363.8 4999.3 5176.7 5255.8 5265.6 3942.8 4453.5 4586.5 4704.5 4721.5 4739.5 4782.7 5539.6 5572.6 5694.8 3388.1 3584.6 3791.7 3792.7 3810.0 6013.8 6016.3 6020.1 6108.7 6114.1 3543.8 3672.1 3815.7 3913.2 3957.7 3365.8 3849.7 3982.3 3999.2 4017.5 4635.7 4651.3 4659.3 4680.9 4687.3 5548.3 5758.2 5768.0 5839.1 5885.4 1168.3 1235.1 1270.4 1285.8 1312.8 761.6 805.0 903.5 907.1 912.0 4264.7 4446.6 4487.4 5360.6 5408.8 4667.0 4936.5 5724.5 5835.7 5904.1 4849.8 5059.2 5160.2 5277.6 5288.9 2078.1 2455.7 2528.1 2586.2 2609.9 4016.7 4761.3 4889.8 5055.6 5059.0 4465.0 4485.0 4643.6 4897.1 5504.8 4597.4 5809.8 6028.9 6178.0 6200.2 5867.7 5966.4 6036.0 6079.6 6080.2 5196.5 5245.4 5395.4 5875.8 5892.9 5269.1 5636.6 5990.9 6139.4 6152.9 3971.1 3976.5 3988.4 3990.7 3995.7 5056.9 5631.7 5799.3 5807.3 6014.5 4904.7 5476.5 5559.7 5670.8 5727.0 5557.5 5735.4 5751.7 5827.3 5857.4 5409.0 5543.8 5588.5 5598.5 5659.5 5776.1 5789.9 5806.5 5810.9 5826.4 4526.3 4661.9 5609.0 5725.3 5736.7 16:32:37 INFO - PROCESS | 1944 | 16:32:37 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:24385.44 Median:3945.12 stddev:38702.10 (981.0%) stddev-sans-first:38769.83 16:32:37 INFO - PROCESS | 1944 | Values: 705.8 724.7 756.5 784.3 836.9 15159.8 15198.6 15874.3 15906.8 15925.2 14002.2 14225.1 14251.6 14256.3 14269.0 702.9 708.6 869.8 883.2 885.8 859.9 872.9 879.5 884.2 886.4 908.7 908.9 909.0 909.6 911.5 730.3 745.3 900.5 901.6 902.6 563.0 563.7 659.6 670.9 672.3 14398.1 14476.7 14502.1 14503.3 14518.1 13224.6 13532.4 13587.6 13863.2 13973.0 11227.0 11699.4 11812.6 11954.8 12084.3 4570.0 4609.5 4611.9 4676.9 4820.0 106825.0 107690.9 108928.0 109736.6 111849.0 10485.6 10681.5 10740.5 10869.9 11120.5 20495.8 20634.5 20667.2 20690.0 20830.7 7753.1 7764.7 7834.6 7906.4 8021.8 4355.2 4388.1 4432.0 4445.3 4561.9 3341.3 3495.6 3527.0 3544.9 3564.0 679.9 692.8 697.6 701.8 702.0 509.7 629.9 673.1 699.2 701.1 8554.5 8903.5 9194.5 9325.2 9613.5 569.6 601.6 652.4 674.6 674.7 4326.2 4617.0 4649.7 4671.8 4758.0 118427.8 139411.0 143423.3 148917.0 152119.0 106846.0 134468.0 141747.7 142417.0 147890.0 72547.6 88117.6 95123.5 99110.0 107821.5 9464.8 9471.3 9487.2 9554.4 9692.1 61980.7 62622.0 63214.4 66282.0 66426.0 89029.0 95845.0 96374.0 96535.5 98767.0 12825.9 12956.7 12972.7 13191.9 13344.5 14663.5 14703.7 14829.0 14932.5 14942.2 13503.7 13643.8 13809.7 13825.5 14025.4 13315.1 13714.3 13848.8 13978.2 14162.4 11184.4 12758.0 13208.3 13336.8 13869.6 10411.2 10505.5 10697.9 10785.1 10929.5 10984.8 11119.9 11201.3 11210.7 11384.5 16:32:37 INFO - PROCESS | 1944 | -------- Summary: end -------- 16:32:37 INFO - PROCESS | 1944 | 16:32:38 INFO - TEST-INFO | 1944: exit 0 16:32:38 INFO - TEST-OK | dromaeo_css | took 1118184ms 16:32:38 INFO - TEST-START | kraken 16:32:38 INFO - operating with platform_type : mac_ 16:32:38 INFO - Initialising browser for kraken test... 16:32:38 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpgPqiNT/profile http://localhost:55747/getInfo.html) 16:32:40 INFO - PROCESS | 1948 | __metrics Screen width/height:1600/1200 16:32:40 INFO - PROCESS | 1948 | colorDepth:24 16:32:40 INFO - PROCESS | 1948 | Browser inner width/height: 1024/689 16:32:40 INFO - PROCESS | 1948 | __metrics 16:32:41 INFO - PROCESS | 1948 | 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" 16:32:41 INFO - PROCESS | 1948 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:32:41 INFO - TEST-INFO | 1948: exit 0 16:32:41 INFO - Browser initialized. 16:32:41 INFO - Running cycle 1/1 for kraken test... 16:32:41 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/T/tmpgPqiNT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:53 INFO - PROCESS | 1949 | RSS: Main: 234151936 16:32:53 INFO - PROCESS | 1949 | 16:32:56 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55747/tests/kraken/driver.html?testName=audio-beat-detection) 16:32:57 INFO - PROCESS | 1949 | RSS: Main: 292909056 16:32:57 INFO - PROCESS | 1949 | 16:32:59 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55747/tests/kraken/driver.html?testName=audio-dft) 16:33:00 INFO - PROCESS | 1949 | RSS: Main: 289226752 16:33:00 INFO - PROCESS | 1949 | 16:33:04 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55747/tests/kraken/driver.html?testName=audio-fft) 16:33:04 INFO - PROCESS | 1949 | RSS: Main: 380149760 16:33:04 INFO - PROCESS | 1949 | 16:33:06 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55747/tests/kraken/driver.html?testName=audio-oscillator) 16:33:07 INFO - PROCESS | 1949 | RSS: Main: 282808320 16:33:07 INFO - PROCESS | 1949 | 16:33:09 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55747/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:33:10 INFO - PROCESS | 1949 | RSS: Main: 287277056 16:33:10 INFO - PROCESS | 1949 | 16:33:12 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55747/tests/kraken/driver.html?testName=imaging-darkroom) 16:33:13 INFO - PROCESS | 1949 | RSS: Main: 323665920 16:33:13 INFO - PROCESS | 1949 | 16:33:16 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55747/tests/kraken/driver.html?testName=imaging-desaturate) 16:33:17 INFO - PROCESS | 1949 | RSS: Main: 326631424 16:33:17 INFO - PROCESS | 1949 | 16:33:19 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55747/tests/kraken/driver.html?testName=json-parse-financial) 16:33:20 INFO - PROCESS | 1949 | RSS: Main: 325066752 16:33:20 INFO - PROCESS | 1949 | 16:33:22 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55747/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:33:22 INFO - PROCESS | 1949 | RSS: Main: 298696704 16:33:22 INFO - PROCESS | 1949 | 16:33:24 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:33:25 INFO - PROCESS | 1949 | RSS: Main: 303022080 16:33:25 INFO - PROCESS | 1949 | 16:33:27 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:33:28 INFO - PROCESS | 1949 | RSS: Main: 311549952 16:33:28 INFO - PROCESS | 1949 | 16:33:31 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:33:31 INFO - PROCESS | 1949 | RSS: Main: 314130432 16:33:31 INFO - PROCESS | 1949 | 16:33:34 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:33:35 INFO - PROCESS | 1949 | RSS: Main: 315035648 16:33:35 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:55747/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55747/tests/kraken/driver.html?testName=ai-astar) 16:33:37 INFO - PROCESS | 1949 | RSS: Main: 358584320 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | __start_tp_report 16:33:37 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 16:33:37 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 16:33:37 INFO - PROCESS | 1949 | |i|pagename|runs| 16:33:37 INFO - PROCESS | 1949 | |0;ai-astar;113;114;117;113;112;115;150;118;112;122 16:33:37 INFO - PROCESS | 1949 | |1;audio-beat-detection;148;110;135;174;126;160;159;136;161;108 16:33:37 INFO - PROCESS | 1949 | |2;audio-dft;180;185;186;185;185;182;182;184;185;185 16:33:37 INFO - PROCESS | 1949 | |3;audio-fft;75;75;77;157;58;60;59;69;138;66 16:33:37 INFO - PROCESS | 1949 | |4;audio-oscillator;81;79;135;71;78;87;153;99;102;116 16:33:37 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;88;83;86;85;86;84;87;84;96;130 16:33:37 INFO - PROCESS | 1949 | |6;imaging-darkroom;96;96;96;95;96;95;95;96;96;95 16:33:37 INFO - PROCESS | 1949 | |7;imaging-desaturate;83;85;86;86;84;83;86;99;134;134 16:33:37 INFO - PROCESS | 1949 | |8;json-parse-financial;53;55;54;55;54;53;55;55;54;57 16:33:37 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;66;65;71;82;74;77;102;103;60;94 16:33:37 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;84;83;82;88;87;86;88;85;86;131 16:33:37 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;139;142;138;138;136;164;134;130;139;141 16:33:37 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;171;176;169;168;171;169;166;168;171;168 16:33:37 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;67;67;67;67;67;69;66;67;66;67 16:33:37 INFO - PROCESS | 1949 | __end_tp_report 16:33:37 INFO - PROCESS | 1949 | __start_cc_report 16:33:37 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,2095 16:33:37 INFO - PROCESS | 1949 | __end_cc_report 16:33:37 INFO - PROCESS | 1949 | __startTimestamp1472772817390__endTimestamp 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | ------- Summary: start ------- 16:33:37 INFO - PROCESS | 1949 | Number of tests: 14 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:118.60 Median:116.00 stddev:11.47 (9.9%) stddev-sans-first:11.99 16:33:37 INFO - PROCESS | 1949 | Values: 113.0 114.0 117.0 113.0 112.0 115.0 150.0 118.0 112.0 122.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:153.50 stddev:22.49 (14.7%) stddev-sans-first:23.74 16:33:37 INFO - PROCESS | 1949 | Values: 148.0 110.0 135.0 174.0 126.0 160.0 159.0 136.0 161.0 108.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:183.90 Median:185.00 stddev:1.91 (1.0%) stddev-sans-first:1.41 16:33:37 INFO - PROCESS | 1949 | Values: 180.0 185.0 186.0 185.0 185.0 182.0 182.0 184.0 185.0 185.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:83.40 Median:67.50 stddev:34.77 (51.5%) stddev-sans-first:36.74 16:33:37 INFO - PROCESS | 1949 | Values: 75.0 75.0 77.0 157.0 58.0 60.0 59.0 69.0 138.0 66.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:100.10 Median:78.50 stddev:27.06 (34.5%) stddev-sans-first:27.81 16:33:37 INFO - PROCESS | 1949 | Values: 81.0 79.0 135.0 71.0 78.0 87.0 153.0 99.0 102.0 116.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:90.90 Median:86.00 stddev:14.22 (16.5%) stddev-sans-first:15.04 16:33:37 INFO - PROCESS | 1949 | Values: 88.0 83.0 86.0 85.0 86.0 84.0 87.0 84.0 96.0 130.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 16:33:37 INFO - PROCESS | 1949 | Values: 96.0 96.0 96.0 95.0 96.0 95.0 95.0 96.0 96.0 95.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:85.50 stddev:20.55 (24.0%) stddev-sans-first:21.25 16:33:37 INFO - PROCESS | 1949 | Values: 83.0 85.0 86.0 86.0 84.0 83.0 86.0 99.0 134.0 134.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:1.18 (2.1%) stddev-sans-first:1.12 16:33:37 INFO - PROCESS | 1949 | Values: 53.0 55.0 54.0 55.0 54.0 53.0 55.0 55.0 54.0 57.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:79.40 Median:72.50 stddev:15.48 (21.4%) stddev-sans-first:15.64 16:33:37 INFO - PROCESS | 1949 | Values: 66.0 65.0 71.0 82.0 74.0 77.0 102.0 103.0 60.0 94.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:86.00 stddev:14.54 (16.9%) stddev-sans-first:15.26 16:33:37 INFO - PROCESS | 1949 | Values: 84.0 83.0 82.0 88.0 87.0 86.0 88.0 85.0 86.0 131.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:140.10 Median:139.00 stddev:9.09 (6.5%) stddev-sans-first:9.63 16:33:37 INFO - PROCESS | 1949 | Values: 139.0 142.0 138.0 138.0 136.0 164.0 134.0 130.0 139.0 141.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:170.00 stddev:2.75 (1.6%) stddev-sans-first:2.88 16:33:37 INFO - PROCESS | 1949 | Values: 171.0 176.0 169.0 168.0 171.0 169.0 166.0 168.0 171.0 168.0 16:33:37 INFO - PROCESS | 1949 | 16:33:37 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 16:33:37 INFO - PROCESS | 1949 | Values: 67.0 67.0 67.0 67.0 67.0 69.0 66.0 67.0 66.0 67.0 16:33:37 INFO - PROCESS | 1949 | -------- Summary: end -------- 16:33:37 INFO - PROCESS | 1949 | 16:33:38 INFO - TEST-INFO | 1949: exit 0 16:33:38 INFO - TEST-OK | kraken | took 59733ms 16:33:38 INFO - SUITE-END | took 1177s 16:33:38 INFO - Completed test suite (00:19:38) 16:33:38 INFO - javascript benchmark 16:33:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.3687499375123, 3337.716294115, 3362.2771104212575, 3399.558057452534, 3415.914602134946, 5482.424345443731, 5544.0, 5688.943110568893, 5767.74045167968, 5769.567282453817, 5586.10622300431, 5619.831405057845, 5625.40933202014, 5634.408387119355, 5646.0, 5051.873703157423, 5087.745612719368, 5097.821576244833, 5116.539511443969, 5126.6923984561045, 5222.63441559091, 5229.372475302969, 5231.686098834082, 5240.633155679104, 5251.081060814361, 5172.793088276464, 5193.792248310063, 5216.7913283468615, 5225.999999999981, 5230.607704422165, 5289.576833853283, 5301.204819277101, 5319.5744340452675, 5319.760610772524, 5376.327959005124, 5073.619478539124, 5091.210862316347, 5092.821751238689, 5209.843704688866, 5218.947810521922, 5681.0, 5684.744186511575, 5713.74288157034, 5740.167675687044, 5797.913031304533, 6238.376162383771, 6246.812595622093, 6249.625022498664, 6249.9062514062325, 6256.343083976162, 5204.9739751301, 6120.264288666148, 6216.0, 6277.4664153547, 6285.622862628257, 5804.1293805929445, 5815.098659707752, 5815.302163740378, 5815.680137592473, 5852.414758524156, 6310.179676642099, 6327.525435592349, 6329.303776584572, 6339.302676705559, 6349.492040636738, 5835.766569337264, 6057.848553786191, 6064.636121832704, 6189.96905015472, 6196.256449226122, 6210.658413787285, 6250.874982500324, 6266.686665666698, 6278.6860656966965, 6280.654563998933, 6152.661603611844, 6193.81418557444, 6229.159063526366, 6238.625682458976, 6241.469475094577, 6096.756129754849, 6098.734805259241, 6105.4294214941465, 6231.657258850716, 6259.061140828912, 4861.29511220871, 4883.609311255091, 4884.633652476007, 4889.608831293488, 4892.657513974059, 4348.108637729258, 4360.040791025969, 4419.756913369795, 4457.955420445819, 4465.642748580106, 5624.128260119688, 5625.831225063254, 5630.774769009194, 5632.464915832989, 5683.829485115453, 6116.541259405562, 6175.660338681326, 6192.225971753531, 6203.720832562545, 6211.565190436717, 5593.412691667398, 5627.971860140673, 5657.745401456944, 5690.032694441955, 5694.373618901917, 5057.165567681365, 5169.68981861082, 5403.13549832025, 5408.0535906216255, 5465.699386533699, 5324.653897496728, 6258.81223563303, 6324.304326523987, 6331.715072821734, 6333.746650133943, 6108.402780883668, 6191.318954914866, 6333.1766870306565, 6345.238571371465, 6347.301796802256, 6288.622682639057, 6328.335524769833, 6335.65153916539, 6350.555461117678, 6358.523110766619, 5259.368875734784, 6099.207103076572, 6201.844953876189, 6212.534059945432, 6215.440610345091, 5950.672713000827, 6135.386461354007, 6230.532710046855, 6247.375262473716, 6274.529410294155, 6047.304559975659, 6087.634741915499, 6104.298005729398, 6137.508999280136, 6142.437386315837, 6097.329293777592, 6099.573029888043, 6109.2974307955155, 6110.602810817282, 6124.846878827887, 5759.65442073477, 5915.674637894957, 5922.970385148046, 5930.0, 5930.466258036798, 5908.734106965111, 6065.181200537871, 6065.3934606539, 6071.514278857592, 6074.696265186812, 5203.635745497779, 5561.304836895388, 5863.237779088691, 5866.2373891393845, 5883.852903677271], "value": 5663.615288587107, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.9688018718876, 526.5918912842546, 527.8574784808102, 538.3405328472619, 547.800317305102, 30301.848490757544, 38805.41791873124, 40002.99999999996, 40162.39756403656, 42545.14909701804, 41885.7905710471, 42176.0, 42287.788561057154, 42306.36540451888, 42379.57620423802, 7723.72966946157, 9557.474338911357, 9586.0, 9737.172340351079, 9780.021997800199, 8637.179467950535, 11619.593314234004, 11883.108766842479, 11890.643280701594, 12181.269123852597, 11774.470148843322, 11813.468393922294, 11861.347625880573, 11878.287302761819, 11886.167968242227, 11912.940435297854, 11982.940085299517, 11986.520539178466, 11987.160898737093, 11987.940060299643, 11544.884551154464, 11637.650870473899, 11638.41807909601, 11656.650300491, 11665.183437159401, 39328.0, 39644.801775991225, 39709.0, 39718.60281397178, 39742.0, 3306.421376259169, 3385.661433856619, 3408.931821363584, 3747.906302342436, 3983.820728067244, 30399.0, 31128.0, 31200.0, 31322.84338578315, 31601.8419907899, 3239.9190020249684, 3251.3659836331926, 3260.6250281217726, 3265.069455205255, 3367.5285460035616, 425690.0, 426243.0, 427431.0, 430909.0, 436971.81514092546, 32028.0, 56526.86946261052, 57093.714531427075, 57218.0, 57268.0, 32632.347353052806, 34083.08044574906, 34096.97709068732, 34247.4862877057, 34272.82863585666, 14203.431862725376, 14383.856161438462, 14410.351534180987, 14412.0, 14418.567442976728, 10958.397288149228, 11146.052585530158, 11160.441977901073, 12059.336736479403, 12143.696407589881, 7241.348278654841, 7301.89047164293, 7509.924900751032, 7674.386049116058, 7696.230376962259, 3171.8414079295944, 3224.500202468621, 3230.1601583588435, 3245.9450678529574, 3258.234314935988, 909.4270609515962, 914.8033172867865, 1016.2835201183149, 1060.81966065769, 1066.088494337346, 24481.265562033168, 25976.176195031654, 26837.693142111588, 28056.579151312748, 29874.0, 984.7045886234101, 1029.7734498410339, 1057.3919996002176, 1060.4962642744788, 1060.734816295926, 12713.936430317974, 14852.554423367315, 15356.616084597974, 15752.842471575366, 15754.0, 566054.0, 567083.0, 568281.0, 570300.0, 570813.0, 53919.73040134774, 54231.72884135554, 54449.72775136178, 54514.45485545094, 54601.45398546043, 1209.4376115106518, 1314.875086866727, 1338.5382043195082, 1354.4514471639002, 1364.536057740373, 29555.0, 30575.0, 30647.387052259277, 31360.529592056595, 31375.37249255048, 46649.0, 47091.0, 47168.05663886772, 47263.0, 47322.526774731814, 467682.0, 469093.0, 471926.0, 472454.0, 472840.6357968188, 25598.23205303844, 26407.471850563266, 26485.470290593694, 27429.0, 27531.31171720723, 37921.4311785329, 45242.0, 45490.0, 46027.30959035684, 46925.826854328916, 38070.42894356531, 38386.232275354894, 38477.80761096177, 38732.32782841783, 38936.80531597324, 37741.4338784913, 37753.81123094367, 38195.809020954715, 38721.0, 38784.418233725955, 37658.0, 37823.81088094542, 38082.42876356912, 38365.040873978374, 38488.0, 31599.0, 32436.83781581077, 33797.97212167278, 33958.83020584881, 34049.48925766165, 21757.0, 24357.14749983742, 24658.15063870181, 25001.874990624932, 25169.119080832083], "value": 18476.440053284285, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.322676754318, 374.64970252813623, 375.92669429461256, 388.764797297635, 409.9979500102499, 15312.69374612507, 15385.230738463075, 20238.29165979191, 20257.49356266094, 20323.695144572845, 20142.295019674315, 20560.971951402444, 20693.0, 20767.68848467274, 20803.063862126204, 5764.913526297109, 5804.780995990836, 6046.395360463963, 6084.2090528231265, 6103.969480152593, 6078.848028799272, 6113.663748493831, 6116.571839971204, 6122.816315510542, 6130.662813545253, 3923.8822835314845, 5476.835694929158, 6795.048693182958, 6806.829829254283, 6909.274526174755, 6043.063325184604, 6404.871902561969, 6826.48801339899, 6837.487188460836, 6846.383825455733, 5911.527077833763, 5919.852003699899, 5926.585139040269, 5928.525717942554, 5932.851678708023, 13489.730205395936, 18432.81567184338, 24630.935895577208, 25558.74441255582, 25830.966761329524, 1798.4874310821483, 2229.3869185973826, 2278.305116939333, 2455.6439316299216, 2543.249741326313, 9862.60549578016, 9871.457069861155, 9936.602535898555, 9960.90039099607, 10116.696499104964, 1788.8837225580296, 1853.8331550160551, 1868.3087257714596, 1905.7808352039553, 1909.6276226135908, 78152.0, 81409.77885331784, 115756.42121789421, 125434.37282813528, 127543.0, 10831.83752243717, 11040.908545607243, 11440.370779607201, 11677.88322116785, 11930.403479825975, 14357.282135893163, 14363.066400683922, 14369.712605747827, 14387.856121438861, 14389.64025899361, 8180.263776260165, 8441.282490988211, 8460.111688272766, 8571.657133714707, 8581.442206256574, 8341.24928756415, 8345.165483451729, 8411.242988130974, 8430.873536896952, 8527.0, 3751.268502642013, 4489.910201796011, 4495.887602809956, 4555.681102322872, 4578.2903649934315, 1855.4990152658977, 1902.3722171683587, 1905.104600837604, 1906.323255244396, 1907.6661584222707, 708.9012031351384, 726.5681763138774, 821.0475848016273, 887.6050157679771, 931.2177770672668, 16308.510744677678, 19623.607527849363, 20218.898905505674, 20299.28952486656, 21525.79615617876, 895.1898531828706, 906.3881879731155, 908.6002159049997, 945.2532499325594, 1026.3585259212991, 10999.23005389615, 11149.108071354272, 11225.326480411202, 11225.60710375133, 11229.887701123047, 488676.6889476625, 540290.2985485126, 548685.0, 552203.0, 555138.224308884, 131671.34164329118, 131709.0, 132958.0, 137406.3129684345, 138981.30509347387, 959.8704174936433, 965.5124162297994, 997.6558087459815, 998.960041598328, 998.9900100998897, 17507.0, 18783.71824422662, 18935.242590296784, 18991.90504047471, 19071.809281907004, 39837.203255935296, 56750.7162464185, 57178.7141064292, 57600.71199643975, 57691.94234605306, 80251.0, 82262.0, 82386.58806705927, 83586.0, 84588.39272438867, 7941.920580794118, 7950.920490795249, 8061.234182752629, 8108.554029528433, 8140.185981401813, 11080.833787493033, 12533.185342953044, 12882.35588220604, 12934.547290844775, 13108.672283192996, 10864.64191976003, 10956.726081847699, 11120.719387780462, 11189.0, 11248.100151987985, 5033.245013247895, 5090.923636145535, 7237.62485127824, 7491.70033198666, 10798.946005269923, 11247.325160490396, 11254.94372528132, 11419.714507137389, 11453.141014424118, 11579.189456737948, 7847.646855891613, 8052.597370131352, 8105.75682729519, 8195.385346098947, 8285.011597216504, 9826.852597211191, 10051.849222261819, 10816.0, 10872.836907446554, 11479.598214062467], "value": 9344.311853032617, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1393.7769956806912, 1496.1621491964502, 1650.4058538925985, 1800.1539276540022, 1845.280340667141, 7861.331786798129, 8148.266656000974, 8362.540060296682, 8938.0, 8940.821183576332, 8428.0, 8669.609867555959, 8685.73942781716, 8839.381243312971, 8856.77858053549, 2106.641870881946, 2384.1417089847682, 2406.217979156781, 2414.360194548446, 2417.721961974378, 2107.1676687708346, 2203.5372571759867, 2367.9289621311386, 2395.9161429349974, 2425.551273014489, 2338.053088499152, 2411.5418070566625, 2414.1671123462465, 2415.975840241593, 2416.7583241675866, 2366.1008816649564, 2370.4073981504625, 2372.2764556810166, 2399.076355603105, 2406.1458182345364, 2376.275236053003, 2391.7488663690237, 2394.964075538868, 2399.3042017814814, 2416.55293770653, 8811.251043661296, 8874.156955089256, 8884.200421962054, 8888.60001299943, 8895.243904268145, 4987.725675087869, 5288.894222115536, 5331.466853314638, 5340.91988620171, 5346.278252435949, 3463.8441270142903, 3590.281943611288, 3613.1328481164333, 3661.048127486846, 3674.963250367489, 1049.8635177426886, 1092.4592326798286, 1106.7011906785124, 1107.197281970573, 1122.180808010157, 11488.483018264114, 11539.48072336747, 11547.595834145848, 11549.769004619944, 11557.422128893522, 4211.0, 4427.579379958899, 4571.885702857455, 4775.450823155382, 4818.132736107535, 9888.258380621339, 9913.107820296207, 9919.553620087114, 9927.950360248153, 9975.052370024836, 5306.230596563477, 5308.991291654651, 5313.734313284321, 5316.122839731478, 5323.254744335796, 4734.384530011076, 4839.564439200489, 4886.731229782325, 4896.2410826321975, 4899.0, 3459.3427248822645, 3906.628870257321, 3933.1543718100747, 3995.440638310639, 4069.450624165759, 1181.4211036592008, 1224.3510939202238, 1251.605744190577, 1280.3982128399637, 1281.557862537423, 757.3070640364116, 800.1798156889236, 820.6102101501739, 911.3939230411702, 916.862470629411, 5582.944170558242, 5710.885782284331, 5844.532437404998, 5908.0, 5994.040953447515, 769.9730509432143, 789.3345041961456, 800.371708209053, 818.0919179710636, 938.9718308450621, 3416.8804091856664, 3485.233248685285, 3765.2657731742597, 3791.3554695701796, 3881.8835434936996, 10418.322809017389, 10727.517261723242, 10834.404107774148, 10845.728856778485, 10856.34861908288, 10787.190960677981, 10810.945945270381, 10815.67552973412, 10839.729006774736, 10853.620123295646, 8158.347332213409, 8290.585470726439, 8291.792705182419, 8308.667653293922, 8323.292520135736, 5367.221752845738, 5560.777568897199, 5637.689927054052, 5687.914681279702, 5708.771649134154, 9795.85306220393, 9967.50162491858, 10034.448105353984, 10077.445740484343, 10318.48407579603, 9854.162396196403, 10216.335938163995, 10293.0, 10314.587416503256, 10422.947885260524, 6976.860462790817, 7108.644567771694, 7120.465965052538, 7124.501284910007, 7126.180489243596, 8141.22658347456, 8225.588720564068, 8232.0, 8239.629216685144, 8245.13426090252, 7348.191698913009, 7449.6275186241555, 7543.245675432413, 7633.6564854580565, 7649.349805266392, 7255.347018768336, 7381.815454613463, 7388.593627350548, 7407.1852096268285, 7439.293267139613, 5777.6822274775295, 6104.779044191268, 6378.883695353387, 6875.140607424072, 6995.720171193096, 4156.854510092132, 4387.8464253752245, 4879.56083952446, 4946.653734238569, 5022.84931452057, 4858.78135483897, 4934.901301973869, 4962.181240095344, 5002.17464118431, 5032.043911656773], "value": 4530.342386458983, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.70222541851456, 303.7494067394399, 311.24367786279345, 313.2435169066704, 319.41546969046647, 5156.819511317105, 5257.974694934489, 5485.451454854512, 5505.394406615279, 5508.807191748289, 5237.214417837326, 5419.295491586088, 5469.753861076251, 5483.616146869721, 5491.588130890179, 4145.17096580685, 4148.419221309019, 4152.484343214735, 4172.7496350218935, 4177.853775117872, 3182.9045128646176, 3356.7314614830757, 3558.861164427384, 3644.9406085561704, 3726.5093962415117, 3837.9040523986982, 4032.3749818777987, 4056.797160141996, 4070.4708387909677, 4075.6331930126285, 3530.5939816921127, 3809.657130858222, 3886.805659717031, 3917.569067402612, 4000.0399704218958, 3457.135716070982, 3475.33968545978, 3489.6510348965153, 3493.916885765421, 3498.860045598173, 4363.759993200372, 4999.3000979862845, 5176.722242213414, 5255.76349064293, 5265.552428043621, 3942.8422863085443, 4453.506036619471, 4586.472555656109, 4704.506026867198, 4721.480637129913, 4739.502352253033, 4782.665213435061, 5539.556835453154, 5572.637778544382, 5694.800681976152, 3388.0852169914233, 3584.62361452049, 3791.7345785795005, 3792.715546334008, 3810.0, 6013.75944962201, 6016.308124565643, 6020.073576455536, 6108.694565271719, 6114.11345354921, 3543.7519373644113, 3672.1186915140174, 3815.7328986970647, 3913.2369188008065, 3957.6635985941516, 3365.8317084145697, 3849.7305188637088, 3982.2721435816557, 3999.1801680655394, 4017.4978127734034, 4635.6986795858165, 4651.2557990721325, 4659.254519276899, 4680.9297860532115, 4687.273472611751, 5548.250986116823, 5758.24373566058, 5768.0, 5839.0949402842625, 5885.411458854081, 1168.3340495917416, 1235.1415766042694, 1270.4219580091221, 1285.8427415326094, 1312.8162057311984, 761.6001599160485, 804.9839003219903, 903.4895284164426, 907.111031189438, 911.9922485653258, 4264.722793018444, 4446.6220371268155, 4487.371767952489, 5360.597955153379, 5408.78364865401, 4666.976665116653, 4936.456989731127, 5724.4561766632105, 5835.679037652885, 5904.110684580893, 4849.75751212438, 5059.229269755556, 5160.225852504243, 5277.577793776489, 5288.910880202298, 2078.0752565108683, 2455.693038370204, 2528.102523604131, 2586.2025892004876, 2609.9120703437175, 4016.7389119707127, 4761.262004389325, 4889.804407823718, 5055.646104772704, 5058.974705126451, 4465.0, 4485.0, 4643.585335625456, 4897.131266299654, 5504.779808807602, 4597.368079217381, 5809.750903555756, 6028.909566356596, 6177.96911015442, 6200.194848992067, 5867.743402411532, 5966.403359663999, 6035.969820151046, 6079.604825686316, 6080.209572755513, 5196.53231209193, 5245.370555533361, 5395.379531353945, 5875.764969401176, 5892.9116063258225, 5269.050451332878, 5636.577256705846, 5990.940090599038, 6139.386061393825, 6152.907706384497, 3971.146203566247, 3976.5228172619472, 3988.387606117997, 3990.6654607055198, 3995.6603688686787, 5056.873578160428, 5631.690257035739, 5799.3040835100055, 5807.303123625192, 6014.458698717136, 4904.6566740327835, 5476.481902430591, 5559.749811258584, 5670.8015219467125, 5726.997775389374, 5557.499825015606, 5735.369109398046, 5751.712414379348, 5827.329857066492, 5857.4184970058695, 5408.971635636636, 5543.833684989457, 5588.469095435927, 5598.496135347838, 5659.518940889906, 5776.133579963039, 5789.884202316014, 5806.535477161732, 5810.883782324246, 5826.388229235869, 4526.307014059499, 4661.86014419568, 5608.971955140361, 5725.312962444534, 5736.6558006519745], "value": 4052.5103020882434, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [705.8235441139715, 724.6738967464639, 756.4629113329536, 784.2588753627821, 836.8744688296755, 15159.848401515981, 15198.620034499116, 15874.285657145427, 15906.761398579016, 15925.203739813021, 14002.159870407757, 14225.14649121051, 14251.643708907282, 14256.287185640702, 14269.0, 702.8734827731006, 708.638594316897, 869.7912500999762, 883.1786438612088, 885.7962668586229, 859.8839156713826, 872.9039805621377, 879.5162660536711, 884.2218847414266, 886.3795343259711, 908.6774195160722, 908.8954770201445, 908.9727308180766, 909.6452383570413, 911.4576826788051, 730.2587873308596, 745.2621904314743, 900.522722956834, 901.6438506789781, 902.6073657958776, 562.980295689653, 563.7350445290707, 659.5910535467992, 670.9261981182066, 672.3175976383974, 14398.064125831788, 14476.710465790626, 14502.1298722077, 14503.347349369198, 14518.128912265298, 13224.603261902159, 13532.391042403115, 13587.59237222885, 13863.168209907337, 13973.0, 11227.0, 11699.35653539055, 11812.586559470463, 11954.760904781942, 12084.335361555111, 4569.954300456986, 4609.539046095364, 4611.861644150681, 4676.859694209145, 4819.975900120512, 106825.0, 107690.92309076966, 108928.0, 109736.61053557848, 111849.0, 10485.633002844865, 10681.519331630096, 10740.516676749565, 10869.891301086887, 11120.499577519031, 20495.795042049685, 20634.484137896372, 20667.17331306761, 20690.0, 20830.687539686918, 7753.1083925348175, 7764.650590723431, 7834.569098699626, 7906.367490600738, 8021.759347219593, 4355.216061109031, 4388.056567837866, 4431.977840110843, 4445.2887537993765, 4561.908761824811, 3341.281624450755, 3495.615482296945, 3526.964730352715, 3544.9365190442763, 3564.0, 679.9286074962058, 692.8198668346165, 697.6476879175983, 701.7999870037015, 702.0452185028357, 509.67380876239235, 629.8897692903723, 673.095592518282, 699.2448155991517, 701.113091938698, 8554.529500877512, 8903.510306933182, 9194.53039225477, 9325.207357374698, 9613.51932403391, 569.6240481282334, 601.5758889982552, 652.350910843704, 674.5514233035075, 674.7435974329821, 4326.242907491176, 4617.0, 4649.680577201732, 4671.766411679402, 4758.04839032188, 118427.81572184167, 139411.0, 143423.282883587, 148917.0, 152119.0, 106846.0, 134468.0, 141747.70866857975, 142417.0, 147890.0, 72547.63726181335, 88117.55941220254, 95123.52438237764, 99110.0, 107821.46089269503, 9464.763380915532, 9471.289653275893, 9487.193588545051, 9554.426734395958, 9692.127708506268, 61980.69009654923, 62622.0, 63214.367856322686, 66282.0, 66426.0, 89029.0, 95845.0, 96374.0, 96535.51732241294, 98767.0, 12825.935870320589, 12956.740865182832, 12972.740545188855, 13191.944644428231, 13344.532941347006, 14663.48677796272, 14703.705925881637, 14829.0, 14932.477363292233, 14942.178180199759, 13503.729925401634, 13643.795343069663, 13809.723805523632, 13825.51610693621, 14025.3688584016, 13315.087579617988, 13714.314284285945, 13848.792268115785, 13978.161310321413, 14162.433502659778, 11184.440777961232, 12758.043146763845, 13208.339583021003, 13336.799948000593, 13869.583912482642, 10411.219158562986, 10505.527251273557, 10697.946510267398, 10785.137189025016, 10929.508172132431, 10984.835227471434, 11119.888801112209, 11201.327920324806, 11210.66368008961, 11384.544618215179], "value": 7792.659673112341, "unit": "score"}], "name": "dromaeo_css", "value": 7205.110813114015, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 117.0, 113.0, 112.0, 115.0, 150.0, 118.0, 112.0, 122.0], "value": 118.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 110.0, 135.0, 174.0, 126.0, 160.0, 159.0, 136.0, 161.0, 108.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 185.0, 186.0, 185.0, 185.0, 182.0, 182.0, 184.0, 185.0, 185.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 157.0, 58.0, 60.0, 59.0, 69.0, 138.0, 66.0], "value": 83.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 79.0, 135.0, 71.0, 78.0, 87.0, 153.0, 99.0, 102.0, 116.0], "value": 100.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 86.0, 85.0, 86.0, 84.0, 87.0, 84.0, 96.0, 130.0], "value": 90.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 86.0, 86.0, 84.0, 83.0, 86.0, 99.0, 134.0, 134.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 54.0, 55.0, 54.0, 53.0, 55.0, 55.0, 54.0, 57.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 71.0, 82.0, 74.0, 77.0, 102.0, 103.0, 60.0, 94.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 88.0, 87.0, 86.0, 88.0, 85.0, 86.0, 131.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 138.0, 138.0, 136.0, 164.0, 134.0, 130.0, 139.0, 141.0], "value": 140.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 176.0, 169.0, 168.0, 171.0, 169.0, 166.0, 168.0, 171.0, 168.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 67.0, 67.0, 69.0, 66.0, 67.0, 66.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1510.8999999999999, "alertThreshold": 2.0}]} 16:33:38 INFO - Return code: 0 16:33:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:33:38 INFO - # TBPL SUCCESS # 16:33:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:33:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:33:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:33:38 INFO - 2016-09-01 16:33:38.718 screenresolution[1953:21146] starting screenresolution argv=screenresolution get 16:33:38 INFO - 2016-09-01 16:33:38.721 screenresolution[1953:21146] Display 0: 1600x1200x32@60 16:33:38 INFO - 2016-09-01 16:33:38.727 screenresolution[1954:21149] starting screenresolution argv=screenresolution list 16:33:38 INFO - Available Modes on Display 0 16:33:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:33:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:33:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:33:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:33:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:33:38 INFO - Intel Iris: 16:33:38 INFO - Chipset Model: Intel Iris 16:33:38 INFO - Type: GPU 16:33:38 INFO - Bus: Built-In 16:33:38 INFO - VRAM (Dynamic, Max): 1536 MB 16:33:38 INFO - Vendor: Intel (0x8086) 16:33:38 INFO - Device ID: 0x0a2e 16:33:38 INFO - Revision ID: 0x0009 16:33:38 INFO - Displays: 16:33:38 INFO - Display: 16:33:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:33:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:33:38 INFO - Display Serial Number: 1600x1200 60 16:33:38 INFO - Main Display: Yes 16:33:38 INFO - Mirror: Off 16:33:38 INFO - Online: Yes 16:33:38 INFO - Rotation: Supported 16:33:38 INFO - Return code: 0 16:33:38 INFO - Running post-action listener: _resource_record_post_action 16:33:38 INFO - [mozharness: 2016-09-01 23:33:38.961618Z] Finished run-tests step (success) 16:33:38 INFO - Running post-run listener: _resource_record_post_run 16:33:39 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 417792; Write bytes: 704756736; Read time: 8; Write time: 1941 16:33:39 INFO - TinderboxPrint: CPU usage
53.6% 16:33:39 INFO - TinderboxPrint: I/O read bytes / time
417,792 / 8 16:33:39 INFO - TinderboxPrint: I/O write bytes / time
704,756,736 / 1,941 16:33:39 INFO - TinderboxPrint: CPU idle
2,253.4 (46.4%) 16:33:39 INFO - TinderboxPrint: CPU system
968.7 (20.0%) 16:33:39 INFO - TinderboxPrint: CPU user
1,631.6 (33.6%) 16:33:39 INFO - TinderboxPrint: Swap in / out
406,712,320 / 0 16:33:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 402752000; Write bytes: 397747712; Read time: 29663; Write time: 803 16:33:39 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 380928; Write bytes: 294274560; Read time: 7; Write time: 1098 16:33:39 INFO - Running post-run listener: _upload_blobber_files 16:33:39 INFO - Blob upload gear active. 16:33:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:33:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:33:39 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'] 16:33:39 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 16:33:39 INFO - (blobuploader) - INFO - Open directory for files ... 16:33:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:33:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:33:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:41 INFO - (blobuploader) - INFO - Done attempting. 16:33:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:33:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:33:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:42 INFO - (blobuploader) - INFO - Done attempting. 16:33:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:33:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:33:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:33:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:33:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:33:45 INFO - (blobuploader) - INFO - Done attempting. 16:33:45 INFO - (blobuploader) - INFO - Iteration through files over. 16:33:45 INFO - Return code: 0 16:33:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:33:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:33:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f6de76e6119a9b1fcb0411018d7a4e41c2af3fdeeb9619193d4c8bc93866c9e9a5f58fdcb60a249a9fdae0a182ecc668f8ac7435caeb3bc238b2bdcf1bdad3ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5ded0635f1c3ac92c1356749b90f26476b4ac6c2dd01aa6038bde8f1af49e21082a22ec82b9b97a68dea6e518f4a43a4631ac4d877bd9254d490f5f73cb38fb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/acb4425297ea43da30cfadb925fe100f81d11a3330d2226687773cc4c8b495155dbf1ab06c739904e931c0534fe13285876eae662de55ae43378f9f0b8a97c4d"} 16:33:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:33:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:33:45 INFO - Contents: 16:33:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f6de76e6119a9b1fcb0411018d7a4e41c2af3fdeeb9619193d4c8bc93866c9e9a5f58fdcb60a249a9fdae0a182ecc668f8ac7435caeb3bc238b2bdcf1bdad3ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5ded0635f1c3ac92c1356749b90f26476b4ac6c2dd01aa6038bde8f1af49e21082a22ec82b9b97a68dea6e518f4a43a4631ac4d877bd9254d490f5f73cb38fb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/acb4425297ea43da30cfadb925fe100f81d11a3330d2226687773cc4c8b495155dbf1ab06c739904e931c0534fe13285876eae662de55ae43378f9f0b8a97c4d"} 16:33:45 INFO - Running post-run listener: copy_logs_to_upload_dir 16:33:45 INFO - Copying logs to upload dir... 16:33:45 INFO - mkdir: /builds/slave/test/build/upload/logs 16:33:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.047243 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-01 16:33:45.329125) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 16:33:45.332407) ========= 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.89QxAKv03E/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.E9LB1smlKs/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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/f6de76e6119a9b1fcb0411018d7a4e41c2af3fdeeb9619193d4c8bc93866c9e9a5f58fdcb60a249a9fdae0a182ecc668f8ac7435caeb3bc238b2bdcf1bdad3ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5ded0635f1c3ac92c1356749b90f26476b4ac6c2dd01aa6038bde8f1af49e21082a22ec82b9b97a68dea6e518f4a43a4631ac4d877bd9254d490f5f73cb38fb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/acb4425297ea43da30cfadb925fe100f81d11a3330d2226687773cc4c8b495155dbf1ab06c739904e931c0534fe13285876eae662de55ae43378f9f0b8a97c4d"} build_url:https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010615 build_url: 'https://queue.taskcluster.net/v1/task/S7QElxGDR5aR3-sv18btuA/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/f6de76e6119a9b1fcb0411018d7a4e41c2af3fdeeb9619193d4c8bc93866c9e9a5f58fdcb60a249a9fdae0a182ecc668f8ac7435caeb3bc238b2bdcf1bdad3ef", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/5ded0635f1c3ac92c1356749b90f26476b4ac6c2dd01aa6038bde8f1af49e21082a22ec82b9b97a68dea6e518f4a43a4631ac4d877bd9254d490f5f73cb38fb1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/acb4425297ea43da30cfadb925fe100f81d11a3330d2226687773cc4c8b495155dbf1ab06c739904e931c0534fe13285876eae662de55ae43378f9f0b8a97c4d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 16:33:45.368408) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:33:45.368730) ========= 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.89QxAKv03E/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.E9LB1smlKs/Listeners TMPDIR=/var/folders/64/m7jhlc3x3b32lz348yvd80pc00000w/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.005051 ========= master_lag: 0.31 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 16:33:45.679897) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 16:33:45.680251) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 16:33:45.680611) ========= ========= Total master_lag: 2.48 =========