builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-074 starttime: 1491666656.32 results: success (0) revision: 45fd7ec20907695251ce9c87cd446d44cb8296ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:56.316854) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:56.317319) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:56.317589) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:56.332202) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:56.332502) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-04-08 08:50:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-04-08 08:50:57 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130756 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:56.476861) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:56.477169) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014274 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:56.526659) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-08 08:50:56.527013) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 45fd7ec20907695251ce9c87cd446d44cb8296ff --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 45fd7ec20907695251ce9c87cd446d44cb8296ff --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-04-08 08:50:57,972 truncating revision to first 12 chars 2017-04-08 08:50:57,972 Setting DEBUG logging. 2017-04-08 08:50:57,972 attempt 1/10 2017-04-08 08:50:57,972 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/45fd7ec20907?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-08 08:50:58,685 unpacking tar archive at: mozilla-aurora-45fd7ec20907/testing/mozharness/ program finished with exit code 0 elapsedTime=1.018304 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-08 08:50:57.561514) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:57.562053) ========= script_repo_revision: 45fd7ec20907695251ce9c87cd446d44cb8296ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:57.562637) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:57.563121) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-08 08:50:57.574488) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-08 08:50:57.575196) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:50:59 INFO - MultiFileLogger online at 20170408 08:50:59 in /builds/slave/test 08:50:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:50:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:50:59 INFO - {'append_to_log': False, 08:50:59 INFO - 'base_work_dir': '/builds/slave/test', 08:50:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:50:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:50:59 INFO - 'branch': 'Mozilla-Aurora', 08:50:59 INFO - 'buildbot_json_path': 'buildprops.json', 08:50:59 INFO - 'config_files': ('talos/linux_config.py',), 08:50:59 INFO - 'default_actions': ('clobber', 08:50:59 INFO - 'read-buildbot-config', 08:50:59 INFO - 'download-and-extract', 08:50:59 INFO - 'populate-webroot', 08:50:59 INFO - 'create-virtualenv', 08:50:59 INFO - 'install', 08:50:59 INFO - 'run-tests'), 08:50:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:50:59 INFO - 'download_minidump_stackwalk': True, 08:50:59 INFO - 'download_symbols': 'ondemand', 08:50:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:50:59 INFO - 'tooltool.py': '/tools/tooltool.py', 08:50:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:50:59 INFO - '/tools/misc-python/virtualenv.py')}, 08:50:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:50:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:50:59 INFO - 'gecko_profile': False, 08:50:59 INFO - 'gecko_profile_interval': 0, 08:50:59 INFO - 'installer_path': 'installer.exe', 08:50:59 INFO - 'log_level': 'info', 08:50:59 INFO - 'log_name': 'talos', 08:50:59 INFO - 'log_to_console': True, 08:50:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:50:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:50:59 INFO - 'opt_config_files': (), 08:50:59 INFO - 'pip_index': False, 08:50:59 INFO - 'suite': 'dromaeojs', 08:50:59 INFO - 'system_bits': '32', 08:50:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:50:59 INFO - 'title': 'talos-linux64-ix-074', 08:50:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:50:59 INFO - 'use_talos_json': True, 08:50:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:50:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:50:59 INFO - 'work_dir': 'build'} 08:50:59 INFO - [mozharness: 2017-04-08 15:50:59.118155Z] Running clobber step. 08:50:59 INFO - Running pre-action listener: _resource_record_pre_action 08:50:59 INFO - Running main action method: clobber 08:50:59 INFO - rmtree: /builds/slave/test/build 08:50:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:51:00 INFO - Running post-action listener: _resource_record_post_action 08:51:00 INFO - [mozharness: 2017-04-08 15:51:00.057013Z] Finished clobber step (success) 08:51:00 INFO - [mozharness: 2017-04-08 15:51:00.057116Z] Running read-buildbot-config step. 08:51:00 INFO - Running pre-action listener: _resource_record_pre_action 08:51:00 INFO - Running main action method: read_buildbot_config 08:51:00 INFO - Using buildbot properties: 08:51:00 INFO - { 08:51:00 INFO - "project": "", 08:51:00 INFO - "product": "firefox", 08:51:00 INFO - "who": "ffxbld@noreply.mozilla.org", 08:51:00 INFO - "installer_path": "public/build/target.tar.bz2", 08:51:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 08:51:00 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 08:51:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 08:51:00 INFO - "stage_platform": "linux64-pgo", 08:51:00 INFO - "basedir": "/builds/slave/test", 08:51:00 INFO - "buildnumber": 1147, 08:51:00 INFO - "platform": "ubuntu64_hw", 08:51:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:51:00 INFO - "slavebuilddir": "test", 08:51:00 INFO - "taskId": "aod8A3GhT7C63m3idPEEAQ", 08:51:00 INFO - "branch": "mozilla-aurora", 08:51:00 INFO - "script_repo_revision": "production", 08:51:00 INFO - "revision": "45fd7ec20907695251ce9c87cd446d44cb8296ff", 08:51:00 INFO - "slavename": "talos-linux64-ix-074", 08:51:00 INFO - "repo_path": "releases/mozilla-aurora" 08:51:00 INFO - } 08:51:00 INFO - Finding installer, test and symbols from parent task. 08:51:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aod8A3GhT7C63m3idPEEAQ'}, attempt #1 08:51:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aod8A3GhT7C63m3idPEEAQ'}, attempt #1 08:51:00 INFO - Task dependencies: e2LuEEwMSFOKQt3xnwKvOw 08:51:00 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw'}, attempt #1 08:51:01 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.tar.bz2 08:51:01 INFO - Set test_url: https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/test_packages.json 08:51:01 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.crashreporter-symbols.zip 08:51:01 INFO - Running post-action listener: _resource_record_post_action 08:51:01 INFO - [mozharness: 2017-04-08 15:51:01.413793Z] Finished read-buildbot-config step (success) 08:51:01 INFO - [mozharness: 2017-04-08 15:51:01.414014Z] Running download-and-extract step. 08:51:01 INFO - Running pre-action listener: _resource_record_pre_action 08:51:01 INFO - Running main action method: download_and_extract 08:51:01 INFO - mkdir: /builds/slave/test/build/tests 08:51:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:01 INFO - trying https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.test_packages.json 08:51:01 INFO - Downloading https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 08:51:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 08:51:01 INFO - Downloaded 896 bytes. 08:51:01 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 08:51:01 INFO - Using the following test package requirements: 08:51:01 INFO - {u'common': [u'target.common.tests.zip'], 08:51:01 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 08:51:01 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 08:51:01 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 08:51:01 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 08:51:01 INFO - u'mozbase': [u'target.common.tests.zip'], 08:51:01 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 08:51:01 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 08:51:01 INFO - u'web-platform': [u'target.common.tests.zip', 08:51:01 INFO - u'target.web-platform.tests.zip'], 08:51:01 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 08:51:01 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 08:51:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.common.tests.zip 08:51:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:51:01 INFO - Fetch https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.common.tests.zip into memory 08:51:03 INFO - Expected file size: 59540438 08:51:03 INFO - Obtained file size: 59540438 08:51:12 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 08:51:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.common.tests.zip 08:51:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.common.tests.zip'}, attempt #1 08:51:12 INFO - Fetch https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.common.tests.zip into memory 08:51:13 INFO - Expected file size: 59540438 08:51:13 INFO - Obtained file size: 59540438 08:51:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.talos.tests.zip 08:51:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:51:24 INFO - Fetch https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.talos.tests.zip into memory 08:51:25 INFO - Expected file size: 14404424 08:51:25 INFO - Obtained file size: 14404424 08:51: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')]} 08:51:25 INFO - trying https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.tar.bz2 08:51:25 INFO - Downloading https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:51:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:51:26 INFO - Downloaded 66739352 bytes. 08:51:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.tar.bz2 08:51:26 INFO - mkdir: /builds/slave/test/properties 08:51:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:51:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:51:26 INFO - Contents: 08:51:26 INFO - build_url:https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.tar.bz2 08:51:26 INFO - Running post-action listener: _resource_record_post_action 08:51:26 INFO - Running post-action listener: set_extra_try_arguments 08:51:26 INFO - [mozharness: 2017-04-08 15:51:26.698748Z] Finished download-and-extract step (success) 08:51:26 INFO - [mozharness: 2017-04-08 15:51:26.698845Z] Running populate-webroot step. 08:51:26 INFO - Running pre-action listener: _resource_record_pre_action 08:51:26 INFO - Running main action method: populate_webroot 08:51:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:51:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:51:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:51:26 INFO - '--tppagecycles', 08:51:26 INFO - '7'], 08:51:26 INFO - 'tests': ['tsvgm']}}, 08:51:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:51:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:51:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:51:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:51:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:51:26 INFO - 'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:51:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:51:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:51:26 INFO - 'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['damp', 'tps']}, 08:51:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:51:26 INFO - 'tests': ['damp', 'tps']}, 08:51:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['dromaeo_dom']}, 08:51:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:51:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['basic_compositor_video']}, 08:51:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:51:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['a11yr', 08:51:26 INFO - 'ts_paint', 08:51:26 INFO - 'tpaint', 08:51:26 INFO - 'sessionrestore', 08:51:26 INFO - 'sessionrestore_no_auto_restore', 08:51:26 INFO - 'tabpaint']}, 08:51:26 INFO - 'other-e10s': {'tests': ['a11yr', 08:51:26 INFO - 'ts_paint', 08:51:26 INFO - 'tpaint', 08:51:26 INFO - 'sessionrestore', 08:51:26 INFO - 'sessionrestore_no_auto_restore', 08:51:26 INFO - 'tabpaint']}, 08:51:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:51:26 INFO - 'ts_paint', 08:51:26 INFO - 'tpaint', 08:51:26 INFO - 'sessionrestore', 08:51:26 INFO - 'sessionrestore_no_auto_restore', 08:51:26 INFO - 'tabpaint']}, 08:51:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:51:26 INFO - 'ts_paint', 08:51:26 INFO - 'tpaint', 08:51:26 INFO - 'sessionrestore', 08:51:26 INFO - 'sessionrestore_no_auto_restore', 08:51:26 INFO - 'tabpaint']}, 08:51:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['a11yr', 08:51:26 INFO - 'ts_paint', 08:51:26 INFO - 'tpaint', 08:51:26 INFO - 'sessionrestore', 08:51:26 INFO - 'sessionrestore_no_auto_restore', 08:51:26 INFO - 'tabpaint']}, 08:51:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['a11yr', 08:51:26 INFO - 'ts_paint', 08:51:26 INFO - 'tpaint', 08:51:26 INFO - 'sessionrestore', 08:51:26 INFO - 'sessionrestore_no_auto_restore', 08:51:26 INFO - 'tabpaint']}, 08:51:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['tsvgx', 08:51:26 INFO - 'tsvgr_opacity', 08:51:26 INFO - 'tart', 08:51:26 INFO - 'tscrollx', 08:51:26 INFO - 'cart', 08:51:26 INFO - 'tsvg_static']}, 08:51:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:51:26 INFO - 'tsvgr_opacity', 08:51:26 INFO - 'tart', 08:51:26 INFO - 'tscrollx', 08:51:26 INFO - 'cart', 08:51:26 INFO - 'tsvg_static']}, 08:51:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:51:26 INFO - 'talos_options': ['--disable-e10s'], 08:51:26 INFO - 'tests': ['tp5o']}, 08:51:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:51:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:51:26 INFO - 'talos_options': ['--disable-e10s', 08:51:26 INFO - '--xperf_path', 08:51:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:26 INFO - 'tests': ['tp5n']}, 08:51:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:51:26 INFO - 'talos_options': ['--xperf_path', 08:51:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:51:26 INFO - 'tests': ['tp5n']}}, 08:51:26 INFO - 'talos.zip': {'path': '', 08:51:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:51:26 INFO - Running post-action listener: _resource_record_post_action 08:51:26 INFO - [mozharness: 2017-04-08 15:51:26.712026Z] Finished populate-webroot step (success) 08:51:26 INFO - [mozharness: 2017-04-08 15:51:26.712116Z] Running create-virtualenv step. 08:51:26 INFO - Running pre-action listener: _resource_record_pre_action 08:51:26 INFO - Running main action method: create_virtualenv 08:51:26 INFO - Creating virtualenv /builds/slave/test/build/venv 08:51:26 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 08:51:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:51:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:51:26 INFO - Using real prefix '/usr' 08:51:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:51:27 INFO - Installing distribute.............................................................................................................................................................................................done. 08:51:29 INFO - Installing pip.................done. 08:51:29 INFO - Return code: 0 08:51:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:51:29 INFO - Reading from file tmpfile_stdout 08:51:29 INFO - Output received: 08:51:29 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:51:29 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:51:29 INFO - Using env: {'DISPLAY': ':0', 08:51:29 INFO - 'HOME': '/home/cltbld', 08:51:29 INFO - 'LANG': 'en_US.UTF-8', 08:51:29 INFO - 'LANGUAGE': 'en_US:en', 08:51:29 INFO - 'LOGNAME': 'cltbld', 08:51:29 INFO - 'MAIL': '/var/mail/cltbld', 08:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:29 INFO - 'MOZ_NO_REMOTE': '1', 08:51:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:29 INFO - 'NO_EM_RESTART': '1', 08:51:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:29 INFO - 'PWD': '/builds/slave/test', 08:51:29 INFO - 'SHELL': '/bin/bash', 08:51:29 INFO - 'SHLVL': '1', 08:51:29 INFO - 'TERM': 'linux', 08:51:29 INFO - 'TMOUT': '86400', 08:51:29 INFO - 'USER': 'cltbld', 08:51:29 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:29 INFO - '_': '/tools/buildbot/bin/python'} 08:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:29 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 08:51:29 INFO - Cleaning up... 08:51:29 INFO - Return code: 0 08:51:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:51:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:51:29 INFO - Using env: {'DISPLAY': ':0', 08:51:29 INFO - 'HOME': '/home/cltbld', 08:51:29 INFO - 'LANG': 'en_US.UTF-8', 08:51:29 INFO - 'LANGUAGE': 'en_US:en', 08:51:29 INFO - 'LOGNAME': 'cltbld', 08:51:29 INFO - 'MAIL': '/var/mail/cltbld', 08:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:29 INFO - 'MOZ_NO_REMOTE': '1', 08:51:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:29 INFO - 'NO_EM_RESTART': '1', 08:51:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:29 INFO - 'PWD': '/builds/slave/test', 08:51:29 INFO - 'SHELL': '/bin/bash', 08:51:29 INFO - 'SHLVL': '1', 08:51:29 INFO - 'TERM': 'linux', 08:51:29 INFO - 'TMOUT': '86400', 08:51:29 INFO - 'USER': 'cltbld', 08:51:29 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:29 INFO - '_': '/tools/buildbot/bin/python'} 08:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:29 INFO - Downloading/unpacking psutil>=3.1.1 08:51:29 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 08:51:29 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 08:51:29 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 08:51:29 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 08:51:29 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 08:51:29 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 08:51:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:51:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:51:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:51:32 INFO - Installing collected packages: psutil 08:51:32 INFO - Running setup.py install for psutil 08:51:32 INFO - building 'psutil._psutil_linux' extension 08:51:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 08:51:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 08:51:32 INFO - building 'psutil._psutil_posix' extension 08:51:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 08:51:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 08:51:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:51:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:51:32 INFO - Successfully installed psutil 08:51:32 INFO - Cleaning up... 08:51:32 INFO - Return code: 0 08:51:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:51:32 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')]} 08:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:32 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')]} 08:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:32 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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:32 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 08:51:32 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 08:51:32 INFO - Using env: {'DISPLAY': ':0', 08:51:32 INFO - 'HOME': '/home/cltbld', 08:51:32 INFO - 'LANG': 'en_US.UTF-8', 08:51:32 INFO - 'LANGUAGE': 'en_US:en', 08:51:32 INFO - 'LOGNAME': 'cltbld', 08:51:32 INFO - 'MAIL': '/var/mail/cltbld', 08:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:32 INFO - 'MOZ_NO_REMOTE': '1', 08:51:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:32 INFO - 'NO_EM_RESTART': '1', 08:51:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:32 INFO - 'PWD': '/builds/slave/test', 08:51:32 INFO - 'SHELL': '/bin/bash', 08:51:32 INFO - 'SHLVL': '1', 08:51:32 INFO - 'TERM': 'linux', 08:51:32 INFO - 'TMOUT': '86400', 08:51:32 INFO - 'USER': 'cltbld', 08:51:32 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:32 INFO - '_': '/tools/buildbot/bin/python'} 08:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:51:32 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 08:51:32 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 08:51:32 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 08:51:32 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 08:51:32 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 08:51:32 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 08:51:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:51:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:51:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:51:34 INFO - Installing collected packages: mozsystemmonitor 08:51:34 INFO - Running setup.py install for mozsystemmonitor 08:51:34 INFO - Successfully installed mozsystemmonitor 08:51:34 INFO - Cleaning up... 08:51:35 INFO - Return code: 0 08:51:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:51:35 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')]} 08:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:35 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')]} 08:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:35 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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:35 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 08:51:35 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 08:51:35 INFO - Using env: {'DISPLAY': ':0', 08:51:35 INFO - 'HOME': '/home/cltbld', 08:51:35 INFO - 'LANG': 'en_US.UTF-8', 08:51:35 INFO - 'LANGUAGE': 'en_US:en', 08:51:35 INFO - 'LOGNAME': 'cltbld', 08:51:35 INFO - 'MAIL': '/var/mail/cltbld', 08:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:35 INFO - 'MOZ_NO_REMOTE': '1', 08:51:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:35 INFO - 'NO_EM_RESTART': '1', 08:51:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:35 INFO - 'PWD': '/builds/slave/test', 08:51:35 INFO - 'SHELL': '/bin/bash', 08:51:35 INFO - 'SHLVL': '1', 08:51:35 INFO - 'TERM': 'linux', 08:51:35 INFO - 'TMOUT': '86400', 08:51:35 INFO - 'USER': 'cltbld', 08:51:35 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:35 INFO - '_': '/tools/buildbot/bin/python'} 08:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:35 INFO - Downloading/unpacking jsonschema==2.5.1 08:51:35 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 08:51:35 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 08:51:35 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 08:51:35 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 08:51:35 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 08:51:35 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 08:51:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:51:37 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:51:37 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 08:51:37 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 08:51:37 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 08:51:37 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 08:51:37 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 08:51:37 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 08:51:37 INFO - Downloading functools32-3.2.3-2.tar.gz 08:51:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:51:37 INFO - warning: no files found matching '*.txt' 08:51:37 INFO - no previously-included directories found matching 'build' 08:51:37 INFO - no previously-included directories found matching 'dist' 08:51:37 INFO - no previously-included directories found matching '.git*' 08:51:37 INFO - Installing collected packages: jsonschema, functools32 08:51:37 INFO - Running setup.py install for functools32 08:51:37 INFO - warning: no files found matching '*.txt' 08:51:37 INFO - no previously-included directories found matching 'build' 08:51:37 INFO - no previously-included directories found matching 'dist' 08:51:37 INFO - no previously-included directories found matching '.git*' 08:51:37 INFO - Successfully installed jsonschema functools32 08:51:37 INFO - Cleaning up... 08:51:37 INFO - Return code: 0 08:51:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:51:37 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')]} 08:51:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:37 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')]} 08:51:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:37 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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:37 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 08:51:37 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 08:51:37 INFO - Using env: {'DISPLAY': ':0', 08:51:37 INFO - 'HOME': '/home/cltbld', 08:51:37 INFO - 'LANG': 'en_US.UTF-8', 08:51:37 INFO - 'LANGUAGE': 'en_US:en', 08:51:37 INFO - 'LOGNAME': 'cltbld', 08:51:37 INFO - 'MAIL': '/var/mail/cltbld', 08:51:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:37 INFO - 'MOZ_NO_REMOTE': '1', 08:51:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:37 INFO - 'NO_EM_RESTART': '1', 08:51:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:37 INFO - 'PWD': '/builds/slave/test', 08:51:37 INFO - 'SHELL': '/bin/bash', 08:51:37 INFO - 'SHLVL': '1', 08:51:37 INFO - 'TERM': 'linux', 08:51:37 INFO - 'TMOUT': '86400', 08:51:37 INFO - 'USER': 'cltbld', 08:51:37 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:37 INFO - '_': '/tools/buildbot/bin/python'} 08:51:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:51:38 INFO - Cleaning up... 08:51:38 INFO - Return code: 0 08:51:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:51:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:38 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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:38 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 08:51:38 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 08:51:38 INFO - Using env: {'DISPLAY': ':0', 08:51:38 INFO - 'HOME': '/home/cltbld', 08:51:38 INFO - 'LANG': 'en_US.UTF-8', 08:51:38 INFO - 'LANGUAGE': 'en_US:en', 08:51:38 INFO - 'LOGNAME': 'cltbld', 08:51:38 INFO - 'MAIL': '/var/mail/cltbld', 08:51:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:38 INFO - 'MOZ_NO_REMOTE': '1', 08:51:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:38 INFO - 'NO_EM_RESTART': '1', 08:51:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:38 INFO - 'PWD': '/builds/slave/test', 08:51:38 INFO - 'SHELL': '/bin/bash', 08:51:38 INFO - 'SHLVL': '1', 08:51:38 INFO - 'TERM': 'linux', 08:51:38 INFO - 'TMOUT': '86400', 08:51:38 INFO - 'USER': 'cltbld', 08:51:38 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:38 INFO - '_': '/tools/buildbot/bin/python'} 08:51:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:38 INFO - Downloading/unpacking blobuploader==1.2.4 08:51:38 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 08:51:38 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 08:51:38 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 08:51:38 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 08:51:38 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 08:51:38 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 08:51:40 INFO - Downloading blobuploader-1.2.4.tar.gz 08:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:51:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:51:40 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 08:51:40 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 08:51:40 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 08:51:40 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 08:51:40 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 08:51:40 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 08:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:51:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:51:40 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 08:51:40 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 08:51:40 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 08:51:40 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 08:51:40 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 08:51:40 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 08:51:41 INFO - Downloading docopt-0.6.1.tar.gz 08:51:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:51:41 INFO - Installing collected packages: blobuploader, requests, docopt 08:51:41 INFO - Running setup.py install for blobuploader 08:51:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:51:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:51:41 INFO - Running setup.py install for requests 08:51:41 INFO - Running setup.py install for docopt 08:51:41 INFO - Successfully installed blobuploader requests docopt 08:51:41 INFO - Cleaning up... 08:51:41 INFO - Return code: 0 08:51:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:41 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')]} 08:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:41 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')]} 08:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:41 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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:41 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 08:51:41 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 08:51:41 INFO - Using env: {'DISPLAY': ':0', 08:51:41 INFO - 'HOME': '/home/cltbld', 08:51:41 INFO - 'LANG': 'en_US.UTF-8', 08:51:41 INFO - 'LANGUAGE': 'en_US:en', 08:51:41 INFO - 'LOGNAME': 'cltbld', 08:51:41 INFO - 'MAIL': '/var/mail/cltbld', 08:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:41 INFO - 'MOZ_NO_REMOTE': '1', 08:51:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:41 INFO - 'NO_EM_RESTART': '1', 08:51:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:41 INFO - 'PWD': '/builds/slave/test', 08:51:41 INFO - 'SHELL': '/bin/bash', 08:51:41 INFO - 'SHLVL': '1', 08:51:41 INFO - 'TERM': 'linux', 08:51:41 INFO - 'TMOUT': '86400', 08:51:41 INFO - 'USER': 'cltbld', 08:51:41 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:41 INFO - '_': '/tools/buildbot/bin/python'} 08:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:41 INFO - Running setup.py (path:/tmp/pip-j2XLyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:42 INFO - Running setup.py (path:/tmp/pip-Zfm8hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:42 INFO - Running setup.py (path:/tmp/pip-RMhvyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:42 INFO - Running setup.py (path:/tmp/pip-5BnIlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:42 INFO - Running setup.py (path:/tmp/pip-FZgkrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:42 INFO - Running setup.py (path:/tmp/pip-e6aSG2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:42 INFO - Running setup.py (path:/tmp/pip-mMBLoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:42 INFO - Running setup.py (path:/tmp/pip-9rH7vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:42 INFO - Running setup.py (path:/tmp/pip-e734vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:42 INFO - Running setup.py (path:/tmp/pip-qP7XDA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:42 INFO - Running setup.py (path:/tmp/pip-edSKJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:42 INFO - Running setup.py (path:/tmp/pip-5XHesb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:42 INFO - Running setup.py (path:/tmp/pip-5XWeip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:42 INFO - Running setup.py (path:/tmp/pip-k6_JJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:42 INFO - Running setup.py (path:/tmp/pip-Hu9YS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:43 INFO - Running setup.py (path:/tmp/pip-zIG6JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:43 INFO - Running setup.py (path:/tmp/pip-jhZ8gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:51:43 INFO - Running setup.py install for manifestparser 08:51:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Running setup.py install for mozcrash 08:51:43 INFO - Running setup.py install for mozdebug 08:51:43 INFO - Running setup.py install for mozdevice 08:51:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Running setup.py install for mozfile 08:51:43 INFO - Running setup.py install for mozhttpd 08:51:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Running setup.py install for mozinfo 08:51:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Running setup.py install for mozInstall 08:51:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:51:43 INFO - Running setup.py install for mozleak 08:51:44 INFO - Running setup.py install for mozlog 08:51:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:51:44 INFO - Running setup.py install for moznetwork 08:51:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:51:44 INFO - Running setup.py install for mozprocess 08:51:44 INFO - Running setup.py install for mozprofile 08:51:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:51:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:51:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:51:44 INFO - Running setup.py install for mozrunner 08:51:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:51:44 INFO - Running setup.py install for mozscreenshot 08:51:44 INFO - Running setup.py install for moztest 08:51:44 INFO - Running setup.py install for mozversion 08:51:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:51:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:51:44 INFO - Cleaning up... 08:51:44 INFO - Return code: 0 08:51:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:44 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')]} 08:51:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:44 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')]} 08:51:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:45 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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:45 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 08:51:45 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 08:51:45 INFO - Using env: {'DISPLAY': ':0', 08:51:45 INFO - 'HOME': '/home/cltbld', 08:51:45 INFO - 'LANG': 'en_US.UTF-8', 08:51:45 INFO - 'LANGUAGE': 'en_US:en', 08:51:45 INFO - 'LOGNAME': 'cltbld', 08:51:45 INFO - 'MAIL': '/var/mail/cltbld', 08:51:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:45 INFO - 'MOZ_NO_REMOTE': '1', 08:51:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:45 INFO - 'NO_EM_RESTART': '1', 08:51:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:45 INFO - 'PWD': '/builds/slave/test', 08:51:45 INFO - 'SHELL': '/bin/bash', 08:51:45 INFO - 'SHLVL': '1', 08:51:45 INFO - 'TERM': 'linux', 08:51:45 INFO - 'TMOUT': '86400', 08:51:45 INFO - 'USER': 'cltbld', 08:51:45 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:45 INFO - '_': '/tools/buildbot/bin/python'} 08:51:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:51:45 INFO - Running setup.py (path:/tmp/pip-FFfxQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:51:45 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)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:51:45 INFO - Running setup.py (path:/tmp/pip-f_HSoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:51:45 INFO - Running setup.py (path:/tmp/pip-hFq0Ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:51:45 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)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:51:45 INFO - Running setup.py (path:/tmp/pip-GYSY96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:51:45 INFO - Running setup.py (path:/tmp/pip-gA19_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:51:45 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)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:51:45 INFO - Running setup.py (path:/tmp/pip-eVug23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:51:45 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)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:51:45 INFO - Running setup.py (path:/tmp/pip-rqu0KK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:51:45 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)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:51:45 INFO - Running setup.py (path:/tmp/pip-ou5ch8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:51:45 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)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:51:45 INFO - Running setup.py (path:/tmp/pip-o1g87T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:51:45 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)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:51:45 INFO - Running setup.py (path:/tmp/pip-CIgju5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:51:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:51:45 INFO - Running setup.py (path:/tmp/pip-hDUx5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:51:46 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)) 08:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:51:46 INFO - Running setup.py (path:/tmp/pip-PpDfFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:51:46 INFO - Running setup.py (path:/tmp/pip-Te_YMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:51:46 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)) 08:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:51:46 INFO - Running setup.py (path:/tmp/pip-QBsrhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:46 INFO - Running setup.py (path:/tmp/pip-QD6IJU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:51:46 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)) 08:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:51:46 INFO - Running setup.py (path:/tmp/pip-KBtqef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:51:46 INFO - Running setup.py (path:/tmp/pip-TaUfhY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:51:46 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)) 08:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:51:46 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)) 08:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:51:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:51:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:51:46 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 08:51:46 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 08:51:46 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 08:51:46 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 08:51:46 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 08:51:46 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 08:51:48 INFO - Downloading blessings-1.6.tar.gz 08:51:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:51:48 INFO - Installing collected packages: blessings 08:51:48 INFO - Running setup.py install for blessings 08:51:48 INFO - Successfully installed blessings 08:51:48 INFO - Cleaning up... 08:51:48 INFO - Return code: 0 08:51:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:51:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:49 INFO - Reading from file tmpfile_stdout 08:51:49 INFO - Current package versions: 08:51:49 INFO - argparse == 1.2.1 08:51:49 INFO - blessings == 1.6 08:51:49 INFO - blobuploader == 1.2.4 08:51:49 INFO - docopt == 0.6.1 08:51:49 INFO - functools32 == 3.2.3-2 08:51:49 INFO - jsonschema == 2.5.1 08:51:49 INFO - manifestparser == 1.1 08:51:49 INFO - mozInstall == 1.12 08:51:49 INFO - mozcrash == 1.0 08:51:49 INFO - mozdebug == 0.1 08:51:49 INFO - mozdevice == 0.49 08:51:49 INFO - mozfile == 1.2 08:51:49 INFO - mozhttpd == 0.7 08:51:49 INFO - mozinfo == 0.9 08:51:49 INFO - mozleak == 0.1 08:51:49 INFO - mozlog == 3.4 08:51:49 INFO - moznetwork == 0.27 08:51:49 INFO - mozprocess == 0.25 08:51:49 INFO - mozprofile == 0.28 08:51:49 INFO - mozrunner == 6.13 08:51:49 INFO - mozscreenshot == 0.1 08:51:49 INFO - mozsystemmonitor == 0.3 08:51:49 INFO - moztest == 0.8 08:51:49 INFO - mozversion == 1.4 08:51:49 INFO - psutil == 3.1.1 08:51:49 INFO - requests == 1.2.3 08:51:49 INFO - wsgiref == 0.1.2 08:51:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:49 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')]} 08:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:49 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')]} 08:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:49 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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:49 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 08:51:49 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 08:51:49 INFO - Using env: {'DISPLAY': ':0', 08:51:49 INFO - 'HOME': '/home/cltbld', 08:51:49 INFO - 'LANG': 'en_US.UTF-8', 08:51:49 INFO - 'LANGUAGE': 'en_US:en', 08:51:49 INFO - 'LOGNAME': 'cltbld', 08:51:49 INFO - 'MAIL': '/var/mail/cltbld', 08:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:49 INFO - 'MOZ_NO_REMOTE': '1', 08:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:49 INFO - 'NO_EM_RESTART': '1', 08:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:49 INFO - 'PWD': '/builds/slave/test', 08:51:49 INFO - 'SHELL': '/bin/bash', 08:51:49 INFO - 'SHLVL': '1', 08:51:49 INFO - 'TERM': 'linux', 08:51:49 INFO - 'TMOUT': '86400', 08:51:49 INFO - 'USER': 'cltbld', 08:51:49 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:49 INFO - '_': '/tools/buildbot/bin/python'} 08:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 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)) 08:51:49 INFO - Cleaning up... 08:51:49 INFO - Return code: 0 08:51:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:51:49 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')]} 08:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:49 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')]} 08:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:49 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 0x1cd01f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dc8a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f6db10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 08:51:49 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 08:51:49 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 08:51:49 INFO - Using env: {'DISPLAY': ':0', 08:51:49 INFO - 'HOME': '/home/cltbld', 08:51:49 INFO - 'LANG': 'en_US.UTF-8', 08:51:49 INFO - 'LANGUAGE': 'en_US:en', 08:51:49 INFO - 'LOGNAME': 'cltbld', 08:51:49 INFO - 'MAIL': '/var/mail/cltbld', 08:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:49 INFO - 'MOZ_NO_REMOTE': '1', 08:51:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:51:49 INFO - 'NO_EM_RESTART': '1', 08:51:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:49 INFO - 'PWD': '/builds/slave/test', 08:51:49 INFO - 'SHELL': '/bin/bash', 08:51:49 INFO - 'SHLVL': '1', 08:51:49 INFO - 'TERM': 'linux', 08:51:49 INFO - 'TMOUT': '86400', 08:51:49 INFO - 'USER': 'cltbld', 08:51:49 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:49 INFO - '_': '/tools/buildbot/bin/python'} 08:51:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:51:49 INFO - Cleaning up... 08:51:49 INFO - Return code: 0 08:51:49 INFO - Running post-action listener: _resource_record_post_action 08:51:49 INFO - Running post-action listener: _start_resource_monitoring 08:51:49 INFO - Starting resource monitoring. 08:51:49 INFO - [mozharness: 2017-04-08 15:51:49.565095Z] Finished create-virtualenv step (success) 08:51:49 INFO - [mozharness: 2017-04-08 15:51:49.565676Z] Running install step. 08:51:49 INFO - Running pre-action listener: _resource_record_pre_action 08:51:49 INFO - Running main action method: install 08:51:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:49 INFO - Reading from file tmpfile_stdout 08:51:49 INFO - Detecting whether we're running mozinstall >=1.0... 08:51:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:51:49 INFO - Reading from file tmpfile_stdout 08:51:49 INFO - Output received: 08:51:49 INFO - Usage: mozinstall [options] installer 08:51:49 INFO - Options: 08:51:49 INFO - -h, --help show this help message and exit 08:51:49 INFO - -d DEST, --destination=DEST 08:51:49 INFO - Directory to install application into. [default: 08:51:49 INFO - "/builds/slave/test"] 08:51:49 INFO - --app=APP Application being installed. [default: firefox] 08:51:49 INFO - mkdir: /builds/slave/test/build/application 08:51:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:51:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:52:06 INFO - Reading from file tmpfile_stdout 08:52:06 INFO - Output received: 08:52:06 INFO - /builds/slave/test/build/application/firefox/firefox 08:52:06 INFO - Running post-action listener: _resource_record_post_action 08:52:06 INFO - [mozharness: 2017-04-08 15:52:06.437064Z] Finished install step (success) 08:52:06 INFO - [mozharness: 2017-04-08 15:52:06.437348Z] Running run-tests step. 08:52:06 INFO - Running pre-action listener: _resource_record_pre_action 08:52:06 INFO - Running main action method: run_tests 08:52:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:52:06 INFO - Python 2.7.3 08:52:06 INFO - Return code: 0 08:52:06 INFO - grabbing minidump binary from tooltool 08:52:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:52:06 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/linux64/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 0x1f6b290>, '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 0x1f6bb90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f6c040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:52:06 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:52:06 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/linux64/releng.manifest -o -c /builds/tooltool_cache 08:52:06 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/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:52:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:52:06 INFO - Return code: 0 08:52:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:52:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:52:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:52:06 INFO - ENV: RUST_BACKTRACE is now 1 08:52:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:52:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:52:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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 08:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-074 --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 08:52:06 INFO - Using env: {'DISPLAY': ':0', 08:52:06 INFO - 'HOME': '/home/cltbld', 08:52:06 INFO - 'LANG': 'en_US.UTF-8', 08:52:06 INFO - 'LANGUAGE': 'en_US:en', 08:52:06 INFO - 'LOGNAME': 'cltbld', 08:52:06 INFO - 'MAIL': '/var/mail/cltbld', 08:52:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:52:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:52:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:52:06 INFO - 'MOZ_NO_REMOTE': '1', 08:52:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:52:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:52:06 INFO - 'NO_EM_RESTART': '1', 08:52:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:52:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:52:06 INFO - 'PWD': '/builds/slave/test', 08:52:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:52:06 INFO - 'RUST_BACKTRACE': '1', 08:52:06 INFO - 'SHELL': '/bin/bash', 08:52:06 INFO - 'SHLVL': '1', 08:52:06 INFO - 'TERM': 'linux', 08:52:06 INFO - 'TMOUT': '86400', 08:52:06 INFO - 'USER': 'cltbld', 08:52:06 INFO - 'XDG_SESSION_COOKIE': '23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026', 08:52:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:52:06 INFO - '_': '/tools/buildbot/bin/python'} 08:52:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-074', '--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 08:52:06 INFO - mozversion application_buildid: 20170408145833 08:52:06 INFO - mozversion application_changeset: 45fd7ec20907695251ce9c87cd446d44cb8296ff 08:52:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:52:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:52:06 INFO - mozversion application_name: Firefox 08:52:06 INFO - mozversion application_remotingname: firefox-dev 08:52:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:52:06 INFO - mozversion application_vendor: Mozilla 08:52:06 INFO - mozversion application_version: 54.0a2 08:52:06 INFO - mozversion platform_buildid: 20170408145833 08:52:06 INFO - mozversion platform_changeset: 45fd7ec20907695251ce9c87cd446d44cb8296ff 08:52:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:52:06 INFO - mozversion platform_version: 54.0a2 08:52:06 INFO - using testdate: 1491666726 08:52:06 INFO - actual date: 1491666726 08:52:06 INFO - starting webserver on 'localhost:52783' 08:52:06 INFO - SUITE-START | Running 2 tests 08:52:06 INFO - TEST-START | dromaeo_css 08:52:06 INFO - operating with platform_type : linux_ 08:52:06 INFO - Initialising browser for dromaeo_css test... 08:52:06 INFO - TEST-INFO | started process 7132 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXZT1dj/profile http://localhost:52783/getInfo.html) 08:52:10 INFO - PROCESS | 7132 | __metrics Screen width/height:1600/1200 08:52:10 INFO - PROCESS | 7132 | colorDepth:24 08:52:10 INFO - PROCESS | 7132 | Browser inner width/height: 1024/697 08:52:10 INFO - PROCESS | 7132 | __metrics 08:52:11 INFO - PROCESS | 7132 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:52:11 INFO - PROCESS | 7132 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:52:12 INFO - PROCESS | 7132 | console.error: PushService: 08:52:12 INFO - PROCESS | 7132 | stateChangeProcessEnqueue: Error transitioning state 08:52:12 INFO - PROCESS | 7132 | UnknownError 08:52:12 INFO - TEST-INFO | 7132: exit 0 08:52:12 INFO - Browser initialized. 08:52:12 INFO - Running cycle 1/1 for dromaeo_css test... 08:52:12 INFO - TEST-INFO | started process 7225 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXZT1dj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:52:24 INFO - PROCESS | 7225 | RSS: Main: 140181504 08:52:24 INFO - PROCESS | 7225 | 08:55:26 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:52783/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52783/tests/dromaeo/cssquery-ext.html) 08:55:27 INFO - PROCESS | 7225 | RSS: Main: 281055232 08:55:27 INFO - PROCESS | 7225 | 08:57:15 INFO - PROCESS | 7225 | ************************* 08:57:15 INFO - PROCESS | 7225 | A coding exception was thrown and uncaught in a Task. 08:57:15 INFO - PROCESS | 7225 | 08:57:15 ERROR - PROCESS | 7225 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:57:15 INFO - PROCESS | 7225 | Full stack: 08:57:15 INFO - PROCESS | 7225 | ************************* 08:57:15 INFO - PROCESS | 7225 | ************************* 08:57:15 INFO - PROCESS | 7225 | A coding exception was thrown and uncaught in a Task. 08:57:15 INFO - PROCESS | 7225 | 08:57:15 ERROR - PROCESS | 7225 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:57:15 INFO - PROCESS | 7225 | Full stack: 08:57:15 INFO - PROCESS | 7225 | ************************* 08:57:15 INFO - PROCESS | 7225 | ************************* 08:57:15 INFO - PROCESS | 7225 | A coding exception was thrown and uncaught in a Task. 08:57:15 INFO - PROCESS | 7225 | 08:57:15 ERROR - PROCESS | 7225 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:57:15 INFO - PROCESS | 7225 | Full stack: 08:57:15 INFO - PROCESS | 7225 | ************************* 08:57:15 INFO - PROCESS | 7225 | ************************* 08:57:15 INFO - PROCESS | 7225 | A coding exception was thrown and uncaught in a Task. 08:57:15 INFO - PROCESS | 7225 | 08:57:15 ERROR - PROCESS | 7225 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:57:15 INFO - PROCESS | 7225 | Full stack: 08:57:15 INFO - PROCESS | 7225 | ************************* 08:58:29 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:52783/tests/dromaeo/cssquery-ext.html (next: http://localhost:52783/tests/dromaeo/cssquery-jquery.html) 08:58:30 INFO - PROCESS | 7225 | RSS: Main: 220463104 08:58:30 INFO - PROCESS | 7225 | 09:01:32 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:52783/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52783/tests/dromaeo/cssquery-mootools.html) 09:01:32 INFO - PROCESS | 7225 | RSS: Main: 237981696 09:01:32 INFO - PROCESS | 7225 | 09:04:34 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:52783/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52783/tests/dromaeo/cssquery-prototype.html) 09:04:35 INFO - PROCESS | 7225 | RSS: Main: 213450752 09:04:35 INFO - PROCESS | 7225 | 09:07:38 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:52783/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52783/tests/dromaeo/cssquery-yui.html) 09:07:38 INFO - PROCESS | 7225 | RSS: Main: 177860608 09:07:38 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | Cycle 1(1): loaded http://localhost:52783/tests/dromaeo/cssquery-yui.html (next: http://localhost:52783/tests/dromaeo/cssquery-dojo.html) 09:10:41 INFO - PROCESS | 7225 | RSS: Main: 262135808 09:10:41 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | __start_tp_report 09:10:41 INFO - PROCESS | 7225 | _x_x_mozilla_page_load 09:10:41 INFO - PROCESS | 7225 | _x_x_mozilla_page_load_details 09:10:41 INFO - PROCESS | 7225 | |i|pagename|runs| 09:10:41 INFO - PROCESS | 7225 | |0;dojo.html;3404.8297585120736;3434.536337594424;3454.533637958876;3456.9827150864244;3504.9123771905697;5719.799807006755;5780.797672081478;5794.652320860751;5796.014677504823;5805.767769289228;5766.711664416784;5807.8257652270395;5811.27359080115;5812.825615231539;5815.709214539267;5217.165253559431;5224.791008359661;5248.816291429801;5313.521783039526;5334.066538355792;5136.691798492084;5150.459201783815;5333.38666053403;5342.332208473941;5349.251104845324;5189.792408303663;5224.660397074197;5300.575953923677;5313.017091838003;5319.441458646845;5321.680699158043;5324.227986941872;5328.786848526054;5329.733513324357;5336.279602253724;5075.923861142086;5184.766685499161;5237.109691352441;5287.365516138088;5293.311869456985;5635.887282254373;5640.802571909963;5713.542916566665;5734.541236701054;5739.196512488255;6149.262088549358;6174.7530098796;6182.752689892444;6191.040388739752;6216.595921265103;6228.308228557202;6232.594881332744;6238.656873871935;6255.718492667841;6271.968640156816;5544;5577.860553486195;5726.026575482137;5780.450857168562;5842.152887831326;6353.047042943595;6357.491400688026;6361.14124593174;6363.363663633692;6368.203974503187;6015.4586087252355;6117.113018612276;6117.510599152057;6126.326104128542;6131.754729810757;6007.279126504847;6032.095185722089;6106.603070800384;6113.23584551931;6220.657863817533;6121.329396744349;6133.916185115405;6135.4593606941635;6144.692765361714;6153.384661533811;5926.733297001645;5957.165996760457;6053.364396738367;6105.633661980207;6128.4531764236735;4662.836800711959;4670.976645116821;4700.247960326331;4708.69393489429;4739.218029025241;4105.794710264475;4112.506499220112;4133.318002529609;4199.391088292181;4215.97892010538;5948.375420580864;5970.402959704082;6017.729202185912;6029.155918171459;6030.27636683592;6151.361375033566;6203.379662033761;6232.781852635136;6243.656598887105;6249.312575616678;5719.3136823581435;5927.9703601481715;5934.1692163097205;5935;5957.255343082114;5115.130427827278;5139.665921715076;5210.21846722987;5261.789528418897;5265;6413.895276623578;6436.485081193493;6446.967765161145;6448.484121270381;6452.967735161388;6122.6938653068055;6185.938140618537;6201;6201.069839524108;6201.565890387629;6218.875622487615;6261.530385221035;6370.936290637034;6379.298277189413;6473.061406096184;6133.273457575638;6137.499563105042;6142.836439233887;6143.481172719343;6145.66198859067;6246.500279977682;6246.812595622138;6250.812475625738;6260.624362538262;6274.901892168943;5791.913121303268;5896.262967129109;5973.970130149321;5988.790392336247;6080.939190608037;6060.515158787374;6098.329183789869;6108.450239478469;6126.172966649446;6139.508839292756;6071.605345652519;6079.452849243589;6080.48315893154;6084.847878803065;6085;5867.794627188028;5881.500072493886;5918.852028699317;6034.366391529002;6050.42520960508;5977.491913187428;5984.970075149597;5990.161377407079;6022.66875321844;6023.397660233942 09:10:41 INFO - PROCESS | 7225 | |1;ext.html;747.648605155577;819.6639377855079;832.6544484039122;891.8305521950829;903.5798353765498;29623.40753184935;41376.793116034416;42156.36765448517;46004.76997615007;46539;42459.575404245945;42633.57366426335;42844.57155428453;42864;42877;7822.843543129134;7963.482373645724;8971.102889711043;9102.271818254558;9105.908940910605;10124.190064794799;10329.328593641427;10365.429901355423;10367.481625918712;10636.255462117617;10458.68623941283;10601.257911946166;10603.469826508683;10603.787924241511;10647.988441098083;10376.584936602603;10403.167746580293;10437.68686939393;10516.84224736622;10593.788124237512;10334.534945927451;10372.377657340508;10393.532291046846;10396.272260941738;10397.48012599367;39684.40473392901;39935;39941.60058399408;39960.80019599883;39967.8001609993;4190.748555086705;4210.3684447332835;4262.019735460831;4319.114581510814;4354.303311470181;29116.563251551244;29813.10560683183;29941.700582994108;30188.54717179244;31328;4265.296226122686;4337.544557821415;4355.586219309161;4384.079343337903;4464.196444639963;235159;236248.81875590683;236613;237009;238144;53000.73499632477;53097.469025309256;53119.20321195185;53173.202401964;53211.46788532143;55126.17310740342;55143.72428137834;55181;55209;55255;15933.442329518411;16225;16240.918795405947;16242.350305987628;16246;11982.340971246666;12159.817602735966;13663.658408539866;14323.212223327608;14340.067895586963;13120.475180992844;13153.868461315264;13159.407826647825;13220.801687974688;13238.338083095807;5457.181422786613;5466.152746324246;5515.7242137892945;5521.613487055948;5646.661200327993;1015.761296095417;1018.908298253146;1028.1928685981618;1049.8582691336724;1056.746380868586;26357.736422635528;27662;27740.306492337448;28050.896182785964;28461.715382845905;1078.692572616816;1092.7650555130685;1108.2630051016145;1117.8435019097333;1127.5489804078434;14173.291335433187;14917.47788827386;15007.699846003019;15071;15233.542993710207;308281;309376;310375;311227;311506;54169.72915135399;54184.45815541873;54197.72901135469;54234;54259.45740542623;1478.8373278939307;1493.6191271225769;1509.2604623734292;1512.3874830693587;1520;25247.621285681096;26759.331016724736;26760.59859102076;26819;26821.329466763487;39887.20225595414;40002.59997399989;40010.79994600009;40053.79973100233;40063;287114.95097671484;287404;287462;287770;288243;29377.412451751272;29989.70010299869;33762.3247535043;33794.66205337915;33825.323493530486;42301.46191228413;42550;42625.57374426216;42700.78649606732;42741;33515;33550.49674254839;33614;33625.83187084049;33915.830420848724;33232.83383583163;33369.83315083409;33377.499337510446;33487;34104.829475852464;38293.617063829006;38312.233755325295;38315.61684383232;38316.42525362066;38383.42424863573;31494.370112598077;31871.840640796647;31957.68042319547;32489;32573.348533028733;25174;25999.220023399328;26290.502402271755;26351;26374.604380933917 09:10:41 INFO - PROCESS | 7225 | |2;jquery.html;442.27687730560524;462.6229622857371;466.85060780550214;473.8436316015714;490.6957686234535;19557.706634400478;20884.37346879594;21321.147154113834;21418.678719819178;21636.3509094727;20879.269225577107;21101;21288.148474061018;21529;22197.223097191603;6714.798556043326;6951.617661028641;6952.09622749042;7171.067761191064;7398.334149926505;7236.131664200303;7445.069366329209;7490.925090749104;7496.662650180728;7499.887501687479;7652.31129198372;7675.616219189046;7676.501027433199;7678.808029799244;7678.846423071535;7302.379297759697;7560.621968901533;7588.317051465395;7610.771676849703;7658.770236892902;7382.9630851845395;7515.436342274351;7534.359579435754;7547.547147171133;7580.734674286374;25086.121985730413;25379.111731089324;25442.236732897858;25476.87261563699;25694;3103.8603262853226;3501.4047611906035;3595.874144404959;3680.926381472382;3696.2792255510185;15275.541733747894;15848.207589620588;15855.286512106868;15968.121753303563;16742.58143546409;2242.854214476054;2257.356653353803;2282.703248577691;2341.2390972933863;2359.3511784259294;114919.41313418827;118068;118932;119553;125483;18199.545011374823;18552.350667726565;18956.431307060535;18996.240150394107;19094.47244220459;32660.346793064007;32732;32743.67256327454;32761;32808.67191328104;10602.840957385646;10619.469026548642;10694.679159625224;10717.249792514449;11122.832102629269;8953.59708813105;8981.326400519987;9025.458472491671;9213.12475314844;9218.21645160169;9312.627494900127;9359.847235135821;9503.429794212369;9636.71089867305;9713.320067595341;3391.932161356759;3519.63043880394;3956.647687543679;4077.7145599807727;4100.569440208795;957.875476188091;974.6880998080546;985.4579981010415;998.7203582996773;1011.8330475471465;20824.58350832975;21468.892655536623;21485.140594376364;21489.892550537148;21694.891525542273;991.1228562721899;997.0826839307854;1001.2540657210279;1010.4594042187541;1024.4570377699831;10875.293105948089;11753.764924701458;11870.88129118715;11978.34119123439;11985.460654270579;649774;655511;655992;669125;677314;92040;94778.52610736902;116867.41566292114;119632;124660;1328.5350127455506;1346.7239215960901;1370.5066176176767;1390.9443622255199;1392.9860701392856;18096.72854907151;18459.538511537317;18716.532086697942;18915.432537023913;18997.145128468976;50808.23787643262;51587.48412515827;52211.73894130505;52238.73880630724;52543;65890;66921.33078669151;67378.66310668415;67599.66200168968;68456.6577167111;12447.502099915902;12497.687557811127;12594.433250503933;12631.242125472501;12653.87346126527;14070.296485175904;14170.574882753534;14538.12771233687;14549.636259093608;14582.416703331748;12267.386630668609;12523.311217883103;12542.12205145631;12579.74840503203;12629.2422454653;11203.439828008404;11291.378974156172;11372.488238029475;11390.37352945596;11580.6525804226;12729.363531823557;12760.42578084007;12818.359082045674;13032.674183145496;13239.801402979156;12855.16441431304;12862.614121576367;12908;12932.870671293544;12938.482460701467;12463.31451770125;12559.183653062522;12605.928496077937;12634.368281586068;12970.481180752664 09:10:41 INFO - PROCESS | 7225 | |3;mootools.html;1440.1071335771824;1516.5450364890532;1551.4259723902157;1568.5294411676498;1584.5484037049437;9672.806543869126;11315.151363647728;11708.882911170896;12088.697782555419;12098.395080245997;11722.413879306043;11809.822852657195;11811.173217874752;11811.645650630473;11814.350210738407;2111.8416118791074;2184.453886528368;2294.7934685878267;2329.9184528541505;2330.5571941331177;2463;2516.811239157061;2531.860747658878;2538.6953565572067;2550.923472295834;2423.442608200115;2436.9390765230833;2445.486447845946;2446.694163229596;2454.3127924181167;2422.4912768318673;2439.6828412306377;2440.255722004788;2440.7193172785;2446.620773780067;2309.434188623781;2354.2466410748566;2371.5612611666897;2400.663907053014;2411.807055435561;11546;11548.826767598493;11564.479598418007;11569.53721851125;11573.363465009505;5770.7691692332255;5822.272215973004;5850.97074514629;5997.460228579405;6017.518598512109;3581.4985901973746;3769.754965927206;3792.2984247914223;3821.8280177392085;3826.426036094608;1387.5074348606302;1445.7180849734307;1475.1960181700892;1485.3538710660896;1499.587613406311;16748.748768768477;17237.051962142075;17843.46469605901;18052.54868628295;18081.909590452095;4500.639948804088;4549.249373853278;4762.142814293391;4830.492798256132;4919.901601968012;12892.226466412045;13003.609891703263;13009.934950325378;13064;13089.41097650608;5714.428557144252;5729.226554415184;5730.7421166047625;5749.9712501437225;5762.942370576325;5361.651492652965;5437.4562543745305;5447.264619276426;5447.346318441812;5529.723513824292;4028.24142237253;4057.655099316532;4090.6931980101613;4125.401816736557;4138.8344466221615;1029.3926583315879;1033.1579762493543;1035.4305132177271;1042.1818872185297;1053.5048527192207;1093.092733031582;1100.935646390195;1114.9498272577753;1124.876263611002;1129.88701129888;7231.421486281085;7330.193678695339;7408.666610002563;7524.887126693104;7534;1038.5326603028668;1049.2655141401049;1054.0671505717496;1056.12869382758;1060.8355704865758;4783.545563171493;4794.5924596408995;4907.509249075064;4947.901041979212;4971.577415919687;14630.780538291934;15431.074135551902;15445.073295602075;15451.072935623673;15503.379864805507;13601;14114.71770564583;15175.924120379328;15185.6203594911;15216.771748423562;10831.620893268699;11042.227044106996;11120.332780033063;11141.554337826557;11142.331460112418;7408.9629551851895;7609.162992070978;7658.617069146632;7708.576028318272;7755.650995705094;13472;13480.73038539204;13539.458421663418;13559.59321220365;13840;11412.31526108436;11672.533098676298;13905.37425815861;13986.930065349608;13995.16029038261;9977.201823854219;10124.645637402655;10178.491075446345;10203.336783109075;10265.58937642486;11233.38216398106;11236.83144752813;11313;11329.433528323387;11386.032187263843;9284.58219380116;9539.046095390406;9540.33217674756;9605.231581473325;9656.95171524138;9240.260779137518;9287.907120928705;9306.162445379949;9444.669436569688;9662.565184566853;8087.069986951341;8259.545724985184;8292.212239837207;8294.958525207336;8298.170182981896;6525.184351956005;6576.605403675603;6621.867562648817;6628.801135965929;6730.192376914802;6595.637239951849;6598.609557295667;6651.13535240435;6690.19717633887;6736.528443009139 09:10:41 INFO - PROCESS | 7225 | |4;prototype.html;408.61385990239233;414.2956973145653;429.67559492583115;442.77197243419636;445.112001556894;5201.401838788541;5305.283786688796;5318.867028324294;5331.653442526238;5332.120200166968;5017.749112544368;5189.42916279209;5216.191490318997;5252.159654455288;5297.231901374298;4429.911401771963;4443.15580039793;4451.710638808483;4455.220336441134;4461.353103799964;4456.086502267028;4486.192485352635;4489.585780479155;4507.549245075499;4514.774261286922;4243.851465198719;4385.7149285296355;4429.379886815848;4440.333949907524;4499;4387.530177390578;4409.294512877957;4422.425084738979;4425.181341451842;4434.889127771799;4170.624643782075;4183.91632167355;4195.454590903194;4213.5786421357925;4240.787960601958;4859.708417494961;5102.107131252016;5136.178211486182;5274.736263186864;5292.142243188673;5109;5136.768845401966;5137.17805151174;5137.38351397831;5152.510511501401;5466.426025267369;5491.368492623359;5553.305836770403;5574.526165275956;5627.6060675752715;4457.420535330419;4495.64034877209;4547.363369128325;4556.430446194226;4591.51789062147;5447.536959358459;5539.975104605661;5581.386047534768;5650.5479561634975;5669.518090962273;4592.425946756655;4647.326137710013;4649.604783593365;4670.276107203389;4708.081924024851;4520.4233586855835;4621.445426548768;4698.107359601665;4699.483056863743;4716.268978308368;4938.678985865908;5011;5033.245013247969;5050;5095.592352611782;5437.694953211201;5490.231367608538;5507.504324610804;5521.972390138024;5544.972275138599;1577.8027746531684;1621.4243943399922;1640.8605268552308;1647.596338896954;1668.2242757118;1257.0886107571937;1266.5181737367302;1269.853966793812;1290.2452065541574;1290.2709968867748;5462.781488740486;5476;5516.889662206733;5534.861628459321;5558.972205139029;5683.886322273532;5702.6293290935955;5710.428957104339;5738.196652468658;5814.336967710316;5306.8142615009065;5367.087595108841;5378.2470454136455;5442.292501974797;5453.318335208099;2942.778746820046;2946.9391019233058;2957.792954493208;2980.180450376164;2990.6560745514544;5631.971840140773;5899.823005309848;5901.200133959183;5928.407159284037;5932.644041357446;5714.457126572969;5748.039759523848;5776.075827867521;5777.364489906107;5807.912881306784;5834.795782147605;5850.385709500526;5850.824475265749;5869.706514674249;5887.234659494238;5454.218348642142;5525.972370138124;5626.465485779008;5665.631733937281;5704.572157088152;5360.517553420055;5439.972800136133;5446.291982042309;5602.355729091207;5647;5891.734871930687;5896.584819643169;5897.469227769521;5909.468147866713;5930.940690593039;4508.368828363966;4696.647751418589;4697.84902698841;4721.834735784231;4735.413636431834;5730.226419433323;5747.48272655463;5747.48272655463;5766.769329226951;5766.942330576809;5558.277423934863;5599.440055994368;5609.27079479665;5616.971915140562;5629.296337957755;5345.251664767014;5494.945050549443;5497.120460726424;5560.777568897199;5573.303337082864;5547.2233887256625;5580.888382232414;5589.636673616202;5593.524550413261;5594.748236329294;5526.613137080527;5532.5020748132865;5546.972265138648;5578.414266501959;5584.497395234448;5264.263003179634;5421.945780542144;5441.727913604224;5457.672539647634;5504.697241651747 09:10:41 INFO - PROCESS | 7225 | |5;yui.html;857.6526506764762;871.7036207689388;895.7312806158153;914.0265617117773;921.5578716401537;16632.25154868031;16777.1611419429;17164.914175429105;17324.653506929884;17917.552061198476;16266.75599866003;16361.83638163618;16509.83490165098;17170.914145429288;17369.652606947853;612.5069319198044;617.4227097663677;851.6593362654941;868.1405408645427;871.6295574380895;882.3250213586612;886.822635472908;889.0620395482763;889.2663552569124;889.750869756466;938.5166639180827;941.3504681769591;941.3551717073792;941.4492521874712;942.420411446962;933.4399360383783;935.3031991166556;936.4802534593264;937.3860121620315;937.8030613571158;680.9659517024179;681.9011243369733;685.8182581615876;686.821426429132;687.2165731066588;17468.213930373036;17523;17532.649347013117;18388.356407525673;18664.90667546667;16292.348306067743;16423;16486.67026659472;16584.83415165845;16854.41009564659;14169.78745318821;14204;14333.354999025069;14347.78478322826;14514.709705805824;4093.16090201508;4094.385842123705;4095;4125.5874412558505;4220.092680073768;45516.544834551896;54847.177292340646;61640.38359616391;77956.22043779546;89656.55171724099;11901.583444579572;12073.698157546132;12308.323042232583;12375.319357435428;12703.237805731686;22802.65796013061;23234.767652323597;23249.418764530685;23268.3019509415;23368.298951031495;8987.46075235488;9092.454452732858;9168.54157292146;9187.219086377732;9272.397294175857;5067.341245638044;5067.569256613229;5078.136716758087;5216.347956505437;5237.450067742909;3792.639699228569;3862.9227415452096;3896.337622604164;3909.354956432214;3941.192055628589;650;652.422605993699;654.450261780107;654.9443297319781;659.5844617890795;834.4299557363306;838.1702114906199;850.6937502499095;869.3393021303729;883.2492381475694;11032.28290161137;11141.554337826557;11303.265287756269;11367.317960922206;11518.596849110409;798.6685525506849;822.9341652667772;827.2926647716237;841.6044459104211;842.8946381702287;5337.646180641951;5553.4724201200825;5592.552595792326;5627.803026894039;5634.295713035871;81494.59252703699;81582;81644.59177704192;82858.58570707108;85368;71009.28990710026;71645;72073.6396318015;73707;74677;63360.68319658372;64281;64992.35007649958;65470.67264663646;66494.33505664881;10511;10835.837462438227;10942.124630029419;11052.889471105185;11056.447177641247;37801.62198377981;38423;39900.40149397819;41465;45371.54628453673;47463.762681186374;48648.5135148644;54752.45247547473;55453;57694;13400.731985360433;13434.664133396744;13450.663733406744;13789.586312410644;14450.277486125862;15860.52418427449;16400.917995409945;16723.581910452336;16974;17219.96680199192;15155.469558565397;15916.124613145925;15998.280077396312;16583.585410364838;16588.50234492967;14870.553883383516;15763.211839407755;16024.519264422086;16437.58906027359;16520.586985325463;14275;14894.925525372737;15528.301226444612;16151.27319270659;16846.831531685017;11538.94230528842;11856.762864742484;11880.643580692593;11984.76030479403;12329.321887296284;12539.310337931136;12617.242965422103;12646.241225526497;12673.30296833683;12801.743965120831 09:10:41 INFO - PROCESS | 7225 | __end_tp_report 09:10:41 INFO - PROCESS | 7225 | __start_cc_report 09:10:41 INFO - PROCESS | 7225 | _x_x_mozilla_cycle_collect,1393 09:10:41 INFO - PROCESS | 7225 | __end_cc_report 09:10:41 INFO - PROCESS | 7225 | __startTimestamp1491667841616__endTimestamp 09:10:41 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | ------- Summary: start ------- 09:10:41 INFO - PROCESS | 7225 | Number of tests: 6 09:10:41 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | [#0] dojo.html Cycles:165 Average:5743.14 Median:5988.79 stddev:650.88 (10.9%) stddev-sans-first:626.50 09:10:41 INFO - PROCESS | 7225 | Values: 3404.8 3434.5 3454.5 3457.0 3504.9 5719.8 5780.8 5794.7 5796.0 5805.8 5766.7 5807.8 5811.3 5812.8 5815.7 5217.2 5224.8 5248.8 5313.5 5334.1 5136.7 5150.5 5333.4 5342.3 5349.3 5189.8 5224.7 5300.6 5313.0 5319.4 5321.7 5324.2 5328.8 5329.7 5336.3 5075.9 5184.8 5237.1 5287.4 5293.3 5635.9 5640.8 5713.5 5734.5 5739.2 6149.3 6174.8 6182.8 6191.0 6216.6 6228.3 6232.6 6238.7 6255.7 6272.0 5544.0 5577.9 5726.0 5780.5 5842.2 6353.0 6357.5 6361.1 6363.4 6368.2 6015.5 6117.1 6117.5 6126.3 6131.8 6007.3 6032.1 6106.6 6113.2 6220.7 6121.3 6133.9 6135.5 6144.7 6153.4 5926.7 5957.2 6053.4 6105.6 6128.5 4662.8 4671.0 4700.2 4708.7 4739.2 4105.8 4112.5 4133.3 4199.4 4216.0 5948.4 5970.4 6017.7 6029.2 6030.3 6151.4 6203.4 6232.8 6243.7 6249.3 5719.3 5928.0 5934.2 5935.0 5957.3 5115.1 5139.7 5210.2 5261.8 5265.0 6413.9 6436.5 6447.0 6448.5 6453.0 6122.7 6185.9 6201.0 6201.1 6201.6 6218.9 6261.5 6370.9 6379.3 6473.1 6133.3 6137.5 6142.8 6143.5 6145.7 6246.5 6246.8 6250.8 6260.6 6274.9 5791.9 5896.3 5974.0 5988.8 6080.9 6060.5 6098.3 6108.5 6126.2 6139.5 6071.6 6079.5 6080.5 6084.8 6085.0 5867.8 5881.5 5918.9 6034.4 6050.4 5977.5 5985.0 5990.2 6022.7 6023.4 09:10:41 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | [#1] ext.html Cycles:180 Average:44715.78 Median:311366.50 stddev:72956.61 (23.4%) stddev-sans-first:73086.58 09:10:41 INFO - PROCESS | 7225 | Values: 747.6 819.7 832.7 891.8 903.6 29623.4 41376.8 42156.4 46004.8 46539.0 42459.6 42633.6 42844.6 42864.0 42877.0 7822.8 7963.5 8971.1 9102.3 9105.9 10124.2 10329.3 10365.4 10367.5 10636.3 10458.7 10601.3 10603.5 10603.8 10648.0 10376.6 10403.2 10437.7 10516.8 10593.8 10334.5 10372.4 10393.5 10396.3 10397.5 39684.4 39935.0 39941.6 39960.8 39967.8 4190.7 4210.4 4262.0 4319.1 4354.3 29116.6 29813.1 29941.7 30188.5 31328.0 4265.3 4337.5 4355.6 4384.1 4464.2 235159.0 236248.8 236613.0 237009.0 238144.0 53000.7 53097.5 53119.2 53173.2 53211.5 55126.2 55143.7 55181.0 55209.0 55255.0 15933.4 16225.0 16240.9 16242.4 16246.0 11982.3 12159.8 13663.7 14323.2 14340.1 13120.5 13153.9 13159.4 13220.8 13238.3 5457.2 5466.2 5515.7 5521.6 5646.7 1015.8 1018.9 1028.2 1049.9 1056.7 26357.7 27662.0 27740.3 28050.9 28461.7 1078.7 1092.8 1108.3 1117.8 1127.5 14173.3 14917.5 15007.7 15071.0 15233.5 308281.0 309376.0 310375.0 311227.0 311506.0 54169.7 54184.5 54197.7 54234.0 54259.5 1478.8 1493.6 1509.3 1512.4 1520.0 25247.6 26759.3 26760.6 26819.0 26821.3 39887.2 40002.6 40010.8 40053.8 40063.0 287115.0 287404.0 287462.0 287770.0 288243.0 29377.4 29989.7 33762.3 33794.7 33825.3 42301.5 42550.0 42625.6 42700.8 42741.0 33515.0 33550.5 33614.0 33625.8 33915.8 33232.8 33369.8 33377.5 33487.0 34104.8 38293.6 38312.2 38315.6 38316.4 38383.4 31494.4 31871.8 31957.7 32489.0 32573.3 25174.0 25999.2 26290.5 26351.0 26374.6 09:10:41 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | [#2] jquery.html Cycles:180 Average:37940.63 Median:21946.06 stddev:109021.03 (496.8%) stddev-sans-first:109290.50 09:10:41 INFO - PROCESS | 7225 | Values: 442.3 462.6 466.9 473.8 490.7 19557.7 20884.4 21321.1 21418.7 21636.4 20879.3 21101.0 21288.1 21529.0 22197.2 6714.8 6951.6 6952.1 7171.1 7398.3 7236.1 7445.1 7490.9 7496.7 7499.9 7652.3 7675.6 7676.5 7678.8 7678.8 7302.4 7560.6 7588.3 7610.8 7658.8 7383.0 7515.4 7534.4 7547.5 7580.7 25086.1 25379.1 25442.2 25476.9 25694.0 3103.9 3501.4 3595.9 3680.9 3696.3 15275.5 15848.2 15855.3 15968.1 16742.6 2242.9 2257.4 2282.7 2341.2 2359.4 114919.4 118068.0 118932.0 119553.0 125483.0 18199.5 18552.4 18956.4 18996.2 19094.5 32660.3 32732.0 32743.7 32761.0 32808.7 10602.8 10619.5 10694.7 10717.2 11122.8 8953.6 8981.3 9025.5 9213.1 9218.2 9312.6 9359.8 9503.4 9636.7 9713.3 3391.9 3519.6 3956.6 4077.7 4100.6 957.9 974.7 985.5 998.7 1011.8 20824.6 21468.9 21485.1 21489.9 21694.9 991.1 997.1 1001.3 1010.5 1024.5 10875.3 11753.8 11870.9 11978.3 11985.5 649774.0 655511.0 655992.0 669125.0 677314.0 92040.0 94778.5 116867.4 119632.0 124660.0 1328.5 1346.7 1370.5 1390.9 1393.0 18096.7 18459.5 18716.5 18915.4 18997.1 50808.2 51587.5 52211.7 52238.7 52543.0 65890.0 66921.3 67378.7 67599.7 68456.7 12447.5 12497.7 12594.4 12631.2 12653.9 14070.3 14170.6 14538.1 14549.6 14582.4 12267.4 12523.3 12542.1 12579.7 12629.2 11203.4 11291.4 11372.5 11390.4 11580.7 12729.4 12760.4 12818.4 13032.7 13239.8 12855.2 12862.6 12908.0 12932.9 12938.5 12463.3 12559.2 12605.9 12634.4 12970.5 09:10:41 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | [#3] mootools.html Cycles:180 Average:7293.26 Median:2386.11 stddev:4677.25 (196.0%) stddev-sans-first:4669.70 09:10:41 INFO - PROCESS | 7225 | Values: 1440.1 1516.5 1551.4 1568.5 1584.5 9672.8 11315.2 11708.9 12088.7 12098.4 11722.4 11809.8 11811.2 11811.6 11814.4 2111.8 2184.5 2294.8 2329.9 2330.6 2463.0 2516.8 2531.9 2538.7 2550.9 2423.4 2436.9 2445.5 2446.7 2454.3 2422.5 2439.7 2440.3 2440.7 2446.6 2309.4 2354.2 2371.6 2400.7 2411.8 11546.0 11548.8 11564.5 11569.5 11573.4 5770.8 5822.3 5851.0 5997.5 6017.5 3581.5 3769.8 3792.3 3821.8 3826.4 1387.5 1445.7 1475.2 1485.4 1499.6 16748.7 17237.1 17843.5 18052.5 18081.9 4500.6 4549.2 4762.1 4830.5 4919.9 12892.2 13003.6 13009.9 13064.0 13089.4 5714.4 5729.2 5730.7 5750.0 5762.9 5361.7 5437.5 5447.3 5447.3 5529.7 4028.2 4057.7 4090.7 4125.4 4138.8 1029.4 1033.2 1035.4 1042.2 1053.5 1093.1 1100.9 1114.9 1124.9 1129.9 7231.4 7330.2 7408.7 7524.9 7534.0 1038.5 1049.3 1054.1 1056.1 1060.8 4783.5 4794.6 4907.5 4947.9 4971.6 14630.8 15431.1 15445.1 15451.1 15503.4 13601.0 14114.7 15175.9 15185.6 15216.8 10831.6 11042.2 11120.3 11141.6 11142.3 7409.0 7609.2 7658.6 7708.6 7755.7 13472.0 13480.7 13539.5 13559.6 13840.0 11412.3 11672.5 13905.4 13986.9 13995.2 9977.2 10124.6 10178.5 10203.3 10265.6 11233.4 11236.8 11313.0 11329.4 11386.0 9284.6 9539.0 9540.3 9605.2 9657.0 9240.3 9287.9 9306.2 9444.7 9662.6 8087.1 8259.5 8292.2 8295.0 8298.2 6525.2 6576.6 6621.9 6628.8 6730.2 6595.6 6598.6 6651.1 6690.2 6736.5 09:10:41 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | [#4] prototype.html Cycles:180 Average:4824.43 Median:5331.89 stddev:1286.29 (24.1%) stddev-sans-first:1246.47 09:10:41 INFO - PROCESS | 7225 | Values: 408.6 414.3 429.7 442.8 445.1 5201.4 5305.3 5318.9 5331.7 5332.1 5017.7 5189.4 5216.2 5252.2 5297.2 4429.9 4443.2 4451.7 4455.2 4461.4 4456.1 4486.2 4489.6 4507.5 4514.8 4243.9 4385.7 4429.4 4440.3 4499.0 4387.5 4409.3 4422.4 4425.2 4434.9 4170.6 4183.9 4195.5 4213.6 4240.8 4859.7 5102.1 5136.2 5274.7 5292.1 5109.0 5136.8 5137.2 5137.4 5152.5 5466.4 5491.4 5553.3 5574.5 5627.6 4457.4 4495.6 4547.4 4556.4 4591.5 5447.5 5540.0 5581.4 5650.5 5669.5 4592.4 4647.3 4649.6 4670.3 4708.1 4520.4 4621.4 4698.1 4699.5 4716.3 4938.7 5011.0 5033.2 5050.0 5095.6 5437.7 5490.2 5507.5 5522.0 5545.0 1577.8 1621.4 1640.9 1647.6 1668.2 1257.1 1266.5 1269.9 1290.2 1290.3 5462.8 5476.0 5516.9 5534.9 5559.0 5683.9 5702.6 5710.4 5738.2 5814.3 5306.8 5367.1 5378.2 5442.3 5453.3 2942.8 2946.9 2957.8 2980.2 2990.7 5632.0 5899.8 5901.2 5928.4 5932.6 5714.5 5748.0 5776.1 5777.4 5807.9 5834.8 5850.4 5850.8 5869.7 5887.2 5454.2 5526.0 5626.5 5665.6 5704.6 5360.5 5440.0 5446.3 5602.4 5647.0 5891.7 5896.6 5897.5 5909.5 5930.9 4508.4 4696.6 4697.8 4721.8 4735.4 5730.2 5747.5 5747.5 5766.8 5766.9 5558.3 5599.4 5609.3 5617.0 5629.3 5345.3 5494.9 5497.1 5560.8 5573.3 5547.2 5580.9 5589.6 5593.5 5594.7 5526.6 5532.5 5547.0 5578.4 5584.5 5264.3 5421.9 5441.7 5457.7 5504.7 09:10:41 INFO - PROCESS | 7225 | 09:10:41 INFO - PROCESS | 7225 | [#5] yui.html Cycles:180 Average:18261.48 Median:4110.29 stddev:22031.08 (536.0%) stddev-sans-first:22054.12 09:10:41 INFO - PROCESS | 7225 | Values: 857.7 871.7 895.7 914.0 921.6 16632.3 16777.2 17164.9 17324.7 17917.6 16266.8 16361.8 16509.8 17170.9 17369.7 612.5 617.4 851.7 868.1 871.6 882.3 886.8 889.1 889.3 889.8 938.5 941.4 941.4 941.4 942.4 933.4 935.3 936.5 937.4 937.8 681.0 681.9 685.8 686.8 687.2 17468.2 17523.0 17532.6 18388.4 18664.9 16292.3 16423.0 16486.7 16584.8 16854.4 14169.8 14204.0 14333.4 14347.8 14514.7 4093.2 4094.4 4095.0 4125.6 4220.1 45516.5 54847.2 61640.4 77956.2 89656.6 11901.6 12073.7 12308.3 12375.3 12703.2 22802.7 23234.8 23249.4 23268.3 23368.3 8987.5 9092.5 9168.5 9187.2 9272.4 5067.3 5067.6 5078.1 5216.3 5237.5 3792.6 3862.9 3896.3 3909.4 3941.2 650.0 652.4 654.5 654.9 659.6 834.4 838.2 850.7 869.3 883.2 11032.3 11141.6 11303.3 11367.3 11518.6 798.7 822.9 827.3 841.6 842.9 5337.6 5553.5 5592.6 5627.8 5634.3 81494.6 81582.0 81644.6 82858.6 85368.0 71009.3 71645.0 72073.6 73707.0 74677.0 63360.7 64281.0 64992.4 65470.7 66494.3 10511.0 10835.8 10942.1 11052.9 11056.4 37801.6 38423.0 39900.4 41465.0 45371.5 47463.8 48648.5 54752.5 55453.0 57694.0 13400.7 13434.7 13450.7 13789.6 14450.3 15860.5 16400.9 16723.6 16974.0 17220.0 15155.5 15916.1 15998.3 16583.6 16588.5 14870.6 15763.2 16024.5 16437.6 16520.6 14275.0 14894.9 15528.3 16151.3 16846.8 11538.9 11856.8 11880.6 11984.8 12329.3 12539.3 12617.2 12646.2 12673.3 12801.7 09:10:41 INFO - PROCESS | 7225 | -------- Summary: end -------- 09:10:41 INFO - PROCESS | 7225 | 09:10:42 INFO - TEST-INFO | 7225: exit 0 09:10:42 INFO - TEST-OK | dromaeo_css | took 1115634ms 09:10:42 INFO - TEST-START | kraken 09:10:42 INFO - operating with platform_type : linux_ 09:10:42 INFO - Initialising browser for kraken test... 09:10:42 INFO - TEST-INFO | started process 7553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYzA6YO/profile http://localhost:52783/getInfo.html) 09:10:46 INFO - PROCESS | 7553 | __metrics Screen width/height:1600/1200 09:10:46 INFO - PROCESS | 7553 | colorDepth:24 09:10:46 INFO - PROCESS | 7553 | Browser inner width/height: 1024/697 09:10:46 INFO - PROCESS | 7553 | __metrics 09:10:47 INFO - PROCESS | 7553 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:10:47 INFO - PROCESS | 7553 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:10:48 INFO - TEST-INFO | 7553: exit 0 09:10:48 INFO - Browser initialized. 09:10:48 INFO - Running cycle 1/1 for kraken test... 09:10:48 INFO - TEST-INFO | started process 7647 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYzA6YO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:11:00 INFO - PROCESS | 7647 | RSS: Main: 140128256 09:11:00 INFO - PROCESS | 7647 | 09:11:02 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52783/tests/kraken/driver.html?testName=audio-beat-detection) 09:11:03 INFO - PROCESS | 7647 | RSS: Main: 183750656 09:11:03 INFO - PROCESS | 7647 | 09:11:05 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52783/tests/kraken/driver.html?testName=audio-dft) 09:11:06 INFO - PROCESS | 7647 | RSS: Main: 173899776 09:11:06 INFO - PROCESS | 7647 | 09:11:09 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52783/tests/kraken/driver.html?testName=audio-fft) 09:11:09 INFO - PROCESS | 7647 | RSS: Main: 266366976 09:11:09 INFO - PROCESS | 7647 | 09:11:12 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52783/tests/kraken/driver.html?testName=audio-oscillator) 09:11:12 INFO - PROCESS | 7647 | RSS: Main: 172265472 09:11:12 INFO - PROCESS | 7647 | 09:11:14 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52783/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:11:15 INFO - PROCESS | 7647 | RSS: Main: 174723072 09:11:15 INFO - PROCESS | 7647 | 09:11:18 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52783/tests/kraken/driver.html?testName=imaging-darkroom) 09:11:18 INFO - PROCESS | 7647 | RSS: Main: 213987328 09:11:18 INFO - PROCESS | 7647 | 09:11:22 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52783/tests/kraken/driver.html?testName=imaging-desaturate) 09:11:22 INFO - PROCESS | 7647 | RSS: Main: 212443136 09:11:22 INFO - PROCESS | 7647 | 09:11:25 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52783/tests/kraken/driver.html?testName=json-parse-financial) 09:11:26 INFO - PROCESS | 7647 | RSS: Main: 210989056 09:11:26 INFO - PROCESS | 7647 | 09:11:27 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52783/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:11:28 INFO - PROCESS | 7647 | RSS: Main: 173043712 09:11:28 INFO - PROCESS | 7647 | 09:11:29 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52783/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:11:30 INFO - PROCESS | 7647 | RSS: Main: 180191232 09:11:30 INFO - PROCESS | 7647 | 09:11:32 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52783/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:11:33 INFO - PROCESS | 7647 | RSS: Main: 189464576 09:11:33 INFO - PROCESS | 7647 | 09:11:35 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52783/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:11:36 INFO - PROCESS | 7647 | RSS: Main: 198459392 09:11:36 INFO - PROCESS | 7647 | 09:11:39 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52783/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:11:39 INFO - PROCESS | 7647 | RSS: Main: 194699264 09:11:39 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | Cycle 1(1): loaded http://localhost:52783/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52783/tests/kraken/driver.html?testName=ai-astar) 09:11:41 INFO - PROCESS | 7647 | RSS: Main: 247046144 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | __start_tp_report 09:11:41 INFO - PROCESS | 7647 | _x_x_mozilla_page_load 09:11:41 INFO - PROCESS | 7647 | _x_x_mozilla_page_load_details 09:11:41 INFO - PROCESS | 7647 | |i|pagename|runs| 09:11:41 INFO - PROCESS | 7647 | |0;ai-astar;103;114;112;112;114;113;113;111;113;112 09:11:41 INFO - PROCESS | 7647 | |1;audio-beat-detection;148;114;115;155;120;117;121;115;132;116 09:11:41 INFO - PROCESS | 7647 | |2;audio-dft;161;167;161;162;162;160;162;162;161;162 09:11:41 INFO - PROCESS | 7647 | |3;audio-fft;76;76;76;109;78;77;77;76;104;77 09:11:41 INFO - PROCESS | 7647 | |4;audio-oscillator;83;82;105;82;82;82;101;82;82;113 09:11:41 INFO - PROCESS | 7647 | |5;imaging-gaussian-blur;113;113;114;113;113;113;113;113;113;113 09:11:41 INFO - PROCESS | 7647 | |6;imaging-darkroom;146;146;146;147;146;146;146;147;146;146 09:11:41 INFO - PROCESS | 7647 | |7;imaging-desaturate;94;94;94;93;94;94;94;94;94;93 09:11:41 INFO - PROCESS | 7647 | |8;json-parse-financial;56;55;56;54;54;55;54;54;54;55 09:11:41 INFO - PROCESS | 7647 | |9;json-stringify-tinderbox;54;54;54;53;54;54;53;53;53;53 09:11:41 INFO - PROCESS | 7647 | |10;stanford-crypto-aes;76;75;81;77;79;81;76;81;76;81 09:11:41 INFO - PROCESS | 7647 | |11;stanford-crypto-ccm;129;123;122;128;120;125;134;117;157;119 09:11:41 INFO - PROCESS | 7647 | |12;stanford-crypto-pbkdf2;159;158;161;166;161;164;165;159;161;159 09:11:41 INFO - PROCESS | 7647 | |13;stanford-crypto-sha256-iterative;61;64;60;61;61;60;62;60;60;60 09:11:41 INFO - PROCESS | 7647 | __end_tp_report 09:11:41 INFO - PROCESS | 7647 | __start_cc_report 09:11:41 INFO - PROCESS | 7647 | _x_x_mozilla_cycle_collect,1673 09:11:41 INFO - PROCESS | 7647 | __end_cc_report 09:11:41 INFO - PROCESS | 7647 | __startTimestamp1491667901646__endTimestamp 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | ------- Summary: start ------- 09:11:41 INFO - PROCESS | 7647 | Number of tests: 14 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#0] ai-astar Cycles:10 Average:111.70 Median:113.00 stddev:3.20 (2.8%) stddev-sans-first:1.00 09:11:41 INFO - PROCESS | 7647 | Values: 103.0 114.0 112.0 112.0 114.0 113.0 113.0 111.0 113.0 112.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#1] audio-beat-detection Cycles:10 Average:125.30 Median:120.50 stddev:14.85 (12.3%) stddev-sans-first:13.28 09:11:41 INFO - PROCESS | 7647 | Values: 148.0 114.0 115.0 155.0 120.0 117.0 121.0 115.0 132.0 116.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.89 (1.2%) stddev-sans-first:1.96 09:11:41 INFO - PROCESS | 7647 | Values: 161.0 167.0 161.0 162.0 162.0 160.0 162.0 162.0 161.0 162.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#3] audio-fft Cycles:10 Average:82.60 Median:76.50 stddev:12.67 (16.6%) stddev-sans-first:13.21 09:11:41 INFO - PROCESS | 7647 | Values: 76.0 76.0 76.0 109.0 78.0 77.0 77.0 76.0 104.0 77.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:82.00 stddev:12.04 (14.7%) stddev-sans-first:12.54 09:11:41 INFO - PROCESS | 7647 | Values: 83.0 82.0 105.0 82.0 82.0 82.0 101.0 82.0 82.0 113.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:11:41 INFO - PROCESS | 7647 | Values: 113.0 113.0 114.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 09:11:41 INFO - PROCESS | 7647 | Values: 146.0 146.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#7] imaging-desaturate Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 09:11:41 INFO - PROCESS | 7647 | Values: 94.0 94.0 94.0 93.0 94.0 94.0 94.0 94.0 94.0 93.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:0.82 (1.5%) stddev-sans-first:0.73 09:11:41 INFO - PROCESS | 7647 | Values: 56.0 55.0 56.0 54.0 54.0 55.0 54.0 54.0 54.0 55.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#9] json-stringify-tinderbox Cycles:10 Average:53.50 Median:54.00 stddev:0.53 (1.0%) stddev-sans-first:0.53 09:11:41 INFO - PROCESS | 7647 | Values: 54.0 54.0 54.0 53.0 54.0 54.0 53.0 53.0 53.0 53.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#10] stanford-crypto-aes Cycles:10 Average:78.30 Median:80.00 stddev:2.54 (3.2%) stddev-sans-first:2.55 09:11:41 INFO - PROCESS | 7647 | Values: 76.0 75.0 81.0 77.0 79.0 81.0 76.0 81.0 76.0 81.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#11] stanford-crypto-ccm Cycles:10 Average:127.40 Median:126.50 stddev:11.60 (9.2%) stddev-sans-first:12.29 09:11:41 INFO - PROCESS | 7647 | Values: 129.0 123.0 122.0 128.0 120.0 125.0 134.0 117.0 157.0 119.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.30 Median:161.00 stddev:2.79 (1.7%) stddev-sans-first:2.83 09:11:41 INFO - PROCESS | 7647 | Values: 159.0 158.0 161.0 166.0 161.0 164.0 165.0 159.0 161.0 159.0 09:11:41 INFO - PROCESS | 7647 | 09:11:41 INFO - PROCESS | 7647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:1.29 (2.1%) stddev-sans-first:1.36 09:11:41 INFO - PROCESS | 7647 | Values: 61.0 64.0 60.0 61.0 61.0 60.0 62.0 60.0 60.0 60.0 09:11:41 INFO - PROCESS | 7647 | -------- Summary: end -------- 09:11:41 INFO - PROCESS | 7647 | 09:11:42 INFO - TEST-INFO | 7647: exit 0 09:11:42 INFO - TEST-OK | kraken | took 60414ms 09:11:42 INFO - SUITE-END | took 1176s 09:11:42 INFO - Completed test suite (00:19:36) 09:11:42 INFO - javascript benchmark 09:11:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7904.750607091663, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3404.8297585120736, 3434.536337594424, 3454.533637958876, 3456.9827150864244, 3504.9123771905697, 5719.799807006755, 5780.797672081478, 5794.652320860751, 5796.014677504823, 5805.767769289228, 5766.711664416784, 5807.8257652270395, 5811.27359080115, 5812.825615231539, 5815.709214539267, 5217.165253559431, 5224.791008359661, 5248.816291429801, 5313.521783039526, 5334.066538355792, 5136.691798492084, 5150.459201783815, 5333.38666053403, 5342.332208473941, 5349.251104845324, 5189.792408303663, 5224.660397074197, 5300.575953923677, 5313.017091838003, 5319.441458646845, 5321.680699158043, 5324.227986941872, 5328.786848526054, 5329.733513324357, 5336.279602253724, 5075.923861142086, 5184.766685499161, 5237.109691352441, 5287.365516138088, 5293.311869456985, 5635.887282254373, 5640.802571909963, 5713.542916566665, 5734.541236701054, 5739.196512488255, 6149.262088549358, 6174.7530098796, 6182.752689892444, 6191.040388739752, 6216.595921265103, 6228.308228557202, 6232.594881332744, 6238.656873871935, 6255.718492667841, 6271.968640156816, 5544.0, 5577.860553486195, 5726.026575482137, 5780.450857168562, 5842.152887831326, 6353.047042943595, 6357.491400688026, 6361.14124593174, 6363.363663633692, 6368.203974503187, 6015.4586087252355, 6117.113018612276, 6117.510599152057, 6126.326104128542, 6131.754729810757, 6007.279126504847, 6032.095185722089, 6106.603070800384, 6113.23584551931, 6220.657863817533, 6121.329396744349, 6133.916185115405, 6135.4593606941635, 6144.692765361714, 6153.384661533811, 5926.733297001645, 5957.165996760457, 6053.364396738367, 6105.633661980207, 6128.4531764236735, 4662.836800711959, 4670.976645116821, 4700.247960326331, 4708.69393489429, 4739.218029025241, 4105.794710264475, 4112.506499220112, 4133.318002529609, 4199.391088292181, 4215.97892010538, 5948.375420580864, 5970.402959704082, 6017.729202185912, 6029.155918171459, 6030.27636683592, 6151.361375033566, 6203.379662033761, 6232.781852635136, 6243.656598887105, 6249.312575616678, 5719.3136823581435, 5927.9703601481715, 5934.1692163097205, 5935.0, 5957.255343082114, 5115.130427827278, 5139.665921715076, 5210.21846722987, 5261.789528418897, 5265.0, 6413.895276623578, 6436.485081193493, 6446.967765161145, 6448.484121270381, 6452.967735161388, 6122.6938653068055, 6185.938140618537, 6201.0, 6201.069839524108, 6201.565890387629, 6218.875622487615, 6261.530385221035, 6370.936290637034, 6379.298277189413, 6473.061406096184, 6133.273457575638, 6137.499563105042, 6142.836439233887, 6143.481172719343, 6145.66198859067, 6246.500279977682, 6246.812595622138, 6250.812475625738, 6260.624362538262, 6274.901892168943, 5791.913121303268, 5896.262967129109, 5973.970130149321, 5988.790392336247, 6080.939190608037, 6060.515158787374, 6098.329183789869, 6108.450239478469, 6126.172966649446, 6139.508839292756, 6071.605345652519, 6079.452849243589, 6080.48315893154, 6084.847878803065, 6085.0, 5867.794627188028, 5881.500072493886, 5918.852028699317, 6034.366391529002, 6050.42520960508, 5977.491913187428, 5984.970075149597, 5990.161377407079, 6022.66875321844, 6023.397660233942], "value": 5699.873579966905, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.648605155577, 819.6639377855079, 832.6544484039122, 891.8305521950829, 903.5798353765498, 29623.40753184935, 41376.793116034416, 42156.36765448517, 46004.76997615007, 46539.0, 42459.575404245945, 42633.57366426335, 42844.57155428453, 42864.0, 42877.0, 7822.843543129134, 7963.482373645724, 8971.102889711043, 9102.271818254558, 9105.908940910605, 10124.190064794799, 10329.328593641427, 10365.429901355423, 10367.481625918712, 10636.255462117617, 10458.68623941283, 10601.257911946166, 10603.469826508683, 10603.787924241511, 10647.988441098083, 10376.584936602603, 10403.167746580293, 10437.68686939393, 10516.84224736622, 10593.788124237512, 10334.534945927451, 10372.377657340508, 10393.532291046846, 10396.272260941738, 10397.48012599367, 39684.40473392901, 39935.0, 39941.60058399408, 39960.80019599883, 39967.8001609993, 4190.748555086705, 4210.3684447332835, 4262.019735460831, 4319.114581510814, 4354.303311470181, 29116.563251551244, 29813.10560683183, 29941.700582994108, 30188.54717179244, 31328.0, 4265.296226122686, 4337.544557821415, 4355.586219309161, 4384.079343337903, 4464.196444639963, 235159.0, 236248.81875590683, 236613.0, 237009.0, 238144.0, 53000.73499632477, 53097.469025309256, 53119.20321195185, 53173.202401964, 53211.46788532143, 55126.17310740342, 55143.72428137834, 55181.0, 55209.0, 55255.0, 15933.442329518411, 16225.0, 16240.918795405947, 16242.350305987628, 16246.0, 11982.340971246666, 12159.817602735966, 13663.658408539866, 14323.212223327608, 14340.067895586963, 13120.475180992844, 13153.868461315264, 13159.407826647825, 13220.801687974688, 13238.338083095807, 5457.181422786613, 5466.152746324246, 5515.7242137892945, 5521.613487055948, 5646.661200327993, 1015.761296095417, 1018.908298253146, 1028.1928685981618, 1049.8582691336724, 1056.746380868586, 26357.736422635528, 27662.0, 27740.306492337448, 28050.896182785964, 28461.715382845905, 1078.692572616816, 1092.7650555130685, 1108.2630051016145, 1117.8435019097333, 1127.5489804078434, 14173.291335433187, 14917.47788827386, 15007.699846003019, 15071.0, 15233.542993710207, 308281.0, 309376.0, 310375.0, 311227.0, 311506.0, 54169.72915135399, 54184.45815541873, 54197.72901135469, 54234.0, 54259.45740542623, 1478.8373278939307, 1493.6191271225769, 1509.2604623734292, 1512.3874830693587, 1520.0, 25247.621285681096, 26759.331016724736, 26760.59859102076, 26819.0, 26821.329466763487, 39887.20225595414, 40002.59997399989, 40010.79994600009, 40053.79973100233, 40063.0, 287114.95097671484, 287404.0, 287462.0, 287770.0, 288243.0, 29377.412451751272, 29989.70010299869, 33762.3247535043, 33794.66205337915, 33825.323493530486, 42301.46191228413, 42550.0, 42625.57374426216, 42700.78649606732, 42741.0, 33515.0, 33550.49674254839, 33614.0, 33625.83187084049, 33915.830420848724, 33232.83383583163, 33369.83315083409, 33377.499337510446, 33487.0, 34104.829475852464, 38293.617063829006, 38312.233755325295, 38315.61684383232, 38316.42525362066, 38383.42424863573, 31494.370112598077, 31871.840640796647, 31957.68042319547, 32489.0, 32573.348533028733, 25174.0, 25999.220023399328, 26290.502402271755, 26351.0, 26374.604380933917], "value": 18737.658273448607, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [442.27687730560524, 462.6229622857371, 466.85060780550214, 473.8436316015714, 490.6957686234535, 19557.706634400478, 20884.37346879594, 21321.147154113834, 21418.678719819178, 21636.3509094727, 20879.269225577107, 21101.0, 21288.148474061018, 21529.0, 22197.223097191603, 6714.798556043326, 6951.617661028641, 6952.09622749042, 7171.067761191064, 7398.334149926505, 7236.131664200303, 7445.069366329209, 7490.925090749104, 7496.662650180728, 7499.887501687479, 7652.31129198372, 7675.616219189046, 7676.501027433199, 7678.808029799244, 7678.846423071535, 7302.379297759697, 7560.621968901533, 7588.317051465395, 7610.771676849703, 7658.770236892902, 7382.9630851845395, 7515.436342274351, 7534.359579435754, 7547.547147171133, 7580.734674286374, 25086.121985730413, 25379.111731089324, 25442.236732897858, 25476.87261563699, 25694.0, 3103.8603262853226, 3501.4047611906035, 3595.874144404959, 3680.926381472382, 3696.2792255510185, 15275.541733747894, 15848.207589620588, 15855.286512106868, 15968.121753303563, 16742.58143546409, 2242.854214476054, 2257.356653353803, 2282.703248577691, 2341.2390972933863, 2359.3511784259294, 114919.41313418827, 118068.0, 118932.0, 119553.0, 125483.0, 18199.545011374823, 18552.350667726565, 18956.431307060535, 18996.240150394107, 19094.47244220459, 32660.346793064007, 32732.0, 32743.67256327454, 32761.0, 32808.67191328104, 10602.840957385646, 10619.469026548642, 10694.679159625224, 10717.249792514449, 11122.832102629269, 8953.59708813105, 8981.326400519987, 9025.458472491671, 9213.12475314844, 9218.21645160169, 9312.627494900127, 9359.847235135821, 9503.429794212369, 9636.71089867305, 9713.320067595341, 3391.932161356759, 3519.63043880394, 3956.647687543679, 4077.7145599807727, 4100.569440208795, 957.875476188091, 974.6880998080546, 985.4579981010415, 998.7203582996773, 1011.8330475471465, 20824.58350832975, 21468.892655536623, 21485.140594376364, 21489.892550537148, 21694.891525542273, 991.1228562721899, 997.0826839307854, 1001.2540657210279, 1010.4594042187541, 1024.4570377699831, 10875.293105948089, 11753.764924701458, 11870.88129118715, 11978.34119123439, 11985.460654270579, 649774.0, 655511.0, 655992.0, 669125.0, 677314.0, 92040.0, 94778.52610736902, 116867.41566292114, 119632.0, 124660.0, 1328.5350127455506, 1346.7239215960901, 1370.5066176176767, 1390.9443622255199, 1392.9860701392856, 18096.72854907151, 18459.538511537317, 18716.532086697942, 18915.432537023913, 18997.145128468976, 50808.23787643262, 51587.48412515827, 52211.73894130505, 52238.73880630724, 52543.0, 65890.0, 66921.33078669151, 67378.66310668415, 67599.66200168968, 68456.6577167111, 12447.502099915902, 12497.687557811127, 12594.433250503933, 12631.242125472501, 12653.87346126527, 14070.296485175904, 14170.574882753534, 14538.12771233687, 14549.636259093608, 14582.416703331748, 12267.386630668609, 12523.311217883103, 12542.12205145631, 12579.74840503203, 12629.2422454653, 11203.439828008404, 11291.378974156172, 11372.488238029475, 11390.37352945596, 11580.6525804226, 12729.363531823557, 12760.42578084007, 12818.359082045674, 13032.674183145496, 13239.801402979156, 12855.16441431304, 12862.614121576367, 12908.0, 12932.870671293544, 12938.482460701467, 12463.31451770125, 12559.183653062522, 12605.928496077937, 12634.368281586068, 12970.481180752664], "value": 11767.848991070532, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.1071335771824, 1516.5450364890532, 1551.4259723902157, 1568.5294411676498, 1584.5484037049437, 9672.806543869126, 11315.151363647728, 11708.882911170896, 12088.697782555419, 12098.395080245997, 11722.413879306043, 11809.822852657195, 11811.173217874752, 11811.645650630473, 11814.350210738407, 2111.8416118791074, 2184.453886528368, 2294.7934685878267, 2329.9184528541505, 2330.5571941331177, 2463.0, 2516.811239157061, 2531.860747658878, 2538.6953565572067, 2550.923472295834, 2423.442608200115, 2436.9390765230833, 2445.486447845946, 2446.694163229596, 2454.3127924181167, 2422.4912768318673, 2439.6828412306377, 2440.255722004788, 2440.7193172785, 2446.620773780067, 2309.434188623781, 2354.2466410748566, 2371.5612611666897, 2400.663907053014, 2411.807055435561, 11546.0, 11548.826767598493, 11564.479598418007, 11569.53721851125, 11573.363465009505, 5770.7691692332255, 5822.272215973004, 5850.97074514629, 5997.460228579405, 6017.518598512109, 3581.4985901973746, 3769.754965927206, 3792.2984247914223, 3821.8280177392085, 3826.426036094608, 1387.5074348606302, 1445.7180849734307, 1475.1960181700892, 1485.3538710660896, 1499.587613406311, 16748.748768768477, 17237.051962142075, 17843.46469605901, 18052.54868628295, 18081.909590452095, 4500.639948804088, 4549.249373853278, 4762.142814293391, 4830.492798256132, 4919.901601968012, 12892.226466412045, 13003.609891703263, 13009.934950325378, 13064.0, 13089.41097650608, 5714.428557144252, 5729.226554415184, 5730.7421166047625, 5749.9712501437225, 5762.942370576325, 5361.651492652965, 5437.4562543745305, 5447.264619276426, 5447.346318441812, 5529.723513824292, 4028.24142237253, 4057.655099316532, 4090.6931980101613, 4125.401816736557, 4138.8344466221615, 1029.3926583315879, 1033.1579762493543, 1035.4305132177271, 1042.1818872185297, 1053.5048527192207, 1093.092733031582, 1100.935646390195, 1114.9498272577753, 1124.876263611002, 1129.88701129888, 7231.421486281085, 7330.193678695339, 7408.666610002563, 7524.887126693104, 7534.0, 1038.5326603028668, 1049.2655141401049, 1054.0671505717496, 1056.12869382758, 1060.8355704865758, 4783.545563171493, 4794.5924596408995, 4907.509249075064, 4947.901041979212, 4971.577415919687, 14630.780538291934, 15431.074135551902, 15445.073295602075, 15451.072935623673, 15503.379864805507, 13601.0, 14114.71770564583, 15175.924120379328, 15185.6203594911, 15216.771748423562, 10831.620893268699, 11042.227044106996, 11120.332780033063, 11141.554337826557, 11142.331460112418, 7408.9629551851895, 7609.162992070978, 7658.617069146632, 7708.576028318272, 7755.650995705094, 13472.0, 13480.73038539204, 13539.458421663418, 13559.59321220365, 13840.0, 11412.31526108436, 11672.533098676298, 13905.37425815861, 13986.930065349608, 13995.16029038261, 9977.201823854219, 10124.645637402655, 10178.491075446345, 10203.336783109075, 10265.58937642486, 11233.38216398106, 11236.83144752813, 11313.0, 11329.433528323387, 11386.032187263843, 9284.58219380116, 9539.046095390406, 9540.33217674756, 9605.231581473325, 9656.95171524138, 9240.260779137518, 9287.907120928705, 9306.162445379949, 9444.669436569688, 9662.565184566853, 8087.069986951341, 8259.545724985184, 8292.212239837207, 8294.958525207336, 8298.170182981896, 6525.184351956005, 6576.605403675603, 6621.867562648817, 6628.801135965929, 6730.192376914802, 6595.637239951849, 6598.609557295667, 6651.13535240435, 6690.19717633887, 6736.528443009139], "value": 5504.456676285954, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.61385990239233, 414.2956973145653, 429.67559492583115, 442.77197243419636, 445.112001556894, 5201.401838788541, 5305.283786688796, 5318.867028324294, 5331.653442526238, 5332.120200166968, 5017.749112544368, 5189.42916279209, 5216.191490318997, 5252.159654455288, 5297.231901374298, 4429.911401771963, 4443.15580039793, 4451.710638808483, 4455.220336441134, 4461.353103799964, 4456.086502267028, 4486.192485352635, 4489.585780479155, 4507.549245075499, 4514.774261286922, 4243.851465198719, 4385.7149285296355, 4429.379886815848, 4440.333949907524, 4499.0, 4387.530177390578, 4409.294512877957, 4422.425084738979, 4425.181341451842, 4434.889127771799, 4170.624643782075, 4183.91632167355, 4195.454590903194, 4213.5786421357925, 4240.787960601958, 4859.708417494961, 5102.107131252016, 5136.178211486182, 5274.736263186864, 5292.142243188673, 5109.0, 5136.768845401966, 5137.17805151174, 5137.38351397831, 5152.510511501401, 5466.426025267369, 5491.368492623359, 5553.305836770403, 5574.526165275956, 5627.6060675752715, 4457.420535330419, 4495.64034877209, 4547.363369128325, 4556.430446194226, 4591.51789062147, 5447.536959358459, 5539.975104605661, 5581.386047534768, 5650.5479561634975, 5669.518090962273, 4592.425946756655, 4647.326137710013, 4649.604783593365, 4670.276107203389, 4708.081924024851, 4520.4233586855835, 4621.445426548768, 4698.107359601665, 4699.483056863743, 4716.268978308368, 4938.678985865908, 5011.0, 5033.245013247969, 5050.0, 5095.592352611782, 5437.694953211201, 5490.231367608538, 5507.504324610804, 5521.972390138024, 5544.972275138599, 1577.8027746531684, 1621.4243943399922, 1640.8605268552308, 1647.596338896954, 1668.2242757118, 1257.0886107571937, 1266.5181737367302, 1269.853966793812, 1290.2452065541574, 1290.2709968867748, 5462.781488740486, 5476.0, 5516.889662206733, 5534.861628459321, 5558.972205139029, 5683.886322273532, 5702.6293290935955, 5710.428957104339, 5738.196652468658, 5814.336967710316, 5306.8142615009065, 5367.087595108841, 5378.2470454136455, 5442.292501974797, 5453.318335208099, 2942.778746820046, 2946.9391019233058, 2957.792954493208, 2980.180450376164, 2990.6560745514544, 5631.971840140773, 5899.823005309848, 5901.200133959183, 5928.407159284037, 5932.644041357446, 5714.457126572969, 5748.039759523848, 5776.075827867521, 5777.364489906107, 5807.912881306784, 5834.795782147605, 5850.385709500526, 5850.824475265749, 5869.706514674249, 5887.234659494238, 5454.218348642142, 5525.972370138124, 5626.465485779008, 5665.631733937281, 5704.572157088152, 5360.517553420055, 5439.972800136133, 5446.291982042309, 5602.355729091207, 5647.0, 5891.734871930687, 5896.584819643169, 5897.469227769521, 5909.468147866713, 5930.940690593039, 4508.368828363966, 4696.647751418589, 4697.84902698841, 4721.834735784231, 4735.413636431834, 5730.226419433323, 5747.48272655463, 5747.48272655463, 5766.769329226951, 5766.942330576809, 5558.277423934863, 5599.440055994368, 5609.27079479665, 5616.971915140562, 5629.296337957755, 5345.251664767014, 5494.945050549443, 5497.120460726424, 5560.777568897199, 5573.303337082864, 5547.2233887256625, 5580.888382232414, 5589.636673616202, 5593.524550413261, 5594.748236329294, 5526.613137080527, 5532.5020748132865, 5546.972265138648, 5578.414266501959, 5584.497395234448, 5264.263003179634, 5421.945780542144, 5441.727913604224, 5457.672539647634, 5504.697241651747], "value": 4451.8962738600285, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [857.6526506764762, 871.7036207689388, 895.7312806158153, 914.0265617117773, 921.5578716401537, 16632.25154868031, 16777.1611419429, 17164.914175429105, 17324.653506929884, 17917.552061198476, 16266.75599866003, 16361.83638163618, 16509.83490165098, 17170.914145429288, 17369.652606947853, 612.5069319198044, 617.4227097663677, 851.6593362654941, 868.1405408645427, 871.6295574380895, 882.3250213586612, 886.822635472908, 889.0620395482763, 889.2663552569124, 889.750869756466, 938.5166639180827, 941.3504681769591, 941.3551717073792, 941.4492521874712, 942.420411446962, 933.4399360383783, 935.3031991166556, 936.4802534593264, 937.3860121620315, 937.8030613571158, 680.9659517024179, 681.9011243369733, 685.8182581615876, 686.821426429132, 687.2165731066588, 17468.213930373036, 17523.0, 17532.649347013117, 18388.356407525673, 18664.90667546667, 16292.348306067743, 16423.0, 16486.67026659472, 16584.83415165845, 16854.41009564659, 14169.78745318821, 14204.0, 14333.354999025069, 14347.78478322826, 14514.709705805824, 4093.16090201508, 4094.385842123705, 4095.0, 4125.5874412558505, 4220.092680073768, 45516.544834551896, 54847.177292340646, 61640.38359616391, 77956.22043779546, 89656.55171724099, 11901.583444579572, 12073.698157546132, 12308.323042232583, 12375.319357435428, 12703.237805731686, 22802.65796013061, 23234.767652323597, 23249.418764530685, 23268.3019509415, 23368.298951031495, 8987.46075235488, 9092.454452732858, 9168.54157292146, 9187.219086377732, 9272.397294175857, 5067.341245638044, 5067.569256613229, 5078.136716758087, 5216.347956505437, 5237.450067742909, 3792.639699228569, 3862.9227415452096, 3896.337622604164, 3909.354956432214, 3941.192055628589, 650.0, 652.422605993699, 654.450261780107, 654.9443297319781, 659.5844617890795, 834.4299557363306, 838.1702114906199, 850.6937502499095, 869.3393021303729, 883.2492381475694, 11032.28290161137, 11141.554337826557, 11303.265287756269, 11367.317960922206, 11518.596849110409, 798.6685525506849, 822.9341652667772, 827.2926647716237, 841.6044459104211, 842.8946381702287, 5337.646180641951, 5553.4724201200825, 5592.552595792326, 5627.803026894039, 5634.295713035871, 81494.59252703699, 81582.0, 81644.59177704192, 82858.58570707108, 85368.0, 71009.28990710026, 71645.0, 72073.6396318015, 73707.0, 74677.0, 63360.68319658372, 64281.0, 64992.35007649958, 65470.67264663646, 66494.33505664881, 10511.0, 10835.837462438227, 10942.124630029419, 11052.889471105185, 11056.447177641247, 37801.62198377981, 38423.0, 39900.40149397819, 41465.0, 45371.54628453673, 47463.762681186374, 48648.5135148644, 54752.45247547473, 55453.0, 57694.0, 13400.731985360433, 13434.664133396744, 13450.663733406744, 13789.586312410644, 14450.277486125862, 15860.52418427449, 16400.917995409945, 16723.581910452336, 16974.0, 17219.96680199192, 15155.469558565397, 15916.124613145925, 15998.280077396312, 16583.585410364838, 16588.50234492967, 14870.553883383516, 15763.211839407755, 16024.519264422086, 16437.58906027359, 16520.586985325463, 14275.0, 14894.925525372737, 15528.301226444612, 16151.27319270659, 16846.831531685017, 11538.94230528842, 11856.762864742484, 11880.643580692593, 11984.76030479403, 12329.321887296284, 12539.310337931136, 12617.242965422103, 12646.241225526497, 12673.30296833683, 12801.743965120831], "value": 7920.5257857362785, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1460.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 114.0, 112.0, 112.0, 114.0, 113.0, 113.0, 111.0, 113.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 115.0, 155.0, 120.0, 117.0, 121.0, 115.0, 132.0, 116.0], "value": 125.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 167.0, 161.0, 162.0, 162.0, 160.0, 162.0, 162.0, 161.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 109.0, 78.0, 77.0, 77.0, 76.0, 104.0, 77.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 105.0, 82.0, 82.0, 82.0, 101.0, 82.0, 82.0, 113.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 53.0, 54.0, 54.0, 53.0, 53.0, 53.0, 53.0], "value": 53.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 81.0, 77.0, 79.0, 81.0, 76.0, 81.0, 76.0, 81.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 123.0, 122.0, 128.0, 120.0, 125.0, 134.0, 117.0, 157.0, 119.0], "value": 127.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 158.0, 161.0, 166.0, 161.0, 164.0, 165.0, 159.0, 161.0, 159.0], "value": 161.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 60.0, 61.0, 61.0, 60.0, 62.0, 60.0, 60.0, 60.0], "value": 60.9, "unit": "score"}]}]} 09:11:42 INFO - Return code: 0 09:11:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:11:42 ERROR - # TBPL SUCCESS # 09:11:42 INFO - Running post-action listener: _resource_record_post_action 09:11:42 INFO - [mozharness: 2017-04-08 16:11:42.989317Z] Finished run-tests step (success) 09:11:42 INFO - Running post-run listener: _resource_record_post_run 09:11:43 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 266240; Write bytes: 366866432; Read time: 24; Write time: 262368 09:11:43 INFO - TinderboxPrint: CPU usage
13.2% 09:11:43 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 24 09:11:43 INFO - TinderboxPrint: I/O write bytes / time
366,866,432 / 262,368 09:11:43 INFO - TinderboxPrint: CPU idle
8,268.1 (86.8%) 09:11:43 INFO - TinderboxPrint: CPU user
1,205.3 (12.7%) 09:11:43 INFO - TinderboxPrint: Swap in / out
0 / 0 09:11:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111718400; Read time: 0; Write time: 110176 09:11:43 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 266240; Write bytes: 254046208; Read time: 24; Write time: 152136 09:11:43 INFO - Running post-run listener: _upload_blobber_files 09:11:43 INFO - Blob upload gear active. 09:11:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:11:43 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 09:11:43 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:11:43 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:11:44 INFO - (blobuploader) - INFO - Open directory for files ... 09:11:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:11:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:11:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:47 INFO - (blobuploader) - INFO - Done attempting. 09:11:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:11:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:11:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:48 INFO - (blobuploader) - INFO - Done attempting. 09:11:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:11:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:49 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:11:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:49 INFO - (blobuploader) - INFO - Done attempting. 09:11:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:11:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:11:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:49 INFO - (blobuploader) - INFO - Done attempting. 09:11:49 INFO - (blobuploader) - INFO - Iteration through files over. 09:11:49 INFO - Return code: 0 09:11:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:11:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:11:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/062f2f1eff5b59de208cc7dfec8e965170909d113949a03d5f767bc95def04ad41dbf489421e7f0bc5326d4911bf3e9598b70375b09c5c243f8e27a7fd9be725", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6ca2a7950b76f506812f36207258e3a55dc136550c886d3e40a4bbcd6e2ec5b6585ea877e83ef1ce36a23fe3a646d25b634e45d32a1eb0a8b669d3f591b4270", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daf8956ee0fc1a2e0e54b3b5a36c37a0b5837f05c1d81b3666171bdf9de2ffe6916e1a40338bdb012854ac91430b87bbad801f0175af7dc9525d955ae3b79d9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fca853864cc998dc586719df14918d506e0cd938c77dea7697a9d346b87ddb78f36f53a30cf4fd65ca169193c72443bb4e9b6e5b262455f9312b60ced4450497"} 09:11:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:11:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:11:49 INFO - Contents: 09:11:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/062f2f1eff5b59de208cc7dfec8e965170909d113949a03d5f767bc95def04ad41dbf489421e7f0bc5326d4911bf3e9598b70375b09c5c243f8e27a7fd9be725", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6ca2a7950b76f506812f36207258e3a55dc136550c886d3e40a4bbcd6e2ec5b6585ea877e83ef1ce36a23fe3a646d25b634e45d32a1eb0a8b669d3f591b4270", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daf8956ee0fc1a2e0e54b3b5a36c37a0b5837f05c1d81b3666171bdf9de2ffe6916e1a40338bdb012854ac91430b87bbad801f0175af7dc9525d955ae3b79d9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fca853864cc998dc586719df14918d506e0cd938c77dea7697a9d346b87ddb78f36f53a30cf4fd65ca169193c72443bb4e9b6e5b262455f9312b60ced4450497"} 09:11:49 INFO - Running post-run listener: copy_logs_to_upload_dir 09:11:49 INFO - Copying logs to upload dir... 09:11:49 INFO - mkdir: /builds/slave/test/build/upload/logs 09:11:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.897359 ========= master_lag: -0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2017-04-08 09:11:48.466786) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-08 09:11:48.472190) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/062f2f1eff5b59de208cc7dfec8e965170909d113949a03d5f767bc95def04ad41dbf489421e7f0bc5326d4911bf3e9598b70375b09c5c243f8e27a7fd9be725", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6ca2a7950b76f506812f36207258e3a55dc136550c886d3e40a4bbcd6e2ec5b6585ea877e83ef1ce36a23fe3a646d25b634e45d32a1eb0a8b669d3f591b4270", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daf8956ee0fc1a2e0e54b3b5a36c37a0b5837f05c1d81b3666171bdf9de2ffe6916e1a40338bdb012854ac91430b87bbad801f0175af7dc9525d955ae3b79d9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fca853864cc998dc586719df14918d506e0cd938c77dea7697a9d346b87ddb78f36f53a30cf4fd65ca169193c72443bb4e9b6e5b262455f9312b60ced4450497"} build_url:https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011244 build_url: 'https://queue.taskcluster.net/v1/task/e2LuEEwMSFOKQt3xnwKvOw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/062f2f1eff5b59de208cc7dfec8e965170909d113949a03d5f767bc95def04ad41dbf489421e7f0bc5326d4911bf3e9598b70375b09c5c243f8e27a7fd9be725", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d6ca2a7950b76f506812f36207258e3a55dc136550c886d3e40a4bbcd6e2ec5b6585ea877e83ef1ce36a23fe3a646d25b634e45d32a1eb0a8b669d3f591b4270", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/daf8956ee0fc1a2e0e54b3b5a36c37a0b5837f05c1d81b3666171bdf9de2ffe6916e1a40338bdb012854ac91430b87bbad801f0175af7dc9525d955ae3b79d9e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fca853864cc998dc586719df14918d506e0cd938c77dea7697a9d346b87ddb78f36f53a30cf4fd65ca169193c72443bb4e9b6e5b262455f9312b60ced4450497"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-08 09:11:48.497387) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 09:11:48.497733) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=23a51e5d4d00092b8466c6c0000001c3-1491666657.100902-1972441026 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004518 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-08 09:11:48.582670) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-08 09:11:48.583002) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-08 09:11:48.583328) ========= ========= Total master_lag: 0.15 =========