builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-014 starttime: 1475078206.7 results: success (0) buildid: 20160928071320 builduid: 8fe010279c8a4870a65dff242cb0c359 revision: 30f0eae664b4ef1e328d6b46c658e811e9edef4e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.702890) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.703274) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.703542) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.721738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.722018) ========= 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=31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-28 08:56:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 58.7M=0s 2016-09-28 08:56:47 (58.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120433 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.856381) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.859710) ========= 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=31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017847 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.910409) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:46.913786) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 30f0eae664b4ef1e328d6b46c658e811e9edef4e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 30f0eae664b4ef1e328d6b46c658e811e9edef4e --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=31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-28 08:56:47,226 truncating revision to first 12 chars 2016-09-28 08:56:47,226 Setting DEBUG logging. 2016-09-28 08:56:47,226 attempt 1/10 2016-09-28 08:56:47,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/30f0eae664b4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 08:56:47,706 unpacking tar archive at: autoland-30f0eae664b4/testing/mozharness/ program finished with exit code 0 elapsedTime=0.812546 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:47.739847) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:47.740148) ========= script_repo_revision: 30f0eae664b4ef1e328d6b46c658e811e9edef4e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:47.740554) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:47.740830) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 08:56:47.752287) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-28 08:56:47.752608) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] 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=31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:56:48 INFO - MultiFileLogger online at 20160928 08:56:48 in /builds/slave/test 08:56:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 08:56:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:56:48 INFO - {'append_to_log': False, 08:56:48 INFO - 'base_work_dir': '/builds/slave/test', 08:56:48 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 08:56:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:56:48 INFO - 'branch': 'Autoland-Non-PGO', 08:56:48 INFO - 'buildbot_json_path': 'buildprops.json', 08:56:48 INFO - 'config_files': ('talos/linux_config.py',), 08:56:48 INFO - 'default_actions': ('clobber', 08:56:48 INFO - 'read-buildbot-config', 08:56:48 INFO - 'download-and-extract', 08:56:48 INFO - 'populate-webroot', 08:56:48 INFO - 'create-virtualenv', 08:56:48 INFO - 'install', 08:56:48 INFO - 'run-tests'), 08:56:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:56:48 INFO - 'download_minidump_stackwalk': True, 08:56:48 INFO - 'download_symbols': 'ondemand', 08:56:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:56:48 INFO - 'tooltool.py': '/tools/tooltool.py', 08:56:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:56:48 INFO - '/tools/misc-python/virtualenv.py')}, 08:56:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:56:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:56:48 INFO - 'installer_path': 'installer.exe', 08:56:48 INFO - 'log_level': 'info', 08:56:48 INFO - 'log_name': 'talos', 08:56:48 INFO - 'log_to_console': True, 08:56:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:56:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:56:48 INFO - 'opt_config_files': (), 08:56:48 INFO - 'pip_index': False, 08:56:48 INFO - 'sps_profile': False, 08:56:48 INFO - 'sps_profile_interval': 0, 08:56:48 INFO - 'suite': 'dromaeojs', 08:56:48 INFO - 'system_bits': '32', 08:56:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:56:48 INFO - 'title': 'talos-linux64-ix-014', 08:56:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:56:48 INFO - 'use_talos_json': True, 08:56:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:56:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:56:48 INFO - 'work_dir': 'build'} 08:56:48 INFO - [mozharness: 2016-09-28 15:56:48.164184Z] Running clobber step. 08:56:48 INFO - Running pre-action listener: _resource_record_pre_action 08:56:48 INFO - Running main action method: clobber 08:56:48 INFO - rmtree: /builds/slave/test/build 08:56:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:56:49 INFO - Running post-action listener: _resource_record_post_action 08:56:49 INFO - [mozharness: 2016-09-28 15:56:49.132342Z] Finished clobber step (success) 08:56:49 INFO - [mozharness: 2016-09-28 15:56:49.132441Z] Running read-buildbot-config step. 08:56:49 INFO - Running pre-action listener: _resource_record_pre_action 08:56:49 INFO - Running main action method: read_buildbot_config 08:56:49 INFO - Using buildbot properties: 08:56:49 INFO - { 08:56:49 INFO - "project": "", 08:56:49 INFO - "product": "firefox", 08:56:49 INFO - "script_repo_revision": "production", 08:56:49 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 08:56:49 INFO - "repository": "", 08:56:49 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 08:56:49 INFO - "buildid": "20160928071320", 08:56:49 INFO - "pgo_build": "False", 08:56:49 INFO - "basedir": "/builds/slave/test", 08:56:49 INFO - "buildnumber": 722, 08:56:49 INFO - "slavename": "talos-linux64-ix-014", 08:56:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:56:49 INFO - "platform": "ubuntu64_hw", 08:56:49 INFO - "scheduler": "tests-autoland-linux64-talos", 08:56:49 INFO - "branch": "autoland", 08:56:49 INFO - "repo_path": "integration/autoland", 08:56:49 INFO - "revision": "30f0eae664b4ef1e328d6b46c658e811e9edef4e", 08:56:49 INFO - "stage_platform": "linux64", 08:56:49 INFO - "builduid": "8fe010279c8a4870a65dff242cb0c359", 08:56:49 INFO - "slavebuilddir": "test" 08:56:49 INFO - } 08:56:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 08:56:49 INFO - Running post-action listener: _resource_record_post_action 08:56:49 INFO - [mozharness: 2016-09-28 15:56:49.134877Z] Finished read-buildbot-config step (success) 08:56:49 INFO - [mozharness: 2016-09-28 15:56:49.134965Z] Running download-and-extract step. 08:56:49 INFO - Running pre-action listener: _resource_record_pre_action 08:56:49 INFO - Running main action method: download_and_extract 08:56:49 INFO - mkdir: /builds/slave/test/build/tests 08:56: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:56:49 INFO - trying https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 08:56:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 08:56:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:56:49 INFO - Downloaded 1361 bytes. 08:56:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 08:56:49 INFO - Using the following test package requirements: 08:56:49 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 08:56:49 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:56:49 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:56:49 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:56:49 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:56:49 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:56:49 INFO - u'jsshell-linux-x86_64.zip'], 08:56:49 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:56:49 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:56:49 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 08:56:49 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:56:49 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:56:49 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:56:49 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:56:49 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:56:49 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:56:49 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 08:56:49 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:56:49 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:56:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 08:56:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:56:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:56:50 INFO - Expected file size: 20413451 08:56:50 INFO - Content-Encoding None 08:56:50 INFO - Content-Type application/zip 08:56:50 INFO - Http code 200 08:56:55 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:56:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 08:56:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:56:55 INFO - Fetch https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 08:56:55 INFO - Expected file size: 20413451 08:56:55 INFO - Content-Encoding None 08:56:55 INFO - Content-Type application/zip 08:56:55 INFO - Http code 200 08:57:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 08:57:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:57:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 08:57:01 INFO - Expected file size: 14414057 08:57:01 INFO - Content-Encoding None 08:57:01 INFO - Content-Type application/zip 08:57:01 INFO - Http code 200 08:57:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:02 INFO - trying https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 08:57:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 08:57:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:57:04 INFO - Downloaded 55735645 bytes. 08:57:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 08:57:04 INFO - mkdir: /builds/slave/test/properties 08:57:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:57:04 INFO - Writing to file /builds/slave/test/properties/build_url 08:57:04 INFO - Contents: 08:57:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 08:57:05 INFO - Running post-action listener: _resource_record_post_action 08:57:05 INFO - Running post-action listener: set_extra_try_arguments 08:57:05 INFO - [mozharness: 2016-09-28 15:57:05.106453Z] Finished download-and-extract step (success) 08:57:05 INFO - [mozharness: 2016-09-28 15:57:05.106696Z] Running populate-webroot step. 08:57:05 INFO - Running pre-action listener: _resource_record_pre_action 08:57:05 INFO - Running main action method: populate_webroot 08:57:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:57:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:57:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:57:05 INFO - '--tppagecycles', 08:57:05 INFO - '7'], 08:57:05 INFO - 'tests': ['tsvgm']}}, 08:57:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:57:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:57:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:57:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:57:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:05 INFO - 'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:57:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:05 INFO - 'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['damp', 'tps']}, 08:57:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:05 INFO - 'tests': ['damp', 'tps']}, 08:57:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['dromaeo_dom']}, 08:57:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:57:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['basic_compositor_video']}, 08:57:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:57:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other-e10s': {'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['a11yr', 08:57:05 INFO - 'ts_paint', 08:57:05 INFO - 'tpaint', 08:57:05 INFO - 'sessionrestore', 08:57:05 INFO - 'sessionrestore_no_auto_restore', 08:57:05 INFO - 'tabpaint']}, 08:57:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['tsvgx', 08:57:05 INFO - 'tsvgr_opacity', 08:57:05 INFO - 'tart', 08:57:05 INFO - 'tscrollx', 08:57:05 INFO - 'cart']}, 08:57:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:57:05 INFO - 'tsvgr_opacity', 08:57:05 INFO - 'tart', 08:57:05 INFO - 'tscrollx', 08:57:05 INFO - 'cart']}, 08:57:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:05 INFO - 'talos_options': ['--disable-e10s'], 08:57:05 INFO - 'tests': ['tp5o']}, 08:57:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:05 INFO - 'tests': ['tp5o']}, 08:57:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:05 INFO - 'talos_options': ['--disable-e10s', 08:57:05 INFO - '--xperf_path', 08:57:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:05 INFO - 'tests': ['tp5n']}, 08:57:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:57:05 INFO - 'talos_options': ['--xperf_path', 08:57:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:57:05 INFO - 'tests': ['tp5n']}}, 08:57:05 INFO - 'talos.zip': {'path': '', 08:57:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:57:05 INFO - Running post-action listener: _resource_record_post_action 08:57:05 INFO - [mozharness: 2016-09-28 15:57:05.129064Z] Finished populate-webroot step (success) 08:57:05 INFO - [mozharness: 2016-09-28 15:57:05.129162Z] Running create-virtualenv step. 08:57:05 INFO - Running pre-action listener: _resource_record_pre_action 08:57:05 INFO - Running main action method: create_virtualenv 08:57:05 INFO - Creating virtualenv /builds/slave/test/build/venv 08:57:05 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:57:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:57:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:57:05 INFO - Using real prefix '/usr' 08:57:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:57:05 INFO - Installing distribute.............................................................................................................................................................................................done. 08:57:07 INFO - Installing pip.................done. 08:57:07 INFO - Return code: 0 08:57:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:57:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:57:07 INFO - Reading from file tmpfile_stdout 08:57:07 INFO - Output received: 08:57:07 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:57:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:57:07 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:57:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:07 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:57:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:07 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 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:07 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:57:07 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:57:07 INFO - Using env: {'DISPLAY': ':0', 08:57:07 INFO - 'HOME': '/home/cltbld', 08:57:07 INFO - 'LANG': 'en_US.UTF-8', 08:57:07 INFO - 'LANGUAGE': 'en_US:en', 08:57:07 INFO - 'LOGNAME': 'cltbld', 08:57:07 INFO - 'MAIL': '/var/mail/cltbld', 08:57:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:07 INFO - 'MOZ_NO_REMOTE': '1', 08:57:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:07 INFO - 'NO_EM_RESTART': '1', 08:57:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:07 INFO - 'PWD': '/builds/slave/test', 08:57:07 INFO - 'SHELL': '/bin/bash', 08:57:07 INFO - 'SHLVL': '1', 08:57:07 INFO - 'TERM': 'linux', 08:57:07 INFO - 'TMOUT': '86400', 08:57:07 INFO - 'USER': 'cltbld', 08:57:07 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:07 INFO - '_': '/tools/buildbot/bin/python'} 08:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:08 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:57:08 INFO - Cleaning up... 08:57:08 INFO - Return code: 0 08:57:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:57:08 INFO - Using env: {'DISPLAY': ':0', 08:57:08 INFO - 'HOME': '/home/cltbld', 08:57:08 INFO - 'LANG': 'en_US.UTF-8', 08:57:08 INFO - 'LANGUAGE': 'en_US:en', 08:57:08 INFO - 'LOGNAME': 'cltbld', 08:57:08 INFO - 'MAIL': '/var/mail/cltbld', 08:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:08 INFO - 'MOZ_NO_REMOTE': '1', 08:57:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:08 INFO - 'NO_EM_RESTART': '1', 08:57:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:08 INFO - 'PWD': '/builds/slave/test', 08:57:08 INFO - 'SHELL': '/bin/bash', 08:57:08 INFO - 'SHLVL': '1', 08:57:08 INFO - 'TERM': 'linux', 08:57:08 INFO - 'TMOUT': '86400', 08:57:08 INFO - 'USER': 'cltbld', 08:57:08 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:08 INFO - '_': '/tools/buildbot/bin/python'} 08:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:08 INFO - Downloading/unpacking psutil>=3.1.1 08:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:57:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:10 INFO - Installing collected packages: psutil 08:57:10 INFO - Running setup.py install for psutil 08:57:10 INFO - building 'psutil._psutil_linux' extension 08:57:10 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:57:10 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:57:10 INFO - building 'psutil._psutil_posix' extension 08:57:10 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:57:10 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:57:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:57:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:57:10 INFO - Successfully installed psutil 08:57:10 INFO - Cleaning up... 08:57:10 INFO - Return code: 0 08:57:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:57:10 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:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:10 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:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:57:10 INFO - Using env: {'DISPLAY': ':0', 08:57:10 INFO - 'HOME': '/home/cltbld', 08:57:10 INFO - 'LANG': 'en_US.UTF-8', 08:57:10 INFO - 'LANGUAGE': 'en_US:en', 08:57:10 INFO - 'LOGNAME': 'cltbld', 08:57:10 INFO - 'MAIL': '/var/mail/cltbld', 08:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:10 INFO - 'MOZ_NO_REMOTE': '1', 08:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:10 INFO - 'NO_EM_RESTART': '1', 08:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:10 INFO - 'PWD': '/builds/slave/test', 08:57:10 INFO - 'SHELL': '/bin/bash', 08:57:10 INFO - 'SHLVL': '1', 08:57:10 INFO - 'TERM': 'linux', 08:57:10 INFO - 'TMOUT': '86400', 08:57:10 INFO - 'USER': 'cltbld', 08:57:10 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:10 INFO - '_': '/tools/buildbot/bin/python'} 08:57:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:57:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:57:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:57:13 INFO - Installing collected packages: mozsystemmonitor 08:57:13 INFO - Running setup.py install for mozsystemmonitor 08:57:13 INFO - Successfully installed mozsystemmonitor 08:57:13 INFO - Cleaning up... 08:57:13 INFO - Return code: 0 08:57:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:57:13 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:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:13 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:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:13 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 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:13 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:57:13 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:57:13 INFO - Using env: {'DISPLAY': ':0', 08:57:13 INFO - 'HOME': '/home/cltbld', 08:57:13 INFO - 'LANG': 'en_US.UTF-8', 08:57:13 INFO - 'LANGUAGE': 'en_US:en', 08:57:13 INFO - 'LOGNAME': 'cltbld', 08:57:13 INFO - 'MAIL': '/var/mail/cltbld', 08:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:13 INFO - 'MOZ_NO_REMOTE': '1', 08:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:13 INFO - 'NO_EM_RESTART': '1', 08:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:13 INFO - 'PWD': '/builds/slave/test', 08:57:13 INFO - 'SHELL': '/bin/bash', 08:57:13 INFO - 'SHLVL': '1', 08:57:13 INFO - 'TERM': 'linux', 08:57:13 INFO - 'TMOUT': '86400', 08:57:13 INFO - 'USER': 'cltbld', 08:57:13 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:13 INFO - '_': '/tools/buildbot/bin/python'} 08:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:13 INFO - Downloading/unpacking jsonschema==2.5.1 08:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:57:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:57:15 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:57:15 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:57:15 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:57:15 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:57:15 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:57:15 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:57:15 INFO - Downloading functools32-3.2.3-2.tar.gz 08:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:57:15 INFO - warning: no files found matching '*.txt' 08:57:15 INFO - no previously-included directories found matching 'build' 08:57:15 INFO - no previously-included directories found matching 'dist' 08:57:15 INFO - no previously-included directories found matching '.git*' 08:57:15 INFO - Installing collected packages: jsonschema, functools32 08:57:15 INFO - Running setup.py install for functools32 08:57:15 INFO - warning: no files found matching '*.txt' 08:57:15 INFO - no previously-included directories found matching 'build' 08:57:15 INFO - no previously-included directories found matching 'dist' 08:57:15 INFO - no previously-included directories found matching '.git*' 08:57:15 INFO - Successfully installed jsonschema functools32 08:57:15 INFO - Cleaning up... 08:57:15 INFO - Return code: 0 08:57:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:57:15 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:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:15 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:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:15 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 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:15 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:57:15 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:57:15 INFO - Using env: {'DISPLAY': ':0', 08:57:15 INFO - 'HOME': '/home/cltbld', 08:57:15 INFO - 'LANG': 'en_US.UTF-8', 08:57:15 INFO - 'LANGUAGE': 'en_US:en', 08:57:15 INFO - 'LOGNAME': 'cltbld', 08:57:15 INFO - 'MAIL': '/var/mail/cltbld', 08:57:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:15 INFO - 'MOZ_NO_REMOTE': '1', 08:57:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:15 INFO - 'NO_EM_RESTART': '1', 08:57:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:15 INFO - 'PWD': '/builds/slave/test', 08:57:15 INFO - 'SHELL': '/bin/bash', 08:57:15 INFO - 'SHLVL': '1', 08:57:15 INFO - 'TERM': 'linux', 08:57:15 INFO - 'TMOUT': '86400', 08:57:15 INFO - 'USER': 'cltbld', 08:57:15 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:15 INFO - '_': '/tools/buildbot/bin/python'} 08:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:57:16 INFO - Cleaning up... 08:57:16 INFO - Return code: 0 08:57:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:16 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 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:16 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:57:16 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:57:16 INFO - Using env: {'DISPLAY': ':0', 08:57:16 INFO - 'HOME': '/home/cltbld', 08:57:16 INFO - 'LANG': 'en_US.UTF-8', 08:57:16 INFO - 'LANGUAGE': 'en_US:en', 08:57:16 INFO - 'LOGNAME': 'cltbld', 08:57:16 INFO - 'MAIL': '/var/mail/cltbld', 08:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:16 INFO - 'MOZ_NO_REMOTE': '1', 08:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:16 INFO - 'NO_EM_RESTART': '1', 08:57:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:16 INFO - 'PWD': '/builds/slave/test', 08:57:16 INFO - 'SHELL': '/bin/bash', 08:57:16 INFO - 'SHLVL': '1', 08:57:16 INFO - 'TERM': 'linux', 08:57:16 INFO - 'TMOUT': '86400', 08:57:16 INFO - 'USER': 'cltbld', 08:57:16 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:16 INFO - '_': '/tools/buildbot/bin/python'} 08:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:16 INFO - Downloading/unpacking blobuploader==1.2.4 08:57:16 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:57:16 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:57:16 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:57:16 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:57:16 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:57:16 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:57:18 INFO - Downloading blobuploader-1.2.4.tar.gz 08:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:57:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:57:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:57:18 INFO - Downloading docopt-0.6.1.tar.gz 08:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:57:18 INFO - Installing collected packages: blobuploader, requests, docopt 08:57:18 INFO - Running setup.py install for blobuploader 08:57:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:57:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:57:18 INFO - Running setup.py install for requests 08:57:19 INFO - Running setup.py install for docopt 08:57:19 INFO - Successfully installed blobuploader requests docopt 08:57:19 INFO - Cleaning up... 08:57:19 INFO - Return code: 0 08:57:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:57:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:57:19 INFO - Using env: {'DISPLAY': ':0', 08:57:19 INFO - 'HOME': '/home/cltbld', 08:57:19 INFO - 'LANG': 'en_US.UTF-8', 08:57:19 INFO - 'LANGUAGE': 'en_US:en', 08:57:19 INFO - 'LOGNAME': 'cltbld', 08:57:19 INFO - 'MAIL': '/var/mail/cltbld', 08:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:19 INFO - 'MOZ_NO_REMOTE': '1', 08:57:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:19 INFO - 'NO_EM_RESTART': '1', 08:57:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:19 INFO - 'PWD': '/builds/slave/test', 08:57:19 INFO - 'SHELL': '/bin/bash', 08:57:19 INFO - 'SHLVL': '1', 08:57:19 INFO - 'TERM': 'linux', 08:57:19 INFO - 'TMOUT': '86400', 08:57:19 INFO - 'USER': 'cltbld', 08:57:19 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:19 INFO - '_': '/tools/buildbot/bin/python'} 08:57:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:19 INFO - Running setup.py (path:/tmp/pip-rCxMrq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:19 INFO - Running setup.py (path:/tmp/pip-7sZ4ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:19 INFO - Running setup.py (path:/tmp/pip-w58_T9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:19 INFO - Running setup.py (path:/tmp/pip-HFrbyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:19 INFO - Running setup.py (path:/tmp/pip-rZDqs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:20 INFO - Running setup.py (path:/tmp/pip-dO_fSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:20 INFO - Running setup.py (path:/tmp/pip-_xRRny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:20 INFO - Running setup.py (path:/tmp/pip-qy9opn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:20 INFO - Running setup.py (path:/tmp/pip-TS0SZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:20 INFO - Running setup.py (path:/tmp/pip-SUaOgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:20 INFO - Running setup.py (path:/tmp/pip-RJ59AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:20 INFO - Running setup.py (path:/tmp/pip-tcSxMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:20 INFO - Running setup.py (path:/tmp/pip-AEWHDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:20 INFO - Running setup.py (path:/tmp/pip-D7Ol4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:20 INFO - Running setup.py (path:/tmp/pip-xAygUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:20 INFO - Running setup.py (path:/tmp/pip-svHj1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:20 INFO - Running setup.py (path:/tmp/pip-fPJdKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:57:20 INFO - Running setup.py install for manifestparser 08:57:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Running setup.py install for mozcrash 08:57:21 INFO - Running setup.py install for mozdebug 08:57:21 INFO - Running setup.py install for mozdevice 08:57:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Running setup.py install for mozfile 08:57:21 INFO - Running setup.py install for mozhttpd 08:57:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Running setup.py install for mozinfo 08:57:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Running setup.py install for mozInstall 08:57:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Running setup.py install for mozleak 08:57:21 INFO - Running setup.py install for mozlog 08:57:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:57:21 INFO - Running setup.py install for moznetwork 08:57:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:57:22 INFO - Running setup.py install for mozprocess 08:57:22 INFO - Running setup.py install for mozprofile 08:57:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:57:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:57:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:57:22 INFO - Running setup.py install for mozrunner 08:57:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:57:22 INFO - Running setup.py install for mozscreenshot 08:57:22 INFO - Running setup.py install for moztest 08:57:22 INFO - Running setup.py install for mozversion 08:57:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:57:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:57:22 INFO - Cleaning up... 08:57:22 INFO - Return code: 0 08:57:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:22 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 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:22 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:57:22 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:57:22 INFO - Using env: {'DISPLAY': ':0', 08:57:22 INFO - 'HOME': '/home/cltbld', 08:57:22 INFO - 'LANG': 'en_US.UTF-8', 08:57:22 INFO - 'LANGUAGE': 'en_US:en', 08:57:22 INFO - 'LOGNAME': 'cltbld', 08:57:22 INFO - 'MAIL': '/var/mail/cltbld', 08:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:22 INFO - 'MOZ_NO_REMOTE': '1', 08:57:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:22 INFO - 'NO_EM_RESTART': '1', 08:57:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:22 INFO - 'PWD': '/builds/slave/test', 08:57:22 INFO - 'SHELL': '/bin/bash', 08:57:22 INFO - 'SHLVL': '1', 08:57:22 INFO - 'TERM': 'linux', 08:57:22 INFO - 'TMOUT': '86400', 08:57:22 INFO - 'USER': 'cltbld', 08:57:22 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:22 INFO - '_': '/tools/buildbot/bin/python'} 08:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:57:22 INFO - Running setup.py (path:/tmp/pip-ga3P_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:57:22 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:57:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:57:22 INFO - Running setup.py (path:/tmp/pip-uubp93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:57:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:57:23 INFO - Running setup.py (path:/tmp/pip-7LwyFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:57:23 INFO - Running setup.py (path:/tmp/pip-msb2Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:57:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:57:23 INFO - Running setup.py (path:/tmp/pip-8qJ0wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:57:23 INFO - Running setup.py (path:/tmp/pip-yaqrdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:57:23 INFO - Running setup.py (path:/tmp/pip-yyszTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:57:23 INFO - Running setup.py (path:/tmp/pip-12fCcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:57:23 INFO - Running setup.py (path:/tmp/pip-7Ib3Uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:57:23 INFO - Running setup.py (path:/tmp/pip-Lg9BXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:57:23 INFO - Running setup.py (path:/tmp/pip-mJCnz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:57:23 INFO - Running setup.py (path:/tmp/pip-IhQlGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:57:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:57:23 INFO - Running setup.py (path:/tmp/pip-OYvSe8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:57:23 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:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:57:23 INFO - Running setup.py (path:/tmp/pip-ug3_Ld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:57:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:57:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:23 INFO - Running setup.py (path:/tmp/pip-apeHAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:57:24 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:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:57:24 INFO - Running setup.py (path:/tmp/pip-dJPUN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:57:24 INFO - Running setup.py (path:/tmp/pip-T4Lmm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:57:24 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:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:57:24 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:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:57:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:57:24 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:57:24 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:57:24 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:57:24 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:57:24 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:57:24 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:57:26 INFO - Downloading blessings-1.6.tar.gz 08:57:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:57:26 INFO - Installing collected packages: blessings 08:57:26 INFO - Running setup.py install for blessings 08:57:26 INFO - Successfully installed blessings 08:57:26 INFO - Cleaning up... 08:57:26 INFO - Return code: 0 08:57:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:57:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:26 INFO - Reading from file tmpfile_stdout 08:57:26 INFO - Current package versions: 08:57:26 INFO - argparse == 1.2.1 08:57:26 INFO - blessings == 1.6 08:57:26 INFO - blobuploader == 1.2.4 08:57:26 INFO - docopt == 0.6.1 08:57:26 INFO - functools32 == 3.2.3-2 08:57:26 INFO - jsonschema == 2.5.1 08:57:26 INFO - manifestparser == 1.1 08:57:26 INFO - mozInstall == 1.12 08:57:26 INFO - mozcrash == 0.17 08:57:26 INFO - mozdebug == 0.1 08:57:26 INFO - mozdevice == 0.48 08:57:26 INFO - mozfile == 1.2 08:57:26 INFO - mozhttpd == 0.7 08:57:26 INFO - mozinfo == 0.9 08:57:26 INFO - mozleak == 0.1 08:57:26 INFO - mozlog == 3.4 08:57:26 INFO - moznetwork == 0.27 08:57:26 INFO - mozprocess == 0.23 08:57:26 INFO - mozprofile == 0.28 08:57:26 INFO - mozrunner == 6.12 08:57:26 INFO - mozscreenshot == 0.1 08:57:26 INFO - mozsystemmonitor == 0.3 08:57:26 INFO - moztest == 0.7 08:57:26 INFO - mozversion == 1.4 08:57:26 INFO - psutil == 3.1.1 08:57:26 INFO - requests == 1.2.3 08:57:26 INFO - wsgiref == 0.1.2 08:57:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:57:26 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:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:26 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:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:26 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 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:26 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:57:26 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:57:26 INFO - Using env: {'DISPLAY': ':0', 08:57:26 INFO - 'HOME': '/home/cltbld', 08:57:26 INFO - 'LANG': 'en_US.UTF-8', 08:57:26 INFO - 'LANGUAGE': 'en_US:en', 08:57:26 INFO - 'LOGNAME': 'cltbld', 08:57:26 INFO - 'MAIL': '/var/mail/cltbld', 08:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:26 INFO - 'MOZ_NO_REMOTE': '1', 08:57:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:26 INFO - 'NO_EM_RESTART': '1', 08:57:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:26 INFO - 'PWD': '/builds/slave/test', 08:57:26 INFO - 'SHELL': '/bin/bash', 08:57:26 INFO - 'SHLVL': '1', 08:57:26 INFO - 'TERM': 'linux', 08:57:26 INFO - 'TMOUT': '86400', 08:57:26 INFO - 'USER': 'cltbld', 08:57:26 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:26 INFO - '_': '/tools/buildbot/bin/python'} 08:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 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:57:26 INFO - Cleaning up... 08:57:26 INFO - Return code: 0 08:57:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:57:26 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:57:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:57:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:26 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:57:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:57:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:57:26 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 0x7f22581dff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26fc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27c9ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, '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': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', '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:57:26 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:57:26 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:57:26 INFO - Using env: {'DISPLAY': ':0', 08:57:26 INFO - 'HOME': '/home/cltbld', 08:57:26 INFO - 'LANG': 'en_US.UTF-8', 08:57:26 INFO - 'LANGUAGE': 'en_US:en', 08:57:26 INFO - 'LOGNAME': 'cltbld', 08:57:26 INFO - 'MAIL': '/var/mail/cltbld', 08:57:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:26 INFO - 'MOZ_NO_REMOTE': '1', 08:57:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:26 INFO - 'NO_EM_RESTART': '1', 08:57:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:26 INFO - 'PWD': '/builds/slave/test', 08:57:26 INFO - 'SHELL': '/bin/bash', 08:57:26 INFO - 'SHLVL': '1', 08:57:26 INFO - 'TERM': 'linux', 08:57:26 INFO - 'TMOUT': '86400', 08:57:26 INFO - 'USER': 'cltbld', 08:57:26 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:26 INFO - '_': '/tools/buildbot/bin/python'} 08:57:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:57:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:57:26 INFO - Cleaning up... 08:57:26 INFO - Return code: 0 08:57:26 INFO - Running post-action listener: _resource_record_post_action 08:57:26 INFO - Running post-action listener: _start_resource_monitoring 08:57:27 INFO - Starting resource monitoring. 08:57:27 INFO - [mozharness: 2016-09-28 15:57:27.022363Z] Finished create-virtualenv step (success) 08:57:27 INFO - [mozharness: 2016-09-28 15:57:27.023332Z] Running install step. 08:57:27 INFO - Running pre-action listener: _resource_record_pre_action 08:57:27 INFO - Running main action method: install 08:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:57:27 INFO - Reading from file tmpfile_stdout 08:57:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:57:27 INFO - Reading from file tmpfile_stdout 08:57:27 INFO - Output received: 08:57:27 INFO - Usage: mozinstall [options] installer 08:57:27 INFO - Options: 08:57:27 INFO - -h, --help show this help message and exit 08:57:27 INFO - -d DEST, --destination=DEST 08:57:27 INFO - Directory to install application into. [default: 08:57:27 INFO - "/builds/slave/test"] 08:57:27 INFO - --app=APP Application being installed. [default: firefox] 08:57:27 INFO - mkdir: /builds/slave/test/build/application 08:57:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:57:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:57:43 INFO - Reading from file tmpfile_stdout 08:57:43 INFO - Output received: 08:57:43 INFO - /builds/slave/test/build/application/firefox/firefox 08:57:43 INFO - Running post-action listener: _resource_record_post_action 08:57:43 INFO - [mozharness: 2016-09-28 15:57:43.426483Z] Finished install step (success) 08:57:43 INFO - [mozharness: 2016-09-28 15:57:43.426755Z] Running run-tests step. 08:57:43 INFO - Running pre-action listener: _resource_record_pre_action 08:57:43 INFO - Running main action method: run_tests 08:57:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:57:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:57:43 INFO - Python 2.7.3 08:57:43 INFO - Return code: 0 08:57:43 INFO - grabbing minidump binary from tooltool 08:57:43 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:57:43 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 0x27c6f60>, '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 0x27c7d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27c81d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:57:43 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:57:43 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:57:43 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:57:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:57:43 INFO - Return code: 0 08:57:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:57:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:57:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:57:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:57:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:57:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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:57:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-014 --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:57:43 INFO - Using env: {'DISPLAY': ':0', 08:57:43 INFO - 'HOME': '/home/cltbld', 08:57:43 INFO - 'LANG': 'en_US.UTF-8', 08:57:43 INFO - 'LANGUAGE': 'en_US:en', 08:57:43 INFO - 'LOGNAME': 'cltbld', 08:57:43 INFO - 'MAIL': '/var/mail/cltbld', 08:57:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:57:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:57:43 INFO - 'MOZ_NO_REMOTE': '1', 08:57:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:57:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:57:43 INFO - 'NO_EM_RESTART': '1', 08:57:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:57:43 INFO - 'PWD': '/builds/slave/test', 08:57:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:57:43 INFO - 'SHELL': '/bin/bash', 08:57:43 INFO - 'SHLVL': '1', 08:57:43 INFO - 'TERM': 'linux', 08:57:43 INFO - 'TMOUT': '86400', 08:57:43 INFO - 'USER': 'cltbld', 08:57:43 INFO - 'XDG_SESSION_COOKIE': '31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828', 08:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:57:43 INFO - '_': '/tools/buildbot/bin/python'} 08:57:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-014', '--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:57:43 INFO - mozversion application_buildid: 20160928071320 08:57:43 INFO - mozversion application_changeset: 30f0eae664b4ef1e328d6b46c658e811e9edef4e 08:57:43 INFO - mozversion application_display_name: Nightly 08:57:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:57:43 INFO - mozversion application_name: Firefox 08:57:43 INFO - mozversion application_remotingname: firefox 08:57:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 08:57:43 INFO - mozversion application_vendor: Mozilla 08:57:43 INFO - mozversion application_version: 52.0a1 08:57:43 INFO - mozversion platform_buildid: 20160928071320 08:57:43 INFO - mozversion platform_changeset: 30f0eae664b4ef1e328d6b46c658e811e9edef4e 08:57:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 08:57:43 INFO - mozversion platform_version: 52.0a1 08:57:43 INFO - using testdate: 1475078263 08:57:43 INFO - actual date: 1475078263 08:57:43 INFO - starting webserver on 'localhost:37823' 08:57:43 INFO - SUITE-START | Running 2 tests 08:57:43 INFO - TEST-START | dromaeo_css 08:57:43 INFO - operating with platform_type : linux_ 08:57:43 INFO - Initialising browser for dromaeo_css test... 08:57:43 INFO - TEST-INFO | started process 28630 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphoXPc_/profile http://localhost:37823/getInfo.html) 08:57:48 INFO - PROCESS | 28630 | __metrics Screen width/height:1600/1200 08:57:48 INFO - PROCESS | 28630 | colorDepth:24 08:57:48 INFO - PROCESS | 28630 | Browser inner width/height: 1024/697 08:57:48 INFO - PROCESS | 28630 | __metrics 08:57:48 INFO - PROCESS | 28630 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:57:48 INFO - PROCESS | 28630 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:57:49 INFO - PROCESS | 28630 | console.error: PushService: 08:57:49 INFO - PROCESS | 28630 | stateChangeProcessEnqueue: Error transitioning state 08:57:49 INFO - PROCESS | 28630 | UnknownError 08:57:49 INFO - TEST-INFO | 28630: exit 0 08:57:49 INFO - Browser initialized. 08:57:49 INFO - Running cycle 1/1 for dromaeo_css test... 08:57:49 INFO - TEST-INFO | started process 28711 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphoXPc_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:58:02 INFO - PROCESS | 28711 | RSS: Main: 174825472 08:58:02 INFO - PROCESS | 28711 | 08:58:52 INFO - PROCESS | 28711 | 1475078332981 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:01:06 INFO - PROCESS | 28711 | Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37823/tests/dromaeo/cssquery-ext.html) 09:01:07 INFO - PROCESS | 28711 | RSS: Main: 322224128 09:01:07 INFO - PROCESS | 28711 | 09:04:10 INFO - PROCESS | 28711 | Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-ext.html (next: http://localhost:37823/tests/dromaeo/cssquery-jquery.html) 09:04:11 INFO - PROCESS | 28711 | RSS: Main: 290017280 09:04:11 INFO - PROCESS | 28711 | 09:07:13 INFO - PROCESS | 28711 | Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37823/tests/dromaeo/cssquery-mootools.html) 09:07:14 INFO - PROCESS | 28711 | RSS: Main: 220880896 09:07:14 INFO - PROCESS | 28711 | 09:10:16 INFO - PROCESS | 28711 | Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37823/tests/dromaeo/cssquery-prototype.html) 09:10:16 INFO - PROCESS | 28711 | RSS: Main: 220614656 09:10:16 INFO - PROCESS | 28711 | 09:13:20 INFO - PROCESS | 28711 | Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37823/tests/dromaeo/cssquery-yui.html) 09:13:20 INFO - PROCESS | 28711 | RSS: Main: 220659712 09:13:20 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | Cycle 1(1): loaded http://localhost:37823/tests/dromaeo/cssquery-yui.html (next: http://localhost:37823/tests/dromaeo/cssquery-dojo.html) 09:16:23 INFO - PROCESS | 28711 | RSS: Main: 312193024 09:16:23 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | __start_tp_report 09:16:23 INFO - PROCESS | 28711 | _x_x_mozilla_page_load 09:16:23 INFO - PROCESS | 28711 | _x_x_mozilla_page_load_details 09:16:23 INFO - PROCESS | 28711 | |i|pagename|runs| 09:16:23 INFO - PROCESS | 28711 | |0;dojo.html;2598.6491823603815;2623.6326914232022;2660.175345642851;2667.8265912715674;2680.155750938453;4698.671093023489;4781.8804529886775;4790.257510085934;4861.173600487917;4920;4728.905421891569;4791.401074865642;4811.831585894494;4815.807367705288;4880.78036488358;4336.761478118702;4346.587074227959;4377.912441751163;4503.662225333105;4543.477500087499;4399.494058183318;4480.372747815292;4487.775611219426;4531.071130418273;4549.340345649879;4524.411826462539;4537.478190008158;4542.250528662767;4552.749598772066;4609.838655647053;4316.5467625899455;4355.5644435556505;4371.69398142127;4379.277419225824;4476.485204201526;4384.057427653038;4505.031418245093;4506.526814684476;4508.413906192207;4515;4803.231482962744;4842.854714358539;4846.927296090562;4866.635002374836;4910.091633047898;5212.713300768456;5220.765065572058;5223.817166399196;5224.555912747421;5281.419043905167;5012.949870501285;5074.873128171788;5098.209777484459;5150.845474635767;5153.665011774223;4779.593734532569;4812.590929770939;4827.782749776268;4886.389201349832;4889.071076495455;5254.579633629301;5260.053190425683;5263.184206448007;5267.446918073624;5309.707966061904;5013.799448022039;5049.924251136236;5059.873503162376;5059.8988020239385;5118.897622047538;5033.6224783141415;5082.847514574569;5136.049830781242;5163.974180129076;5184.403793563789;5174.870628234325;5175.844724658266;5216.921746173811;5242.213667949762;5247.4490178530705;5115.335006449212;5127.077126117335;5133.486651334837;5134.255532947776;5182.222666600041;4258.488981322199;4260.744355338689;4311.655067594585;4380.78096095194;4387.6270517006415;3749.643783840531;3791.374423220193;3801.030737162061;3810.6189381062227;3848.8460461581785;4403.757793321398;4405.559444055633;4418.116376724668;4467.508574056851;4469.240229160985;5221.503957124067;5229.346331708536;5229.50319719626;5229.712365819841;5230.97384513075;4247;4273.508546517128;4383.978080109579;4443.755593442392;4445.799939002753;4692.882677933079;4701.2242979908615;4708.599769019672;4710.105080034782;4764.714117152982;4882.316475693406;4899.706017638953;4908.214685650279;4908.754562271801;4959.082569724613;4922.606191504599;5021.698698078127;5105.463926287686;5170;5197.870053248548;4822.590079843112;4836.95163048365;4842.394700662418;4852.975735121302;4897.755112244445;5049.166887463527;5064.5441910228255;5068.113080211022;5072.365954255718;5121.769520371667;5030.572401345927;5158.252053452154;5159.329287192791;5172.63791534604;5236.266922630911;4948.554630083309;4953.108440480748;4967.279744436964;4978.4523702393435;5012.423571289349;5044.974775126248;5053.393592768891;5054.4692807254705;5092.949070509248;5105.106606343949;4940;4976.900461990812;4982.053409852117;4985.077760614298;5052.823151189691;4896.92654610174;4950.801967921243;4961.776720047678;5074.517920797518;5081.644284900021;4864.124457597666;4900.754962251802;4917.262410638433;4958.677685950402;4972.254161875748 09:16:23 INFO - PROCESS | 28711 | |1;ext.html;566.2921348314608;567.0813282482378;572.4676051272318;579.3945327133146;588.3586890289583;27285.317867053334;36883.631163688355;38039.23921521568;38151.23697526048;42226;39071.60928390715;39525.40711889317;39537.40693889587;39553.40669889954;39583.60416395835;7668.539887606761;7830.412719046066;7830.608469576527;7832.686692532292;7838.568878711697;7411.110666720001;7567.508111972732;9783.46190959497;10038.196944244442;10041.899581004205;10083.294169408144;10164.491775411238;10176.592936282539;10190.286679932407;10192;9927.702168934944;10005.549750261183;10013.649522266758;10016.849747253797;10026.248031397601;9797.167240784542;9875.407475551418;9877.456739879304;9878.160356369717;9882.061204185591;37887.431688524695;38234.426483602765;38302.23395532102;38303.42544861829;38374.61625383738;2810.7189281071933;2865.498537755884;3138.59198304219;3583.9104022399647;3637.1452708613456;27786.16641500758;28321.291967700767;28332.29169270764;28401.431971360664;28614;3454.4300190468543;3515.6484351564895;3554.253606742587;3603.6396360364015;3617.981910090433;251891;252003;252529;252775.73612132005;252871;50114.24828627573;50115.24827127596;50291.74854125706;50532.49467505352;50563.241551376756;53248.46751532512;53279;53331.46668533265;53366;53385.7330713344;12293.446794894251;12554.121211515107;12590;12592;12602.306873122066;8504.574771261536;8681.435706679045;9610.471424071744;9689.51552422376;9691.70924872255;13345;13470.52853150135;13528.59414217575;14360.712785744226;14872.925635371754;5089.262057001714;5619.494245517842;5621.7189140542805;5623.831285061455;5637.774489020475;760.7337431898791;834.6577903059716;901.6348378906554;908.7001289574424;919.2002957427081;26202.86898565495;27026.72973270242;27229.45541089167;27332.59001114985;27574.310642233704;900.7703035725979;901.6844104563481;918.5682729117304;933.141509810962;952.066974365126;14244.216568088854;14406.207658578669;14491.85508144905;14659.204936324119;14750.262486875614;354540;355362;356746;356903;371444;39152.60847391547;46255.5374446258;47554.52445475501;47581.52418475771;52350.21474677883;1084.810158222292;1120.7814476177068;1139.4758411130806;1182.0366401382846;1182.9940850295866;27186.32034199161;28382.43235135327;28390.85804570964;29071.854640726662;29572.112836614935;41441.37837932494;41796.58203417927;41829.58170418379;41831.37252941148;41848;315917;317222;320793;321955;322190;35241;35443.468347975315;35490.112747180494;38702.61297387103;39954.60045399625;39761.60238397579;42925;43222.567774321855;44185.33721994108;44209.33685994648;36125.27749444944;36777.26445471022;36949.81525092357;37378.6262137386;37620.435693465166;36134.09664758402;36432.27135457223;36455.27089458249;37308;37835.62164378431;35670.108247294025;36065.459018115274;36098;36310.8184459076;36618.63381366152;32319.353612927138;33142.834285828416;33439.83280083584;33885.66114338825;34478.31043379169;25109.497810043333;25552.479285150515;25754.484910301315;26175.214743557724;26194.607080894184 09:16:23 INFO - PROCESS | 28711 | |2;jquery.html;343.2705500810777;357.84433771309466;366.86792754608337;369.7374863846668;379.27558363525674;12109.939450302747;13179.472821087156;14580.781288280685;16297.348106075773;16952.152392380394;15141.167235802026;15303.464378746747;15409.07545547268;15431.537053888402;15940.52178434646;4865.5134486551415;4926.802927882881;5066.316047333618;5075.847724568269;5098.209777484496;3700.666939975402;3881.8253178606888;4996.925046124311;5004.199328107504;5044.243363495487;3848.9615103849023;3904.2386734586767;3911.198204368112;3924.2936271471126;4053.655439287649;5941.910871336933;6017.187679663233;6037.245344331958;6038.275406951194;6042.728077236491;5079.923801142986;5239.292695486099;5241.86895327616;5242.580593552507;5294.814681486129;19926.70109948352;20160.596788064304;20397.592048159102;20561.69157462639;20716.58566828655;1901.2109974360624;2066.7209926659857;2189.0696454007143;2266.841321107523;2350.847194932324;12640.360274570456;12758.23450592958;12953.352332383438;13195.60413187606;13404.195748255135;1685.4016824027417;1714.211462727147;1721.5265801904452;1727.041491971955;1743.9912800436043;44917.1987230649;65049.34950650528;65193.67403163001;67143.32856671419;68766.65616671885;9107.635694572275;9360.344775865624;9418;9567.665131720496;9809.460479673544;13109.934450327688;13121;13128.409221585052;13133.474661013452;13143.60569182926;6539.673016349258;6567.376099270562;6766.391024807693;6813.080234168423;6861.828454288683;6254.6247225166635;6301.558890877686;6344.809655710336;6425.470043619237;6464.935350646528;5827.388124246978;5860.619059761102;5906.438888305604;5953.910691339634;6016.338202797689;1679.5968967447725;1703.1058694185651;1703.761473393726;1717.2787429279483;1767.1605987156;814.621201141472;858.013284722574;862.4351050062219;879.6965047058754;894.3113802372137;16496.83503164977;16756.989161300477;17103.657926841643;17156.968112493552;17804.91097544504;842.3261390887266;865.4893612768496;906.5875026862761;916.2898753466117;937.8686983822271;8778.60496277669;9474.763130921781;9998.350107243006;10103.898961010444;10222.386656800616;369835;385735;390434;390758;390901;65212.67393663001;65598.67200663967;66572.66713666498;67187.66406168036;69287;882.876397304365;883.5405589093764;908.0783005249676;908.6910450446879;909.263496567769;14951.476698315942;15033.451397087656;15205.847941520444;15350.232488375314;15717.607059823138;24059.518809623358;35331.29337413289;35383.761568344984;35701.28597428089;35772.28455430929;43479.347809782244;44783.328250075625;44812.32781508215;45766.313505298116;47735.761321193175;7223.313785190398;7296.7081316746735;7375.594342311009;7466.925330746623;7500.47477685487;11012.119030477703;11352.375619341015;11573.305601663928;11639.127065469955;11684.88315116872;10333.844992325272;10351.223658225814;10419.24288962454;10622.946885265525;10865.565377384815;10050.949745251228;10295.330803497749;10418.73953151147;10575.101116405192;10589;10184;10265.725658358171;10416.739581510523;10600.310979786289;10807.513661885392;6443.96778016107;6844.486663500158;6917.481188910952;6971.930280697331;7194.136703595602;8259.710910118118;9634.181094606756;9725.319227653996;9822.459764712723;10056.597736090474 09:16:23 INFO - PROCESS | 28711 | |3;mootools.html;1264.721761212533;1390.645385426716;1405.3394904394934;1489.2628149066213;1704.7187214109672;6852.862942741142;7453.366463850566;7468.253174682529;7474.701011959529;7782.805429864256;7821.927189294827;7896.684132634688;7901.446898717092;7903.3677305815545;7908.418316336742;2112.9049192786283;2143.228437762412;2143.346279384787;2144.2709478777215;2146.1737231166035;2122.7983341582603;2138.1554286057;2151.8278537716947;2151.924682636108;2164.945876353088;2018.2734215682342;2033.1359172351765;2039.857209995301;2043.9386818395399;2141.6787481877764;2095.0886364431444;2110.05047728522;2110.356341315898;2113.4399384163207;2115.4182599785026;2055.825254853339;2103.894805259746;2106.9157233710635;2107.2203284784728;2107.7681455039933;7851.842963140705;8016.238457346542;8053.516788992679;8061.153578874192;8076.111627721005;4344.283193273106;4383.211022016036;4503.842365517191;4510.842120525798;4514.322851572258;2794.3852352482622;2874.6837847836723;2878.294817769639;2881.9135425937257;2894.1751600793673;950.1306304731231;951.7477868364889;953.50894289441;985.0494273026563;986.7187851462371;10333.689989300334;10666;10683.626073087482;10686.145108391387;10690.572377104907;3694.3719567673024;3808.352580061396;3910.335243008695;3987;3994.8801535953967;8362.874556881652;8390.32877369809;8431.19903609156;8433.072362040171;8453.196946290092;4313.719608225455;4313.827446902089;4320.395144679747;4320.481542214891;4321.956780432155;3578.499010138583;3693.8337774800198;4001.1797581496307;4003.3586229645707;4019.31671615826;3297.1921879139763;3305.2067503798917;3425.1265927188406;3446.0867870014467;3472.965270347315;994.4431118573622;1004.1063453526367;1005.4821766790109;1005.7485628592851;1030.340582027503;678.864227154571;828.5691440450931;857.2541888556871;882.2192359761528;892.9553522323857;5204.635675502678;5233.633645644768;5326.280952071421;5333.2266821310695;5619.8595035124445;394.6842525979263;397.7076848516338;401.0535137076497;401.5783427401217;403.8990252436891;3688.8155592220282;3801;3853.6917046636204;3862.4978752761963;3986.063275130342;9478;9896.356736812084;9977.151942084858;9985.550650220757;10000.849987250198;9538.093881081286;9723.951380243054;9868.753281168028;9905.75235619101;9912.612234287206;7348.926510734931;7371.6682749276415;7417.888731669029;7422.406207503279;7426.071741032371;5223.764930578057;5351.839444816662;5355;5417.972910135424;5503.330884520924;8818.779530511789;8832.823343533222;8906.554672266231;8936.50849203274;8991.91008089937;8589.957050214709;9120.179183873483;9131.589078491352;9139.405938613969;9165.954170229374;6044.546659000681;6210.254769427517;6229.4393504584805;6548.770793022221;6557.442617377576;7236.819079523054;7443.739469118555;7497.475176737576;7706.807329816799;7719.691212351668;6750.594964302158;6750.696218670269;6814.488913331421;6943.201531823905;7016.403605693573;6752.628605426748;6875.381215690411;6913.481488888253;7042.964785176041;7055.400290975324;6337.2395312562785;6445.097686323825;6450;6455.7740479083;6459.870802584016;4410.647148228199;4422.159789639958;4501.504834468271;4544.6818722689095;4581.862544123682;4521.886952826206;4562.2016147173445;4652.744099074448;4692.202325604587;4751.9762401189155 09:16:23 INFO - PROCESS | 28711 | |4;prototype.html;284.4356041770262;286.40713722594234;295.2044240771122;296.6484715612;300.33026351236725;4593.496544383311;4752.239641657336;4809.302651115586;4836.685615434994;4849.393825771778;4601.918549140958;4734.218853889104;4782.665213435061;4790.640701947359;4791.47293797682;3762.2663580601793;3778.8110594470304;3812.142267989694;3814.7138964577766;3819.618038196186;3496.4580490024086;3597.244578638489;3763.6424539668824;3778.8110594470304;3785.734998550103;3692.778433294011;3702.907427314325;3731.421629647409;3745.9625403745886;3747.344214762419;3732.2348918471785;3736.2527494501073;3738.345789486829;3755.0800053986677;3774.622537746231;3344.147242453183;3350.430426827445;3351.1287065362935;3351.581052368454;3357.3285342931513;4479.3281007848755;4641.396618439581;4661.277501987197;4667.813287468497;4715.882102947419;4299.72051816634;4302.935455968163;4307.224699554079;4309.35359696045;4313.633341166004;5003.474635163292;5018.5232402921665;5020.673656212334;5027.195648696191;5035.974820125876;3531.6644918732677;3594.7663401878795;3630.7276954228537;3652.214773823614;3653.5433070866143;5216.530512253916;5217.5565076968505;5228.640553456129;5234.476552344773;5245.606579506552;3725.515682961198;3727.3290807654885;3736.2153947671295;3736.7944763038295;3739.0652336915773;3774.471573979645;3785.734998550075;3786.8295926683363;3786.8863934082024;3791.109089363997;4127;4172.457580514574;4184.476940382452;4185.162967406531;4192.413062171298;4953.50464953509;4961.975190124026;4962.404511458648;5016.473270306638;5022.899542009212;1199.111399545719;1226.0934247245618;1260.577706468325;1270.1109223543372;1286.899700755859;876.8114855306139;893.8882639670041;896.1799953043023;898.9280857531381;901.9864702029474;4338.587834155814;4343.218220720238;4349.26062569364;4351.586599273064;4357.782110894443;5062.7215503146945;5067.961067981055;5078.644494885397;5084.33903592538;5089.262057001714;4180.017695841475;4423.646108311327;4426.1369033038245;4467.24056910326;4473.7986790594505;2719.9184024479296;2731.426400455887;2761.8066735328734;2761.8757155928033;2762.903298384547;4753.619710423158;4874.8781280467565;4875.951670390883;4882.780274887638;4904.2643603459055;5257.211418287211;5270.152788464442;5300.443453437333;5301.708406037705;5303.15149576066;4807.230843065093;4838.298446725134;4838.806447742051;4839.225723884161;4900.72581128909;4887;5129.615278854027;5166.689998600096;5167.896642067213;5193.169092945111;5086.567641750493;5095.464976177448;5101.51535604117;5110.642255042111;5114.79540818378;4891.73095479752;4893.926591101207;4896.853094407174;4901.240307752304;4908.288298196813;3543.574771027493;3635.690966267897;3649.142451523943;3650.799206043693;3660.151623430257;5143.871403214949;5145.614078944169;5166.682495963505;5190.429052804264;5198.454162312903;4979.253112033224;4985.376827896513;4986.501349864984;4994.300797888226;4996.725180115129;4770.379850619536;4973.64279111591;4981.925271120864;4986.875328116681;5003.374578177728;4728.976355118202;4826.755558665238;4858.392700912386;4907.981591321383;4956.53782134264;5001.749912504288;5022.137381692401;5029.145045342228;5033.697978121324;5051.74741262928;4926.137925862888;4929.334539837219;4936.3829521309835;4940.591931299562;4953.7523123844385 09:16:23 INFO - PROCESS | 28711 | |5;yui.html;621.5898202255;640.6540468147202;680.6698751105713;715.270424167349;729.0922801112612;12164.270143791367;13165.144265622728;13404.128731632438;13432.2612256326;13446.260455674934;12918.095733298673;12963.28701921394;12972.805407918866;12975.74048519029;12983.935080324585;739.1979702023298;762.672051018062;764.0907320288866;768.2010708862776;770.4067867741835;613.0681364326222;761.7905076104088;763.9923600763976;764.4190415284369;765.6554550452319;660.2374257732326;787.5078076202374;804.2279411764713;814.8492528882147;818.7298191596789;800.6437135474716;808.6320724070563;808.7412028150994;809.0291650019971;809.2110192562263;479.4749749024812;588.7556663984441;589.2369381650773;589.5283772981641;589.8377946064824;10849.077828384598;12429.875701242961;12779.744405111938;12780.808287875689;13276.668083297995;12686.682832929158;13389.66525836852;13415;13563.45746170152;13940.860591394057;10665.146788256921;10676.306040107369;10783.5686572537;10798.352098874093;10808.325169442427;3288.2765791526067;3308.884189053396;3360.983195084009;3394.4568868980846;3418.1283772637817;64054;64246.67876660633;64516.67741661262;65537.67231163813;66831.33168668348;10207.132393746466;10224.6421375253;10264.794704105876;10398.948005259925;10557.15542756593;19077.332293369665;19082.809171908382;19109.235630574898;19118.808811911982;19135.425937221906;6538.738450462023;6619.106420633249;6697.296783837724;6848.760293389708;6895.069165662671;3478.0609235506777;3492.8253587320532;3516.701080408194;3604.9098772530897;3617.5839778425293;2883.668378136499;3013.352129292213;3013.382256637384;3013.789034767545;3046.1014000869795;489.12934975742525;491.0253147502205;495.5490503641741;506.05873076078467;512.2674575357275;439.923013472641;452.3305507848403;585.2684144818976;589.9587028907936;605.6214865708932;7669.309762121436;8518.74443766688;8713.825723485621;8751.56242187888;8974.910250897537;468.24378628514705;476.216623654094;526.2238198657027;534.6765207049679;547.9972600136974;3915.236528876899;4003.559608443069;4018.477597912253;4048.352263637804;4134.441850350225;57352.42647573554;57390;57527.424725753044;58311.50392929229;78460;91529;92354;92846.53576732209;93706.53146734223;95723.52138239265;80277;81856.59071704485;81878;82086.58956705178;83015.58492207622;7400.851982960418;7431.36833369148;7472.551646901204;7601.277878601524;7946.245106714853;31026.379472410877;36790.08024799402;41632.791836040626;42344.153116938105;42846;62520.687396562724;62889.37110629018;64364.67817660882;65422;65558;10368.896311036793;10640.62757803504;10693.251472396858;10853.186011049045;11025.44872756375;12510;13128.34358282063;13234.735305294033;13360.131591446525;13598.86401135976;11819.172657914207;12680.429380677708;12959.740805184032;13120.803187952379;13322.933385333012;8757.518336491554;8947.686830960885;12428.254304741744;12700.047496437988;12735.363231838557;11492.195546311677;12347.320897350732;12396.31820249895;12628.747425051264;12646.13387909956;9581.329306948726;10048.09567138961;10189.235807314333;10202.795944080928;10534;8802.515861627671;8986.460812351279;9011.684591039282;9347.439153650803;10008.39949602997 09:16:23 INFO - PROCESS | 28711 | __end_tp_report 09:16:23 INFO - PROCESS | 28711 | __start_cc_report 09:16:23 INFO - PROCESS | 28711 | _x_x_mozilla_cycle_collect,1328 09:16:23 INFO - PROCESS | 28711 | __end_cc_report 09:16:23 INFO - PROCESS | 28711 | __startTimestamp1475079383886__endTimestamp 09:16:23 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | ------- Summary: start ------- 09:16:23 INFO - PROCESS | 28711 | Number of tests: 6 09:16:23 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | [#0] dojo.html Cycles:165 Average:4766.33 Median:4900.75 stddev:507.44 (10.4%) stddev-sans-first:479.66 09:16:23 INFO - PROCESS | 28711 | Values: 2598.6 2623.6 2660.2 2667.8 2680.2 4698.7 4781.9 4790.3 4861.2 4920.0 4728.9 4791.4 4811.8 4815.8 4880.8 4336.8 4346.6 4377.9 4503.7 4543.5 4399.5 4480.4 4487.8 4531.1 4549.3 4524.4 4537.5 4542.3 4552.7 4609.8 4316.5 4355.6 4371.7 4379.3 4476.5 4384.1 4505.0 4506.5 4508.4 4515.0 4803.2 4842.9 4846.9 4866.6 4910.1 5212.7 5220.8 5223.8 5224.6 5281.4 5012.9 5074.9 5098.2 5150.8 5153.7 4779.6 4812.6 4827.8 4886.4 4889.1 5254.6 5260.1 5263.2 5267.4 5309.7 5013.8 5049.9 5059.9 5059.9 5118.9 5033.6 5082.8 5136.0 5164.0 5184.4 5174.9 5175.8 5216.9 5242.2 5247.4 5115.3 5127.1 5133.5 5134.3 5182.2 4258.5 4260.7 4311.7 4380.8 4387.6 3749.6 3791.4 3801.0 3810.6 3848.8 4403.8 4405.6 4418.1 4467.5 4469.2 5221.5 5229.3 5229.5 5229.7 5231.0 4247.0 4273.5 4384.0 4443.8 4445.8 4692.9 4701.2 4708.6 4710.1 4764.7 4882.3 4899.7 4908.2 4908.8 4959.1 4922.6 5021.7 5105.5 5170.0 5197.9 4822.6 4837.0 4842.4 4853.0 4897.8 5049.2 5064.5 5068.1 5072.4 5121.8 5030.6 5158.3 5159.3 5172.6 5236.3 4948.6 4953.1 4967.3 4978.5 5012.4 5045.0 5053.4 5054.5 5092.9 5105.1 4940.0 4976.9 4982.1 4985.1 5052.8 4896.9 4950.8 4961.8 5074.5 5081.6 4864.1 4900.8 4917.3 4958.7 4972.3 09:16:23 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | [#1] ext.html Cycles:180 Average:46745.22 Median:36443.77 stddev:82274.00 (225.8%) stddev-sans-first:82431.74 09:16:23 INFO - PROCESS | 28711 | Values: 566.3 567.1 572.5 579.4 588.4 27285.3 36883.6 38039.2 38151.2 42226.0 39071.6 39525.4 39537.4 39553.4 39583.6 7668.5 7830.4 7830.6 7832.7 7838.6 7411.1 7567.5 9783.5 10038.2 10041.9 10083.3 10164.5 10176.6 10190.3 10192.0 9927.7 10005.5 10013.6 10016.8 10026.2 9797.2 9875.4 9877.5 9878.2 9882.1 37887.4 38234.4 38302.2 38303.4 38374.6 2810.7 2865.5 3138.6 3583.9 3637.1 27786.2 28321.3 28332.3 28401.4 28614.0 3454.4 3515.6 3554.3 3603.6 3618.0 251891.0 252003.0 252529.0 252775.7 252871.0 50114.2 50115.2 50291.7 50532.5 50563.2 53248.5 53279.0 53331.5 53366.0 53385.7 12293.4 12554.1 12590.0 12592.0 12602.3 8504.6 8681.4 9610.5 9689.5 9691.7 13345.0 13470.5 13528.6 14360.7 14872.9 5089.3 5619.5 5621.7 5623.8 5637.8 760.7 834.7 901.6 908.7 919.2 26202.9 27026.7 27229.5 27332.6 27574.3 900.8 901.7 918.6 933.1 952.1 14244.2 14406.2 14491.9 14659.2 14750.3 354540.0 355362.0 356746.0 356903.0 371444.0 39152.6 46255.5 47554.5 47581.5 52350.2 1084.8 1120.8 1139.5 1182.0 1183.0 27186.3 28382.4 28390.9 29071.9 29572.1 41441.4 41796.6 41829.6 41831.4 41848.0 315917.0 317222.0 320793.0 321955.0 322190.0 35241.0 35443.5 35490.1 38702.6 39954.6 39761.6 42925.0 43222.6 44185.3 44209.3 36125.3 36777.3 36949.8 37378.6 37620.4 36134.1 36432.3 36455.3 37308.0 37835.6 35670.1 36065.5 36098.0 36310.8 36618.6 32319.4 33142.8 33439.8 33885.7 34478.3 25109.5 25552.5 25754.5 26175.2 26194.6 09:16:23 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | [#2] jquery.html Cycles:180 Average:23331.85 Median:390829.50 stddev:63316.69 (16.2%) stddev-sans-first:63470.79 09:16:23 INFO - PROCESS | 28711 | Values: 343.3 357.8 366.9 369.7 379.3 12109.9 13179.5 14580.8 16297.3 16952.2 15141.2 15303.5 15409.1 15431.5 15940.5 4865.5 4926.8 5066.3 5075.8 5098.2 3700.7 3881.8 4996.9 5004.2 5044.2 3849.0 3904.2 3911.2 3924.3 4053.7 5941.9 6017.2 6037.2 6038.3 6042.7 5079.9 5239.3 5241.9 5242.6 5294.8 19926.7 20160.6 20397.6 20561.7 20716.6 1901.2 2066.7 2189.1 2266.8 2350.8 12640.4 12758.2 12953.4 13195.6 13404.2 1685.4 1714.2 1721.5 1727.0 1744.0 44917.2 65049.3 65193.7 67143.3 68766.7 9107.6 9360.3 9418.0 9567.7 9809.5 13109.9 13121.0 13128.4 13133.5 13143.6 6539.7 6567.4 6766.4 6813.1 6861.8 6254.6 6301.6 6344.8 6425.5 6464.9 5827.4 5860.6 5906.4 5953.9 6016.3 1679.6 1703.1 1703.8 1717.3 1767.2 814.6 858.0 862.4 879.7 894.3 16496.8 16757.0 17103.7 17157.0 17804.9 842.3 865.5 906.6 916.3 937.9 8778.6 9474.8 9998.4 10103.9 10222.4 369835.0 385735.0 390434.0 390758.0 390901.0 65212.7 65598.7 66572.7 67187.7 69287.0 882.9 883.5 908.1 908.7 909.3 14951.5 15033.5 15205.8 15350.2 15717.6 24059.5 35331.3 35383.8 35701.3 35772.3 43479.3 44783.3 44812.3 45766.3 47735.8 7223.3 7296.7 7375.6 7466.9 7500.5 11012.1 11352.4 11573.3 11639.1 11684.9 10333.8 10351.2 10419.2 10622.9 10865.6 10050.9 10295.3 10418.7 10575.1 10589.0 10184.0 10265.7 10416.7 10600.3 10807.5 6444.0 6844.5 6917.5 6971.9 7194.1 8259.7 9634.2 9725.3 9822.5 10056.6 09:16:23 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | [#3] mootools.html Cycles:180 Average:5083.41 Median:5214.20 stddev:2920.35 (56.0%) stddev-sans-first:2914.44 09:16:23 INFO - PROCESS | 28711 | Values: 1264.7 1390.6 1405.3 1489.3 1704.7 6852.9 7453.4 7468.3 7474.7 7782.8 7821.9 7896.7 7901.4 7903.4 7908.4 2112.9 2143.2 2143.3 2144.3 2146.2 2122.8 2138.2 2151.8 2151.9 2164.9 2018.3 2033.1 2039.9 2043.9 2141.7 2095.1 2110.1 2110.4 2113.4 2115.4 2055.8 2103.9 2106.9 2107.2 2107.8 7851.8 8016.2 8053.5 8061.2 8076.1 4344.3 4383.2 4503.8 4510.8 4514.3 2794.4 2874.7 2878.3 2881.9 2894.2 950.1 951.7 953.5 985.0 986.7 10333.7 10666.0 10683.6 10686.1 10690.6 3694.4 3808.4 3910.3 3987.0 3994.9 8362.9 8390.3 8431.2 8433.1 8453.2 4313.7 4313.8 4320.4 4320.5 4322.0 3578.5 3693.8 4001.2 4003.4 4019.3 3297.2 3305.2 3425.1 3446.1 3473.0 994.4 1004.1 1005.5 1005.7 1030.3 678.9 828.6 857.3 882.2 893.0 5204.6 5233.6 5326.3 5333.2 5619.9 394.7 397.7 401.1 401.6 403.9 3688.8 3801.0 3853.7 3862.5 3986.1 9478.0 9896.4 9977.2 9985.6 10000.8 9538.1 9724.0 9868.8 9905.8 9912.6 7348.9 7371.7 7417.9 7422.4 7426.1 5223.8 5351.8 5355.0 5418.0 5503.3 8818.8 8832.8 8906.6 8936.5 8991.9 8590.0 9120.2 9131.6 9139.4 9166.0 6044.5 6210.3 6229.4 6548.8 6557.4 7236.8 7443.7 7497.5 7706.8 7719.7 6750.6 6750.7 6814.5 6943.2 7016.4 6752.6 6875.4 6913.5 7043.0 7055.4 6337.2 6445.1 6450.0 6455.8 6459.9 4410.6 4422.2 4501.5 4544.7 4581.9 4521.9 4562.2 4652.7 4692.2 4752.0 09:16:23 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | [#4] prototype.html Cycles:180 Average:4164.93 Median:4762.00 stddev:1200.82 (25.2%) stddev-sans-first:1168.33 09:16:23 INFO - PROCESS | 28711 | Values: 284.4 286.4 295.2 296.6 300.3 4593.5 4752.2 4809.3 4836.7 4849.4 4601.9 4734.2 4782.7 4790.6 4791.5 3762.3 3778.8 3812.1 3814.7 3819.6 3496.5 3597.2 3763.6 3778.8 3785.7 3692.8 3702.9 3731.4 3746.0 3747.3 3732.2 3736.3 3738.3 3755.1 3774.6 3344.1 3350.4 3351.1 3351.6 3357.3 4479.3 4641.4 4661.3 4667.8 4715.9 4299.7 4302.9 4307.2 4309.4 4313.6 5003.5 5018.5 5020.7 5027.2 5036.0 3531.7 3594.8 3630.7 3652.2 3653.5 5216.5 5217.6 5228.6 5234.5 5245.6 3725.5 3727.3 3736.2 3736.8 3739.1 3774.5 3785.7 3786.8 3786.9 3791.1 4127.0 4172.5 4184.5 4185.2 4192.4 4953.5 4962.0 4962.4 5016.5 5022.9 1199.1 1226.1 1260.6 1270.1 1286.9 876.8 893.9 896.2 898.9 902.0 4338.6 4343.2 4349.3 4351.6 4357.8 5062.7 5068.0 5078.6 5084.3 5089.3 4180.0 4423.6 4426.1 4467.2 4473.8 2719.9 2731.4 2761.8 2761.9 2762.9 4753.6 4874.9 4876.0 4882.8 4904.3 5257.2 5270.2 5300.4 5301.7 5303.2 4807.2 4838.3 4838.8 4839.2 4900.7 4887.0 5129.6 5166.7 5167.9 5193.2 5086.6 5095.5 5101.5 5110.6 5114.8 4891.7 4893.9 4896.9 4901.2 4908.3 3543.6 3635.7 3649.1 3650.8 3660.2 5143.9 5145.6 5166.7 5190.4 5198.5 4979.3 4985.4 4986.5 4994.3 4996.7 4770.4 4973.6 4981.9 4986.9 5003.4 4729.0 4826.8 4858.4 4908.0 4956.5 5001.7 5022.1 5029.1 5033.7 5051.7 4926.1 4929.3 4936.4 4940.6 4953.8 09:16:23 INFO - PROCESS | 28711 | 09:16:23 INFO - PROCESS | 28711 | [#5] yui.html Cycles:180 Average:17196.89 Median:472.23 stddev:24176.80 (5119.7%) stddev-sans-first:24212.59 09:16:23 INFO - PROCESS | 28711 | Values: 621.6 640.7 680.7 715.3 729.1 12164.3 13165.1 13404.1 13432.3 13446.3 12918.1 12963.3 12972.8 12975.7 12983.9 739.2 762.7 764.1 768.2 770.4 613.1 761.8 764.0 764.4 765.7 660.2 787.5 804.2 814.8 818.7 800.6 808.6 808.7 809.0 809.2 479.5 588.8 589.2 589.5 589.8 10849.1 12429.9 12779.7 12780.8 13276.7 12686.7 13389.7 13415.0 13563.5 13940.9 10665.1 10676.3 10783.6 10798.4 10808.3 3288.3 3308.9 3361.0 3394.5 3418.1 64054.0 64246.7 64516.7 65537.7 66831.3 10207.1 10224.6 10264.8 10398.9 10557.2 19077.3 19082.8 19109.2 19118.8 19135.4 6538.7 6619.1 6697.3 6848.8 6895.1 3478.1 3492.8 3516.7 3604.9 3617.6 2883.7 3013.4 3013.4 3013.8 3046.1 489.1 491.0 495.5 506.1 512.3 439.9 452.3 585.3 590.0 605.6 7669.3 8518.7 8713.8 8751.6 8974.9 468.2 476.2 526.2 534.7 548.0 3915.2 4003.6 4018.5 4048.4 4134.4 57352.4 57390.0 57527.4 58311.5 78460.0 91529.0 92354.0 92846.5 93706.5 95723.5 80277.0 81856.6 81878.0 82086.6 83015.6 7400.9 7431.4 7472.6 7601.3 7946.2 31026.4 36790.1 41632.8 42344.2 42846.0 62520.7 62889.4 64364.7 65422.0 65558.0 10368.9 10640.6 10693.3 10853.2 11025.4 12510.0 13128.3 13234.7 13360.1 13598.9 11819.2 12680.4 12959.7 13120.8 13322.9 8757.5 8947.7 12428.3 12700.0 12735.4 11492.2 12347.3 12396.3 12628.7 12646.1 9581.3 10048.1 10189.2 10202.8 10534.0 8802.5 8986.5 9011.7 9347.4 10008.4 09:16:23 INFO - PROCESS | 28711 | -------- Summary: end -------- 09:16:23 INFO - PROCESS | 28711 | 09:16:24 INFO - TEST-INFO | 28711: exit 0 09:16:24 INFO - TEST-OK | dromaeo_css | took 1121190ms 09:16:24 INFO - TEST-START | kraken 09:16:24 INFO - operating with platform_type : linux_ 09:16:24 INFO - Initialising browser for kraken test... 09:16:24 INFO - TEST-INFO | started process 29013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnXx5WT/profile http://localhost:37823/getInfo.html) 09:16:29 INFO - PROCESS | 29013 | __metrics Screen width/height:1600/1200 09:16:29 INFO - PROCESS | 29013 | colorDepth:24 09:16:29 INFO - PROCESS | 29013 | Browser inner width/height: 1024/697 09:16:29 INFO - PROCESS | 29013 | __metrics 09:16:29 INFO - PROCESS | 29013 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:16:29 INFO - PROCESS | 29013 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:16:30 INFO - PROCESS | 29013 | console.error: PushService: 09:16:30 INFO - PROCESS | 29013 | stateChangeProcessEnqueue: Error transitioning state 09:16:30 INFO - PROCESS | 29013 | UnknownError 09:16:31 INFO - TEST-INFO | 29013: exit 0 09:16:31 INFO - Browser initialized. 09:16:31 INFO - Running cycle 1/1 for kraken test... 09:16:31 INFO - TEST-INFO | started process 29096 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnXx5WT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:43 INFO - PROCESS | 29096 | RSS: Main: 175403008 09:16:43 INFO - PROCESS | 29096 | 09:16:46 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37823/tests/kraken/driver.html?testName=audio-beat-detection) 09:16:47 INFO - PROCESS | 29096 | RSS: Main: 233762816 09:16:47 INFO - PROCESS | 29096 | 09:16:49 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37823/tests/kraken/driver.html?testName=audio-dft) 09:16:50 INFO - PROCESS | 29096 | RSS: Main: 218091520 09:16:50 INFO - PROCESS | 29096 | 09:16:53 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37823/tests/kraken/driver.html?testName=audio-fft) 09:16:54 INFO - PROCESS | 29096 | RSS: Main: 300105728 09:16:54 INFO - PROCESS | 29096 | 09:16:56 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37823/tests/kraken/driver.html?testName=audio-oscillator) 09:16:57 INFO - PROCESS | 29096 | RSS: Main: 210329600 09:16:57 INFO - PROCESS | 29096 | 09:16:59 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37823/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:17:00 INFO - PROCESS | 29096 | RSS: Main: 214372352 09:17:00 INFO - PROCESS | 29096 | 09:17:03 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37823/tests/kraken/driver.html?testName=imaging-darkroom) 09:17:03 INFO - PROCESS | 29096 | RSS: Main: 250531840 09:17:03 INFO - PROCESS | 29096 | 09:17:07 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37823/tests/kraken/driver.html?testName=imaging-desaturate) 09:17:07 INFO - PROCESS | 29096 | RSS: Main: 249507840 09:17:07 INFO - PROCESS | 29096 | 09:17:10 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37823/tests/kraken/driver.html?testName=json-parse-financial) 09:17:11 INFO - PROCESS | 29096 | RSS: Main: 249262080 09:17:11 INFO - PROCESS | 29096 | 09:17:13 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37823/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:17:13 INFO - PROCESS | 29096 | RSS: Main: 211632128 09:17:13 INFO - PROCESS | 29096 | 09:17:15 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:17:16 INFO - PROCESS | 29096 | RSS: Main: 218886144 09:17:16 INFO - PROCESS | 29096 | 09:17:18 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:17:19 INFO - PROCESS | 29096 | RSS: Main: 223744000 09:17:19 INFO - PROCESS | 29096 | 09:17:22 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:17:22 INFO - PROCESS | 29096 | RSS: Main: 223948800 09:17:22 INFO - PROCESS | 29096 | 09:17:25 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:17:26 INFO - PROCESS | 29096 | RSS: Main: 222134272 09:17:26 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | Cycle 1(1): loaded http://localhost:37823/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37823/tests/kraken/driver.html?testName=ai-astar) 09:17:28 INFO - PROCESS | 29096 | RSS: Main: 273563648 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | __start_tp_report 09:17:28 INFO - PROCESS | 29096 | _x_x_mozilla_page_load 09:17:28 INFO - PROCESS | 29096 | _x_x_mozilla_page_load_details 09:17:28 INFO - PROCESS | 29096 | |i|pagename|runs| 09:17:28 INFO - PROCESS | 29096 | |0;ai-astar;111;112;113;114;113;114;114;123;112;113 09:17:28 INFO - PROCESS | 29096 | |1;audio-beat-detection;167;131;127;171;130;125;123;125;158;126 09:17:28 INFO - PROCESS | 29096 | |2;audio-dft;162;162;163;162;161;162;164;162;163;162 09:17:28 INFO - PROCESS | 29096 | |3;audio-fft;77;77;77;106;77;77;76;76;112;76 09:17:28 INFO - PROCESS | 29096 | |4;audio-oscillator;85;84;134;84;85;83;110;83;83;122 09:17:28 INFO - PROCESS | 29096 | |5;imaging-gaussian-blur;117;117;118;120;116;117;118;117;117;117 09:17:28 INFO - PROCESS | 29096 | |6;imaging-darkroom;146;146;147;146;147;147;147;146;148;147 09:17:28 INFO - PROCESS | 29096 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;95;95 09:17:28 INFO - PROCESS | 29096 | |8;json-parse-financial;63;62;62;62;69;64;62;62;63;64 09:17:28 INFO - PROCESS | 29096 | |9;json-stringify-tinderbox;66;66;67;67;68;67;66;66;66;67 09:17:28 INFO - PROCESS | 29096 | |10;stanford-crypto-aes;82;82;85;82;97;83;88;82;88;82 09:17:28 INFO - PROCESS | 29096 | |11;stanford-crypto-ccm;140;139;134;140;143;142;135;139;165;148 09:17:28 INFO - PROCESS | 29096 | |12;stanford-crypto-pbkdf2;173;174;172;170;172;173;172;172;174;172 09:17:28 INFO - PROCESS | 29096 | |13;stanford-crypto-sha256-iterative;74;69;70;69;76;70;70;70;72;69 09:17:28 INFO - PROCESS | 29096 | __end_tp_report 09:17:28 INFO - PROCESS | 29096 | __start_cc_report 09:17:28 INFO - PROCESS | 29096 | _x_x_mozilla_cycle_collect,1976 09:17:28 INFO - PROCESS | 29096 | __end_cc_report 09:17:28 INFO - PROCESS | 29096 | __startTimestamp1475079448300__endTimestamp 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | ------- Summary: start ------- 09:17:28 INFO - PROCESS | 29096 | Number of tests: 14 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#0] ai-astar Cycles:10 Average:113.90 Median:113.50 stddev:3.35 (3.0%) stddev-sans-first:3.38 09:17:28 INFO - PROCESS | 29096 | Values: 111.0 112.0 113.0 114.0 113.0 114.0 114.0 123.0 112.0 113.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:130.50 stddev:19.06 (14.6%) stddev-sans-first:17.16 09:17:28 INFO - PROCESS | 29096 | Values: 167.0 131.0 127.0 171.0 130.0 125.0 123.0 125.0 158.0 126.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 09:17:28 INFO - PROCESS | 29096 | Values: 162.0 162.0 163.0 162.0 161.0 162.0 164.0 162.0 163.0 162.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#3] audio-fft Cycles:10 Average:83.10 Median:77.00 stddev:13.73 (17.8%) stddev-sans-first:14.39 09:17:28 INFO - PROCESS | 29096 | Values: 77.0 77.0 77.0 106.0 77.0 77.0 76.0 76.0 112.0 76.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:83.50 stddev:19.29 (23.1%) stddev-sans-first:20.09 09:17:28 INFO - PROCESS | 29096 | Values: 85.0 84.0 134.0 84.0 85.0 83.0 110.0 83.0 83.0 122.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.00 stddev:1.07 (0.9%) stddev-sans-first:1.13 09:17:28 INFO - PROCESS | 29096 | Values: 117.0 117.0 118.0 120.0 116.0 117.0 118.0 117.0 117.0 117.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#6] imaging-darkroom Cycles:10 Average:146.70 Median:147.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 09:17:28 INFO - PROCESS | 29096 | Values: 146.0 146.0 147.0 146.0 147.0 147.0 147.0 146.0 148.0 147.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:17:28 INFO - PROCESS | 29096 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#8] json-parse-financial Cycles:10 Average:63.30 Median:63.00 stddev:2.16 (3.4%) stddev-sans-first:2.29 09:17:28 INFO - PROCESS | 29096 | Values: 63.0 62.0 62.0 62.0 69.0 64.0 62.0 62.0 63.0 64.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#9] json-stringify-tinderbox Cycles:10 Average:66.60 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 09:17:28 INFO - PROCESS | 29096 | Values: 66.0 66.0 67.0 67.0 68.0 67.0 66.0 66.0 66.0 67.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:84.00 stddev:4.84 (5.8%) stddev-sans-first:5.00 09:17:28 INFO - PROCESS | 29096 | Values: 82.0 82.0 85.0 82.0 97.0 83.0 88.0 82.0 88.0 82.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:141.00 stddev:8.83 (6.3%) stddev-sans-first:9.32 09:17:28 INFO - PROCESS | 29096 | Values: 140.0 139.0 134.0 140.0 143.0 142.0 135.0 139.0 165.0 148.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:172.50 stddev:1.17 (0.7%) stddev-sans-first:1.22 09:17:28 INFO - PROCESS | 29096 | Values: 173.0 174.0 172.0 170.0 172.0 173.0 172.0 172.0 174.0 172.0 09:17:28 INFO - PROCESS | 29096 | 09:17:28 INFO - PROCESS | 29096 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.90 Median:70.00 stddev:2.38 (3.4%) stddev-sans-first:2.24 09:17:28 INFO - PROCESS | 29096 | Values: 74.0 69.0 70.0 69.0 76.0 70.0 70.0 70.0 72.0 69.0 09:17:28 INFO - PROCESS | 29096 | -------- Summary: end -------- 09:17:28 INFO - PROCESS | 29096 | 09:17:29 INFO - TEST-INFO | 29096: exit 0 09:17:29 INFO - TEST-OK | kraken | took 64899ms 09:17:29 INFO - SUITE-END | took 1186s 09:17:29 INFO - Completed test suite (00:19:46) 09:17:29 INFO - javascript benchmark 09:17:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2598.6491823603815, 2623.6326914232022, 2660.175345642851, 2667.8265912715674, 2680.155750938453, 4698.671093023489, 4781.8804529886775, 4790.257510085934, 4861.173600487917, 4920.0, 4728.905421891569, 4791.401074865642, 4811.831585894494, 4815.807367705288, 4880.78036488358, 4336.761478118702, 4346.587074227959, 4377.912441751163, 4503.662225333105, 4543.477500087499, 4399.494058183318, 4480.372747815292, 4487.775611219426, 4531.071130418273, 4549.340345649879, 4524.411826462539, 4537.478190008158, 4542.250528662767, 4552.749598772066, 4609.838655647053, 4316.5467625899455, 4355.5644435556505, 4371.69398142127, 4379.277419225824, 4476.485204201526, 4384.057427653038, 4505.031418245093, 4506.526814684476, 4508.413906192207, 4515.0, 4803.231482962744, 4842.854714358539, 4846.927296090562, 4866.635002374836, 4910.091633047898, 5212.713300768456, 5220.765065572058, 5223.817166399196, 5224.555912747421, 5281.419043905167, 5012.949870501285, 5074.873128171788, 5098.209777484459, 5150.845474635767, 5153.665011774223, 4779.593734532569, 4812.590929770939, 4827.782749776268, 4886.389201349832, 4889.071076495455, 5254.579633629301, 5260.053190425683, 5263.184206448007, 5267.446918073624, 5309.707966061904, 5013.799448022039, 5049.924251136236, 5059.873503162376, 5059.8988020239385, 5118.897622047538, 5033.6224783141415, 5082.847514574569, 5136.049830781242, 5163.974180129076, 5184.403793563789, 5174.870628234325, 5175.844724658266, 5216.921746173811, 5242.213667949762, 5247.4490178530705, 5115.335006449212, 5127.077126117335, 5133.486651334837, 5134.255532947776, 5182.222666600041, 4258.488981322199, 4260.744355338689, 4311.655067594585, 4380.78096095194, 4387.6270517006415, 3749.643783840531, 3791.374423220193, 3801.030737162061, 3810.6189381062227, 3848.8460461581785, 4403.757793321398, 4405.559444055633, 4418.116376724668, 4467.508574056851, 4469.240229160985, 5221.503957124067, 5229.346331708536, 5229.50319719626, 5229.712365819841, 5230.97384513075, 4247.0, 4273.508546517128, 4383.978080109579, 4443.755593442392, 4445.799939002753, 4692.882677933079, 4701.2242979908615, 4708.599769019672, 4710.105080034782, 4764.714117152982, 4882.316475693406, 4899.706017638953, 4908.214685650279, 4908.754562271801, 4959.082569724613, 4922.606191504599, 5021.698698078127, 5105.463926287686, 5170.0, 5197.870053248548, 4822.590079843112, 4836.95163048365, 4842.394700662418, 4852.975735121302, 4897.755112244445, 5049.166887463527, 5064.5441910228255, 5068.113080211022, 5072.365954255718, 5121.769520371667, 5030.572401345927, 5158.252053452154, 5159.329287192791, 5172.63791534604, 5236.266922630911, 4948.554630083309, 4953.108440480748, 4967.279744436964, 4978.4523702393435, 5012.423571289349, 5044.974775126248, 5053.393592768891, 5054.4692807254705, 5092.949070509248, 5105.106606343949, 4940.0, 4976.900461990812, 4982.053409852117, 4985.077760614298, 5052.823151189691, 4896.92654610174, 4950.801967921243, 4961.776720047678, 5074.517920797518, 5081.644284900021, 4864.124457597666, 4900.754962251802, 4917.262410638433, 4958.677685950402, 4972.254161875748], "value": 4733.075421208568, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.2921348314608, 567.0813282482378, 572.4676051272318, 579.3945327133146, 588.3586890289583, 27285.317867053334, 36883.631163688355, 38039.23921521568, 38151.23697526048, 42226.0, 39071.60928390715, 39525.40711889317, 39537.40693889587, 39553.40669889954, 39583.60416395835, 7668.539887606761, 7830.412719046066, 7830.608469576527, 7832.686692532292, 7838.568878711697, 7411.110666720001, 7567.508111972732, 9783.46190959497, 10038.196944244442, 10041.899581004205, 10083.294169408144, 10164.491775411238, 10176.592936282539, 10190.286679932407, 10192.0, 9927.702168934944, 10005.549750261183, 10013.649522266758, 10016.849747253797, 10026.248031397601, 9797.167240784542, 9875.407475551418, 9877.456739879304, 9878.160356369717, 9882.061204185591, 37887.431688524695, 38234.426483602765, 38302.23395532102, 38303.42544861829, 38374.61625383738, 2810.7189281071933, 2865.498537755884, 3138.59198304219, 3583.9104022399647, 3637.1452708613456, 27786.16641500758, 28321.291967700767, 28332.29169270764, 28401.431971360664, 28614.0, 3454.4300190468543, 3515.6484351564895, 3554.253606742587, 3603.6396360364015, 3617.981910090433, 251891.0, 252003.0, 252529.0, 252775.73612132005, 252871.0, 50114.24828627573, 50115.24827127596, 50291.74854125706, 50532.49467505352, 50563.241551376756, 53248.46751532512, 53279.0, 53331.46668533265, 53366.0, 53385.7330713344, 12293.446794894251, 12554.121211515107, 12590.0, 12592.0, 12602.306873122066, 8504.574771261536, 8681.435706679045, 9610.471424071744, 9689.51552422376, 9691.70924872255, 13345.0, 13470.52853150135, 13528.59414217575, 14360.712785744226, 14872.925635371754, 5089.262057001714, 5619.494245517842, 5621.7189140542805, 5623.831285061455, 5637.774489020475, 760.7337431898791, 834.6577903059716, 901.6348378906554, 908.7001289574424, 919.2002957427081, 26202.86898565495, 27026.72973270242, 27229.45541089167, 27332.59001114985, 27574.310642233704, 900.7703035725979, 901.6844104563481, 918.5682729117304, 933.141509810962, 952.066974365126, 14244.216568088854, 14406.207658578669, 14491.85508144905, 14659.204936324119, 14750.262486875614, 354540.0, 355362.0, 356746.0, 356903.0, 371444.0, 39152.60847391547, 46255.5374446258, 47554.52445475501, 47581.52418475771, 52350.21474677883, 1084.810158222292, 1120.7814476177068, 1139.4758411130806, 1182.0366401382846, 1182.9940850295866, 27186.32034199161, 28382.43235135327, 28390.85804570964, 29071.854640726662, 29572.112836614935, 41441.37837932494, 41796.58203417927, 41829.58170418379, 41831.37252941148, 41848.0, 315917.0, 317222.0, 320793.0, 321955.0, 322190.0, 35241.0, 35443.468347975315, 35490.112747180494, 38702.61297387103, 39954.60045399625, 39761.60238397579, 42925.0, 43222.567774321855, 44185.33721994108, 44209.33685994648, 36125.27749444944, 36777.26445471022, 36949.81525092357, 37378.6262137386, 37620.435693465166, 36134.09664758402, 36432.27135457223, 36455.27089458249, 37308.0, 37835.62164378431, 35670.108247294025, 36065.459018115274, 36098.0, 36310.8184459076, 36618.63381366152, 32319.353612927138, 33142.834285828416, 33439.83280083584, 33885.66114338825, 34478.31043379169, 25109.497810043333, 25552.479285150515, 25754.484910301315, 26175.214743557724, 26194.607080894184], "value": 17579.07628321226, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.2705500810777, 357.84433771309466, 366.86792754608337, 369.7374863846668, 379.27558363525674, 12109.939450302747, 13179.472821087156, 14580.781288280685, 16297.348106075773, 16952.152392380394, 15141.167235802026, 15303.464378746747, 15409.07545547268, 15431.537053888402, 15940.52178434646, 4865.5134486551415, 4926.802927882881, 5066.316047333618, 5075.847724568269, 5098.209777484496, 3700.666939975402, 3881.8253178606888, 4996.925046124311, 5004.199328107504, 5044.243363495487, 3848.9615103849023, 3904.2386734586767, 3911.198204368112, 3924.2936271471126, 4053.655439287649, 5941.910871336933, 6017.187679663233, 6037.245344331958, 6038.275406951194, 6042.728077236491, 5079.923801142986, 5239.292695486099, 5241.86895327616, 5242.580593552507, 5294.814681486129, 19926.70109948352, 20160.596788064304, 20397.592048159102, 20561.69157462639, 20716.58566828655, 1901.2109974360624, 2066.7209926659857, 2189.0696454007143, 2266.841321107523, 2350.847194932324, 12640.360274570456, 12758.23450592958, 12953.352332383438, 13195.60413187606, 13404.195748255135, 1685.4016824027417, 1714.211462727147, 1721.5265801904452, 1727.041491971955, 1743.9912800436043, 44917.1987230649, 65049.34950650528, 65193.67403163001, 67143.32856671419, 68766.65616671885, 9107.635694572275, 9360.344775865624, 9418.0, 9567.665131720496, 9809.460479673544, 13109.934450327688, 13121.0, 13128.409221585052, 13133.474661013452, 13143.60569182926, 6539.673016349258, 6567.376099270562, 6766.391024807693, 6813.080234168423, 6861.828454288683, 6254.6247225166635, 6301.558890877686, 6344.809655710336, 6425.470043619237, 6464.935350646528, 5827.388124246978, 5860.619059761102, 5906.438888305604, 5953.910691339634, 6016.338202797689, 1679.5968967447725, 1703.1058694185651, 1703.761473393726, 1717.2787429279483, 1767.1605987156, 814.621201141472, 858.013284722574, 862.4351050062219, 879.6965047058754, 894.3113802372137, 16496.83503164977, 16756.989161300477, 17103.657926841643, 17156.968112493552, 17804.91097544504, 842.3261390887266, 865.4893612768496, 906.5875026862761, 916.2898753466117, 937.8686983822271, 8778.60496277669, 9474.763130921781, 9998.350107243006, 10103.898961010444, 10222.386656800616, 369835.0, 385735.0, 390434.0, 390758.0, 390901.0, 65212.67393663001, 65598.67200663967, 66572.66713666498, 67187.66406168036, 69287.0, 882.876397304365, 883.5405589093764, 908.0783005249676, 908.6910450446879, 909.263496567769, 14951.476698315942, 15033.451397087656, 15205.847941520444, 15350.232488375314, 15717.607059823138, 24059.518809623358, 35331.29337413289, 35383.761568344984, 35701.28597428089, 35772.28455430929, 43479.347809782244, 44783.328250075625, 44812.32781508215, 45766.313505298116, 47735.761321193175, 7223.313785190398, 7296.7081316746735, 7375.594342311009, 7466.925330746623, 7500.47477685487, 11012.119030477703, 11352.375619341015, 11573.305601663928, 11639.127065469955, 11684.88315116872, 10333.844992325272, 10351.223658225814, 10419.24288962454, 10622.946885265525, 10865.565377384815, 10050.949745251228, 10295.330803497749, 10418.73953151147, 10575.101116405192, 10589.0, 10184.0, 10265.725658358171, 10416.739581510523, 10600.310979786289, 10807.513661885392, 6443.96778016107, 6844.486663500158, 6917.481188910952, 6971.930280697331, 7194.136703595602, 8259.710910118118, 9634.181094606756, 9725.319227653996, 9822.459764712723, 10056.597736090474], "value": 7998.597811874112, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1264.721761212533, 1390.645385426716, 1405.3394904394934, 1489.2628149066213, 1704.7187214109672, 6852.862942741142, 7453.366463850566, 7468.253174682529, 7474.701011959529, 7782.805429864256, 7821.927189294827, 7896.684132634688, 7901.446898717092, 7903.3677305815545, 7908.418316336742, 2112.9049192786283, 2143.228437762412, 2143.346279384787, 2144.2709478777215, 2146.1737231166035, 2122.7983341582603, 2138.1554286057, 2151.8278537716947, 2151.924682636108, 2164.945876353088, 2018.2734215682342, 2033.1359172351765, 2039.857209995301, 2043.9386818395399, 2141.6787481877764, 2095.0886364431444, 2110.05047728522, 2110.356341315898, 2113.4399384163207, 2115.4182599785026, 2055.825254853339, 2103.894805259746, 2106.9157233710635, 2107.2203284784728, 2107.7681455039933, 7851.842963140705, 8016.238457346542, 8053.516788992679, 8061.153578874192, 8076.111627721005, 4344.283193273106, 4383.211022016036, 4503.842365517191, 4510.842120525798, 4514.322851572258, 2794.3852352482622, 2874.6837847836723, 2878.294817769639, 2881.9135425937257, 2894.1751600793673, 950.1306304731231, 951.7477868364889, 953.50894289441, 985.0494273026563, 986.7187851462371, 10333.689989300334, 10666.0, 10683.626073087482, 10686.145108391387, 10690.572377104907, 3694.3719567673024, 3808.352580061396, 3910.335243008695, 3987.0, 3994.8801535953967, 8362.874556881652, 8390.32877369809, 8431.19903609156, 8433.072362040171, 8453.196946290092, 4313.719608225455, 4313.827446902089, 4320.395144679747, 4320.481542214891, 4321.956780432155, 3578.499010138583, 3693.8337774800198, 4001.1797581496307, 4003.3586229645707, 4019.31671615826, 3297.1921879139763, 3305.2067503798917, 3425.1265927188406, 3446.0867870014467, 3472.965270347315, 994.4431118573622, 1004.1063453526367, 1005.4821766790109, 1005.7485628592851, 1030.340582027503, 678.864227154571, 828.5691440450931, 857.2541888556871, 882.2192359761528, 892.9553522323857, 5204.635675502678, 5233.633645644768, 5326.280952071421, 5333.2266821310695, 5619.8595035124445, 394.6842525979263, 397.7076848516338, 401.0535137076497, 401.5783427401217, 403.8990252436891, 3688.8155592220282, 3801.0, 3853.6917046636204, 3862.4978752761963, 3986.063275130342, 9478.0, 9896.356736812084, 9977.151942084858, 9985.550650220757, 10000.849987250198, 9538.093881081286, 9723.951380243054, 9868.753281168028, 9905.75235619101, 9912.612234287206, 7348.926510734931, 7371.6682749276415, 7417.888731669029, 7422.406207503279, 7426.071741032371, 5223.764930578057, 5351.839444816662, 5355.0, 5417.972910135424, 5503.330884520924, 8818.779530511789, 8832.823343533222, 8906.554672266231, 8936.50849203274, 8991.91008089937, 8589.957050214709, 9120.179183873483, 9131.589078491352, 9139.405938613969, 9165.954170229374, 6044.546659000681, 6210.254769427517, 6229.4393504584805, 6548.770793022221, 6557.442617377576, 7236.819079523054, 7443.739469118555, 7497.475176737576, 7706.807329816799, 7719.691212351668, 6750.594964302158, 6750.696218670269, 6814.488913331421, 6943.201531823905, 7016.403605693573, 6752.628605426748, 6875.381215690411, 6913.481488888253, 7042.964785176041, 7055.400290975324, 6337.2395312562785, 6445.097686323825, 6450.0, 6455.7740479083, 6459.870802584016, 4410.647148228199, 4422.159789639958, 4501.504834468271, 4544.6818722689095, 4581.862544123682, 4521.886952826206, 4562.2016147173445, 4652.744099074448, 4692.202325604587, 4751.9762401189155], "value": 3991.2824820842206, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.4356041770262, 286.40713722594234, 295.2044240771122, 296.6484715612, 300.33026351236725, 4593.496544383311, 4752.239641657336, 4809.302651115586, 4836.685615434994, 4849.393825771778, 4601.918549140958, 4734.218853889104, 4782.665213435061, 4790.640701947359, 4791.47293797682, 3762.2663580601793, 3778.8110594470304, 3812.142267989694, 3814.7138964577766, 3819.618038196186, 3496.4580490024086, 3597.244578638489, 3763.6424539668824, 3778.8110594470304, 3785.734998550103, 3692.778433294011, 3702.907427314325, 3731.421629647409, 3745.9625403745886, 3747.344214762419, 3732.2348918471785, 3736.2527494501073, 3738.345789486829, 3755.0800053986677, 3774.622537746231, 3344.147242453183, 3350.430426827445, 3351.1287065362935, 3351.581052368454, 3357.3285342931513, 4479.3281007848755, 4641.396618439581, 4661.277501987197, 4667.813287468497, 4715.882102947419, 4299.72051816634, 4302.935455968163, 4307.224699554079, 4309.35359696045, 4313.633341166004, 5003.474635163292, 5018.5232402921665, 5020.673656212334, 5027.195648696191, 5035.974820125876, 3531.6644918732677, 3594.7663401878795, 3630.7276954228537, 3652.214773823614, 3653.5433070866143, 5216.530512253916, 5217.5565076968505, 5228.640553456129, 5234.476552344773, 5245.606579506552, 3725.515682961198, 3727.3290807654885, 3736.2153947671295, 3736.7944763038295, 3739.0652336915773, 3774.471573979645, 3785.734998550075, 3786.8295926683363, 3786.8863934082024, 3791.109089363997, 4127.0, 4172.457580514574, 4184.476940382452, 4185.162967406531, 4192.413062171298, 4953.50464953509, 4961.975190124026, 4962.404511458648, 5016.473270306638, 5022.899542009212, 1199.111399545719, 1226.0934247245618, 1260.577706468325, 1270.1109223543372, 1286.899700755859, 876.8114855306139, 893.8882639670041, 896.1799953043023, 898.9280857531381, 901.9864702029474, 4338.587834155814, 4343.218220720238, 4349.26062569364, 4351.586599273064, 4357.782110894443, 5062.7215503146945, 5067.961067981055, 5078.644494885397, 5084.33903592538, 5089.262057001714, 4180.017695841475, 4423.646108311327, 4426.1369033038245, 4467.24056910326, 4473.7986790594505, 2719.9184024479296, 2731.426400455887, 2761.8066735328734, 2761.8757155928033, 2762.903298384547, 4753.619710423158, 4874.8781280467565, 4875.951670390883, 4882.780274887638, 4904.2643603459055, 5257.211418287211, 5270.152788464442, 5300.443453437333, 5301.708406037705, 5303.15149576066, 4807.230843065093, 4838.298446725134, 4838.806447742051, 4839.225723884161, 4900.72581128909, 4887.0, 5129.615278854027, 5166.689998600096, 5167.896642067213, 5193.169092945111, 5086.567641750493, 5095.464976177448, 5101.51535604117, 5110.642255042111, 5114.79540818378, 4891.73095479752, 4893.926591101207, 4896.853094407174, 4901.240307752304, 4908.288298196813, 3543.574771027493, 3635.690966267897, 3649.142451523943, 3650.799206043693, 3660.151623430257, 5143.871403214949, 5145.614078944169, 5166.682495963505, 5190.429052804264, 5198.454162312903, 4979.253112033224, 4985.376827896513, 4986.501349864984, 4994.300797888226, 4996.725180115129, 4770.379850619536, 4973.64279111591, 4981.925271120864, 4986.875328116681, 5003.374578177728, 4728.976355118202, 4826.755558665238, 4858.392700912386, 4907.981591321383, 4956.53782134264, 5001.749912504288, 5022.137381692401, 5029.145045342228, 5033.697978121324, 5051.74741262928, 4926.137925862888, 4929.334539837219, 4936.3829521309835, 4940.591931299562, 4953.7523123844385], "value": 3783.2458480339305, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [621.5898202255, 640.6540468147202, 680.6698751105713, 715.270424167349, 729.0922801112612, 12164.270143791367, 13165.144265622728, 13404.128731632438, 13432.2612256326, 13446.260455674934, 12918.095733298673, 12963.28701921394, 12972.805407918866, 12975.74048519029, 12983.935080324585, 739.1979702023298, 762.672051018062, 764.0907320288866, 768.2010708862776, 770.4067867741835, 613.0681364326222, 761.7905076104088, 763.9923600763976, 764.4190415284369, 765.6554550452319, 660.2374257732326, 787.5078076202374, 804.2279411764713, 814.8492528882147, 818.7298191596789, 800.6437135474716, 808.6320724070563, 808.7412028150994, 809.0291650019971, 809.2110192562263, 479.4749749024812, 588.7556663984441, 589.2369381650773, 589.5283772981641, 589.8377946064824, 10849.077828384598, 12429.875701242961, 12779.744405111938, 12780.808287875689, 13276.668083297995, 12686.682832929158, 13389.66525836852, 13415.0, 13563.45746170152, 13940.860591394057, 10665.146788256921, 10676.306040107369, 10783.5686572537, 10798.352098874093, 10808.325169442427, 3288.2765791526067, 3308.884189053396, 3360.983195084009, 3394.4568868980846, 3418.1283772637817, 64054.0, 64246.67876660633, 64516.67741661262, 65537.67231163813, 66831.33168668348, 10207.132393746466, 10224.6421375253, 10264.794704105876, 10398.948005259925, 10557.15542756593, 19077.332293369665, 19082.809171908382, 19109.235630574898, 19118.808811911982, 19135.425937221906, 6538.738450462023, 6619.106420633249, 6697.296783837724, 6848.760293389708, 6895.069165662671, 3478.0609235506777, 3492.8253587320532, 3516.701080408194, 3604.9098772530897, 3617.5839778425293, 2883.668378136499, 3013.352129292213, 3013.382256637384, 3013.789034767545, 3046.1014000869795, 489.12934975742525, 491.0253147502205, 495.5490503641741, 506.05873076078467, 512.2674575357275, 439.923013472641, 452.3305507848403, 585.2684144818976, 589.9587028907936, 605.6214865708932, 7669.309762121436, 8518.74443766688, 8713.825723485621, 8751.56242187888, 8974.910250897537, 468.24378628514705, 476.216623654094, 526.2238198657027, 534.6765207049679, 547.9972600136974, 3915.236528876899, 4003.559608443069, 4018.477597912253, 4048.352263637804, 4134.441850350225, 57352.42647573554, 57390.0, 57527.424725753044, 58311.50392929229, 78460.0, 91529.0, 92354.0, 92846.53576732209, 93706.53146734223, 95723.52138239265, 80277.0, 81856.59071704485, 81878.0, 82086.58956705178, 83015.58492207622, 7400.851982960418, 7431.36833369148, 7472.551646901204, 7601.277878601524, 7946.245106714853, 31026.379472410877, 36790.08024799402, 41632.791836040626, 42344.153116938105, 42846.0, 62520.687396562724, 62889.37110629018, 64364.67817660882, 65422.0, 65558.0, 10368.896311036793, 10640.62757803504, 10693.251472396858, 10853.186011049045, 11025.44872756375, 12510.0, 13128.34358282063, 13234.735305294033, 13360.131591446525, 13598.86401135976, 11819.172657914207, 12680.429380677708, 12959.740805184032, 13120.803187952379, 13322.933385333012, 8757.518336491554, 8947.686830960885, 12428.254304741744, 12700.047496437988, 12735.363231838557, 11492.195546311677, 12347.320897350732, 12396.31820249895, 12628.747425051264, 12646.13387909956, 9581.329306948726, 10048.09567138961, 10189.235807314333, 10202.795944080928, 10534.0, 8802.515861627671, 8986.460812351279, 9011.684591039282, 9347.439153650803, 10008.39949602997], "value": 6425.987500287669, "unit": "score"}], "name": "dromaeo_css", "value": 6334.127458827407, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 113.0, 114.0, 113.0, 114.0, 114.0, 123.0, 112.0, 113.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 131.0, 127.0, 171.0, 130.0, 125.0, 123.0, 125.0, 158.0, 126.0], "value": 138.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 163.0, 162.0, 161.0, 162.0, 164.0, 162.0, 163.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 77.0, 106.0, 77.0, 77.0, 76.0, 76.0, 112.0, 76.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 134.0, 84.0, 85.0, 83.0, 110.0, 83.0, 83.0, 122.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 120.0, 116.0, 117.0, 118.0, 117.0, 117.0, 117.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 146.0, 148.0, 147.0], "value": 146.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 69.0, 64.0, 62.0, 62.0, 63.0, 64.0], "value": 63.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 68.0, 67.0, 66.0, 66.0, 66.0, 67.0], "value": 66.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 85.0, 82.0, 97.0, 83.0, 88.0, 82.0, 88.0, 82.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 134.0, 140.0, 143.0, 142.0, 135.0, 139.0, 165.0, 148.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 172.0, 170.0, 172.0, 173.0, 172.0, 172.0, 174.0, 172.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 69.0, 70.0, 69.0, 76.0, 70.0, 70.0, 70.0, 72.0, 69.0], "value": 70.9, "unit": "score"}], "name": "kraken", "value": 1552.9, "alertThreshold": 2.0}]} 09:17:30 INFO - Return code: 0 09:17:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:17:30 INFO - # TBPL SUCCESS # 09:17:30 INFO - Running post-action listener: _resource_record_post_action 09:17:30 INFO - [mozharness: 2016-09-28 16:17:30.086822Z] Finished run-tests step (success) 09:17:30 INFO - Running post-run listener: _resource_record_post_run 09:17:30 INFO - Total resource usage - Wall time: 1202s; CPU: 13.0%; Read bytes: 4096; Write bytes: 391565312; Read time: 8; Write time: 269164 09:17:30 INFO - TinderboxPrint: CPU usage
13.0% 09:17:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 09:17:30 INFO - TinderboxPrint: I/O write bytes / time
391,565,312 / 269,164 09:17:30 INFO - TinderboxPrint: CPU idle
8,343.1 (87.0%) 09:17:30 INFO - TinderboxPrint: CPU user
1,198.1 (12.5%) 09:17:30 INFO - TinderboxPrint: Swap in / out
0 / 0 09:17:30 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99954688; Read time: 0; Write time: 104364 09:17:30 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 0; Write bytes: 291610624; Read time: 0; Write time: 164800 09:17:30 INFO - Running post-run listener: _upload_blobber_files 09:17:30 INFO - Blob upload gear active. 09:17:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:17:30 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:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:17:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:17:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:17:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:17:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:32 INFO - (blobuploader) - INFO - Done attempting. 09:17:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:17:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:17:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:36 INFO - (blobuploader) - INFO - Done attempting. 09:17:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:17:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:17:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:17:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:17:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:17:36 INFO - (blobuploader) - INFO - Done attempting. 09:17:36 INFO - (blobuploader) - INFO - Iteration through files over. 09:17:36 INFO - Return code: 0 09:17:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:17:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:17:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/210dbcd98279b428ae677c70014add9d3377a517e6afa6997a39a85364f63b02feb529a3ce977bd6691d315870d987c34e817d2e513caf76450e7ff9f7134c47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/130bf237102ab31ff7fcec0e985af398fbec89ba9fb7d390c43082a2124fdf7c2ba4c2b80eefdac5cfe5911ed4dad7dcefd800fe9fb52ce84b4d272de3db8ceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c1fc98bf4ec7931b035f1539ba4ad1444303f9c9b8df43fde2981227e478ce513d7ad2271836a671d1ac9e2b6f2856654b846df4efad2b52fa558dccf6b0de6e"} 09:17:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:17:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:17:36 INFO - Contents: 09:17:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/210dbcd98279b428ae677c70014add9d3377a517e6afa6997a39a85364f63b02feb529a3ce977bd6691d315870d987c34e817d2e513caf76450e7ff9f7134c47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/130bf237102ab31ff7fcec0e985af398fbec89ba9fb7d390c43082a2124fdf7c2ba4c2b80eefdac5cfe5911ed4dad7dcefd800fe9fb52ce84b4d272de3db8ceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c1fc98bf4ec7931b035f1539ba4ad1444303f9c9b8df43fde2981227e478ce513d7ad2271836a671d1ac9e2b6f2856654b846df4efad2b52fa558dccf6b0de6e"} 09:17:36 INFO - Running post-run listener: copy_logs_to_upload_dir 09:17:36 INFO - Copying logs to upload dir... 09:17:36 INFO - mkdir: /builds/slave/test/build/upload/logs 09:17:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.945044 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-09-28 09:17:36.715318) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 09:17:36.719242) ========= 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=31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/210dbcd98279b428ae677c70014add9d3377a517e6afa6997a39a85364f63b02feb529a3ce977bd6691d315870d987c34e817d2e513caf76450e7ff9f7134c47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/130bf237102ab31ff7fcec0e985af398fbec89ba9fb7d390c43082a2124fdf7c2ba4c2b80eefdac5cfe5911ed4dad7dcefd800fe9fb52ce84b4d272de3db8ceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c1fc98bf4ec7931b035f1539ba4ad1444303f9c9b8df43fde2981227e478ce513d7ad2271836a671d1ac9e2b6f2856654b846df4efad2b52fa558dccf6b0de6e"} build_url:https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011536 build_url: 'https://queue.taskcluster.net/v1/task/Zol7vPoKT9-eReFJQwyzhQ/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/210dbcd98279b428ae677c70014add9d3377a517e6afa6997a39a85364f63b02feb529a3ce977bd6691d315870d987c34e817d2e513caf76450e7ff9f7134c47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/130bf237102ab31ff7fcec0e985af398fbec89ba9fb7d390c43082a2124fdf7c2ba4c2b80eefdac5cfe5911ed4dad7dcefd800fe9fb52ce84b4d272de3db8ceb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/c1fc98bf4ec7931b035f1539ba4ad1444303f9c9b8df43fde2981227e478ce513d7ad2271836a671d1ac9e2b6f2856654b846df4efad2b52fa558dccf6b0de6e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 09:17:36.745526) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:17:36.745876) ========= 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=31acecf42b6a79ec49471d4a000001bb-1475077848.131154-1639681828 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004465 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:17:36.829683) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 09:17:36.830025) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 09:17:36.830320) ========= ========= Total master_lag: 0.17 =========