builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-016 starttime: 1471442844.42 results: success (0) buildid: 20160817043003 builduid: 06e9b86617fc4b61b1aa5902523e0539 revision: e232e2267d5f5fb009cb5d3d1a446e5b28ae8915 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.418156) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.418637) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.418929) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.436195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.436522) ========= 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=00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-17 07:07:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.7M=0s 2016-08-17 07:07:25 (66.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112820 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.569257) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.569593) ========= 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=00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015000 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.643068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:24.643589) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e232e2267d5f5fb009cb5d3d1a446e5b28ae8915 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev e232e2267d5f5fb009cb5d3d1a446e5b28ae8915 --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=00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-17 07:07:25,192 truncating revision to first 12 chars 2016-08-17 07:07:25,192 Setting DEBUG logging. 2016-08-17 07:07:25,192 attempt 1/10 2016-08-17 07:07:25,192 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/e232e2267d5f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-17 07:07:25,489 unpacking tar archive at: mozilla-inbound-e232e2267d5f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.686792 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:25.349496) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:25.349827) ========= script_repo_revision: e232e2267d5f5fb009cb5d3d1a446e5b28ae8915 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:25.350269) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:25.350547) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-17 07:07:25.361511) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-17 07:07:25.361893) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:07:25 INFO - MultiFileLogger online at 20160817 07:07:25 in /builds/slave/test 07:07:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 07:07:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:07:25 INFO - {'append_to_log': False, 07:07:25 INFO - 'base_work_dir': '/builds/slave/test', 07:07:25 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 07:07:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:07:25 INFO - 'branch': 'Mozilla-Inbound', 07:07:25 INFO - 'buildbot_json_path': 'buildprops.json', 07:07:25 INFO - 'config_files': ('talos/linux_config.py',), 07:07:25 INFO - 'default_actions': ('clobber', 07:07:25 INFO - 'read-buildbot-config', 07:07:25 INFO - 'download-and-extract', 07:07:25 INFO - 'populate-webroot', 07:07:25 INFO - 'create-virtualenv', 07:07:25 INFO - 'install', 07:07:25 INFO - 'run-tests'), 07:07:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:07:25 INFO - 'download_minidump_stackwalk': True, 07:07:25 INFO - 'download_symbols': 'ondemand', 07:07:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:07:25 INFO - 'tooltool.py': '/tools/tooltool.py', 07:07:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:07:25 INFO - '/tools/misc-python/virtualenv.py')}, 07:07:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:07:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:07:25 INFO - 'installer_path': 'installer.exe', 07:07:25 INFO - 'log_level': 'info', 07:07:25 INFO - 'log_name': 'talos', 07:07:25 INFO - 'log_to_console': True, 07:07:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:07:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:07:25 INFO - 'opt_config_files': (), 07:07:25 INFO - 'pip_index': False, 07:07:25 INFO - 'sps_profile': False, 07:07:25 INFO - 'sps_profile_interval': 0, 07:07:25 INFO - 'suite': 'dromaeojs', 07:07:25 INFO - 'system_bits': '32', 07:07:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:07:25 INFO - 'title': 'talos-linux64-ix-016', 07:07:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:07:25 INFO - 'use_talos_json': True, 07:07:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:07:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:07:25 INFO - 'work_dir': 'build'} 07:07:25 INFO - [mozharness: 2016-08-17 14:07:25.998778Z] Running clobber step. 07:07:25 INFO - Running pre-action listener: _resource_record_pre_action 07:07:25 INFO - Running main action method: clobber 07:07:25 INFO - rmtree: /builds/slave/test/build 07:07:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:07:26 INFO - Running post-action listener: _resource_record_post_action 07:07:26 INFO - [mozharness: 2016-08-17 14:07:26.456287Z] Finished clobber step (success) 07:07:26 INFO - [mozharness: 2016-08-17 14:07:26.456388Z] Running read-buildbot-config step. 07:07:26 INFO - Running pre-action listener: _resource_record_pre_action 07:07:26 INFO - Running main action method: read_buildbot_config 07:07:26 INFO - Using buildbot properties: 07:07:26 INFO - { 07:07:26 INFO - "project": "", 07:07:26 INFO - "product": "firefox", 07:07:26 INFO - "script_repo_revision": "production", 07:07:26 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 07:07:26 INFO - "repository": "", 07:07:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 07:07:26 INFO - "buildid": "20160817043003", 07:07:26 INFO - "pgo_build": "True", 07:07:26 INFO - "basedir": "/builds/slave/test", 07:07:26 INFO - "buildnumber": 1419, 07:07:26 INFO - "slavename": "talos-linux64-ix-016", 07:07:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:07:26 INFO - "platform": "ubuntu64_hw", 07:07:26 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 07:07:26 INFO - "branch": "mozilla-inbound", 07:07:26 INFO - "repo_path": "integration/mozilla-inbound", 07:07:26 INFO - "revision": "e232e2267d5f5fb009cb5d3d1a446e5b28ae8915", 07:07:26 INFO - "stage_platform": "linux64-pgo", 07:07:26 INFO - "builduid": "06e9b86617fc4b61b1aa5902523e0539", 07:07:26 INFO - "slavebuilddir": "test" 07:07:26 INFO - } 07:07:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 07:07:26 INFO - Running post-action listener: _resource_record_post_action 07:07:26 INFO - [mozharness: 2016-08-17 14:07:26.458887Z] Finished read-buildbot-config step (success) 07:07:26 INFO - [mozharness: 2016-08-17 14:07:26.458976Z] Running download-and-extract step. 07:07:26 INFO - Running pre-action listener: _resource_record_pre_action 07:07:26 INFO - Running main action method: download_and_extract 07:07:26 INFO - mkdir: /builds/slave/test/build/tests 07:07: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')]} 07:07:26 INFO - trying https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:07:26 INFO - Downloading https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:07:27 INFO - Downloaded 1361 bytes. 07:07:27 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 07:07:27 INFO - Using the following test package requirements: 07:07:27 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:07:27 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:07:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 07:07:27 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:07:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 07:07:27 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:07:27 INFO - u'jsshell-linux-x86_64.zip'], 07:07:27 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:07:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 07:07:27 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 07:07:27 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:07:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 07:07:27 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:07:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 07:07:27 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:07:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 07:07:27 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 07:07:27 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 07:07:27 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:07:27 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')]} 07:07:27 INFO - trying https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:07:27 INFO - Downloading https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:07:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:07:27 INFO - Downloaded 21903960 bytes. 07:07:27 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 07:07:32 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:32 INFO - trying https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:07:32 INFO - Downloading https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 07:07:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:07:32 INFO - Downloaded 21903960 bytes. 07:07:32 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 07:07:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:37 INFO - trying https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:07:37 INFO - Downloading https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 07:07:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:07:39 INFO - Downloaded 14413719 bytes. 07:07:39 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 07:07:39 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')]} 07:07:39 INFO - trying https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:07:39 INFO - Downloading https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:07:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:07:41 INFO - Downloaded 59592339 bytes. 07:07:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:07:41 INFO - mkdir: /builds/slave/test/properties 07:07:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:07:41 INFO - Writing to file /builds/slave/test/properties/build_url 07:07:41 INFO - Contents: 07:07:41 INFO - build_url:https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 07:07:41 INFO - Running post-action listener: _resource_record_post_action 07:07:41 INFO - Running post-action listener: set_extra_try_arguments 07:07:41 INFO - [mozharness: 2016-08-17 14:07:41.782141Z] Finished download-and-extract step (success) 07:07:41 INFO - [mozharness: 2016-08-17 14:07:41.782367Z] Running populate-webroot step. 07:07:41 INFO - Running pre-action listener: _resource_record_pre_action 07:07:41 INFO - Running main action method: populate_webroot 07:07:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:07:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:07:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:07:41 INFO - '--tppagecycles', 07:07:41 INFO - '7'], 07:07:41 INFO - 'tests': ['tsvgm']}}, 07:07:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:07:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:07:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:07:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:07:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:41 INFO - 'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:07:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:41 INFO - 'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['damp', 'tps']}, 07:07:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:41 INFO - 'tests': ['damp', 'tps']}, 07:07:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['dromaeo_dom']}, 07:07:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:07:41 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['basic_compositor_video']}, 07:07:41 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:07:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['a11yr', 07:07:41 INFO - 'ts_paint', 07:07:41 INFO - 'tpaint', 07:07:41 INFO - 'sessionrestore', 07:07:41 INFO - 'sessionrestore_no_auto_restore', 07:07:41 INFO - 'tabpaint']}, 07:07:41 INFO - 'other-e10s': {'tests': ['a11yr', 07:07:41 INFO - 'ts_paint', 07:07:41 INFO - 'tpaint', 07:07:41 INFO - 'sessionrestore', 07:07:41 INFO - 'sessionrestore_no_auto_restore', 07:07:41 INFO - 'tabpaint']}, 07:07:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:07:41 INFO - 'ts_paint', 07:07:41 INFO - 'tpaint', 07:07:41 INFO - 'sessionrestore', 07:07:41 INFO - 'sessionrestore_no_auto_restore', 07:07:41 INFO - 'tabpaint']}, 07:07:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:07:41 INFO - 'ts_paint', 07:07:41 INFO - 'tpaint', 07:07:41 INFO - 'sessionrestore', 07:07:41 INFO - 'sessionrestore_no_auto_restore', 07:07:41 INFO - 'tabpaint']}, 07:07:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['a11yr', 07:07:41 INFO - 'ts_paint', 07:07:41 INFO - 'tpaint', 07:07:41 INFO - 'sessionrestore', 07:07:41 INFO - 'sessionrestore_no_auto_restore', 07:07:41 INFO - 'tabpaint']}, 07:07:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['a11yr', 07:07:41 INFO - 'ts_paint', 07:07:41 INFO - 'tpaint', 07:07:41 INFO - 'sessionrestore', 07:07:41 INFO - 'sessionrestore_no_auto_restore', 07:07:41 INFO - 'tabpaint']}, 07:07:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['tsvgx', 07:07:41 INFO - 'tsvgr_opacity', 07:07:41 INFO - 'tart', 07:07:41 INFO - 'tscrollx', 07:07:41 INFO - 'cart']}, 07:07:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:07:41 INFO - 'tsvgr_opacity', 07:07:41 INFO - 'tart', 07:07:41 INFO - 'tscrollx', 07:07:41 INFO - 'cart']}, 07:07:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:41 INFO - 'talos_options': ['--disable-e10s'], 07:07:41 INFO - 'tests': ['tp5o']}, 07:07:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:41 INFO - 'tests': ['tp5o']}, 07:07:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:41 INFO - 'talos_options': ['--disable-e10s', 07:07:41 INFO - '--xperf_path', 07:07:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:41 INFO - 'tests': ['tp5n']}, 07:07:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:07:41 INFO - 'talos_options': ['--xperf_path', 07:07:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:07:41 INFO - 'tests': ['tp5n']}}, 07:07:41 INFO - 'talos.zip': {'path': '', 07:07:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:07:41 INFO - Running post-action listener: _resource_record_post_action 07:07:41 INFO - [mozharness: 2016-08-17 14:07:41.799922Z] Finished populate-webroot step (success) 07:07:41 INFO - [mozharness: 2016-08-17 14:07:41.800010Z] Running create-virtualenv step. 07:07:41 INFO - Running pre-action listener: _resource_record_pre_action 07:07:41 INFO - Running main action method: create_virtualenv 07:07:41 INFO - Creating virtualenv /builds/slave/test/build/venv 07:07:41 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 07:07:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:07:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:07:41 INFO - Using real prefix '/usr' 07:07:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:07:42 INFO - Installing distribute.............................................................................................................................................................................................done. 07:07:44 INFO - Installing pip.................done. 07:07:44 INFO - Return code: 0 07:07:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:44 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:07:44 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 07:07:44 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 07:07:44 INFO - Using env: {'DISPLAY': ':0', 07:07:44 INFO - 'HOME': '/home/cltbld', 07:07:44 INFO - 'LANG': 'en_US.UTF-8', 07:07:44 INFO - 'LANGUAGE': 'en_US:en', 07:07:44 INFO - 'LOGNAME': 'cltbld', 07:07:44 INFO - 'MAIL': '/var/mail/cltbld', 07:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:44 INFO - 'MOZ_NO_REMOTE': '1', 07:07:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:44 INFO - 'NO_EM_RESTART': '1', 07:07:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:44 INFO - 'PWD': '/builds/slave/test', 07:07:44 INFO - 'SHELL': '/bin/bash', 07:07:44 INFO - 'SHLVL': '1', 07:07:44 INFO - 'TERM': 'linux', 07:07:44 INFO - 'TMOUT': '86400', 07:07:44 INFO - 'USER': 'cltbld', 07:07:44 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:44 INFO - '_': '/tools/buildbot/bin/python'} 07:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:44 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 07:07:44 INFO - Cleaning up... 07:07:44 INFO - Return code: 0 07:07:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:44 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:07:44 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 07:07:44 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 07:07:44 INFO - Using env: {'DISPLAY': ':0', 07:07:44 INFO - 'HOME': '/home/cltbld', 07:07:44 INFO - 'LANG': 'en_US.UTF-8', 07:07:44 INFO - 'LANGUAGE': 'en_US:en', 07:07:44 INFO - 'LOGNAME': 'cltbld', 07:07:44 INFO - 'MAIL': '/var/mail/cltbld', 07:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:44 INFO - 'MOZ_NO_REMOTE': '1', 07:07:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:44 INFO - 'NO_EM_RESTART': '1', 07:07:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:44 INFO - 'PWD': '/builds/slave/test', 07:07:44 INFO - 'SHELL': '/bin/bash', 07:07:44 INFO - 'SHLVL': '1', 07:07:44 INFO - 'TERM': 'linux', 07:07:44 INFO - 'TMOUT': '86400', 07:07:44 INFO - 'USER': 'cltbld', 07:07:44 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:44 INFO - '_': '/tools/buildbot/bin/python'} 07:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:44 INFO - Downloading/unpacking psutil>=3.1.1 07:07:44 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 07:07:44 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 07:07:44 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 07:07:44 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 07:07:44 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 07:07:44 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 07:07:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:07:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:07:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:07:46 INFO - Installing collected packages: psutil 07:07:46 INFO - Running setup.py install for psutil 07:07:46 INFO - building 'psutil._psutil_linux' extension 07:07:46 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 07:07:47 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 07:07:47 INFO - building 'psutil._psutil_posix' extension 07:07:47 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 07:07:47 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 07:07:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:07:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:07:47 INFO - Successfully installed psutil 07:07:47 INFO - Cleaning up... 07:07:47 INFO - Return code: 0 07:07:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:47 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:07:47 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 07:07:47 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 07:07:47 INFO - Using env: {'DISPLAY': ':0', 07:07:47 INFO - 'HOME': '/home/cltbld', 07:07:47 INFO - 'LANG': 'en_US.UTF-8', 07:07:47 INFO - 'LANGUAGE': 'en_US:en', 07:07:47 INFO - 'LOGNAME': 'cltbld', 07:07:47 INFO - 'MAIL': '/var/mail/cltbld', 07:07:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:47 INFO - 'MOZ_NO_REMOTE': '1', 07:07:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:47 INFO - 'NO_EM_RESTART': '1', 07:07:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:47 INFO - 'PWD': '/builds/slave/test', 07:07:47 INFO - 'SHELL': '/bin/bash', 07:07:47 INFO - 'SHLVL': '1', 07:07:47 INFO - 'TERM': 'linux', 07:07:47 INFO - 'TMOUT': '86400', 07:07:47 INFO - 'USER': 'cltbld', 07:07:47 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:07:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:47 INFO - '_': '/tools/buildbot/bin/python'} 07:07:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:07:47 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 07:07:47 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 07:07:47 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 07:07:47 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 07:07:47 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 07:07:47 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 07:07:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:07:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:07:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:07:49 INFO - Installing collected packages: mozsystemmonitor 07:07:49 INFO - Running setup.py install for mozsystemmonitor 07:07:49 INFO - Successfully installed mozsystemmonitor 07:07:49 INFO - Cleaning up... 07:07:49 INFO - Return code: 0 07:07:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:07: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')]} 07:07:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07: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')]} 07:07:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:07:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:07:49 INFO - Using env: {'DISPLAY': ':0', 07:07:49 INFO - 'HOME': '/home/cltbld', 07:07:49 INFO - 'LANG': 'en_US.UTF-8', 07:07:49 INFO - 'LANGUAGE': 'en_US:en', 07:07:49 INFO - 'LOGNAME': 'cltbld', 07:07:49 INFO - 'MAIL': '/var/mail/cltbld', 07:07:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:49 INFO - 'MOZ_NO_REMOTE': '1', 07:07:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:49 INFO - 'NO_EM_RESTART': '1', 07:07:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:49 INFO - 'PWD': '/builds/slave/test', 07:07:49 INFO - 'SHELL': '/bin/bash', 07:07:49 INFO - 'SHLVL': '1', 07:07:49 INFO - 'TERM': 'linux', 07:07:49 INFO - 'TMOUT': '86400', 07:07:49 INFO - 'USER': 'cltbld', 07:07:49 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:07:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:49 INFO - '_': '/tools/buildbot/bin/python'} 07:07:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:49 INFO - Downloading/unpacking jsonschema==2.5.1 07:07:49 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 07:07:49 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 07:07:49 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 07:07:49 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 07:07:49 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 07:07:49 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 07:07:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:07:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:07:52 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 07:07:52 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 07:07:52 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 07:07:52 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 07:07:52 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 07:07:52 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 07:07:52 INFO - Downloading functools32-3.2.3-2.tar.gz 07:07:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:07:52 INFO - warning: no files found matching '*.txt' 07:07:52 INFO - no previously-included directories found matching 'build' 07:07:52 INFO - no previously-included directories found matching 'dist' 07:07:52 INFO - no previously-included directories found matching '.git*' 07:07:52 INFO - Installing collected packages: jsonschema, functools32 07:07:52 INFO - Running setup.py install for functools32 07:07:52 INFO - warning: no files found matching '*.txt' 07:07:52 INFO - no previously-included directories found matching 'build' 07:07:52 INFO - no previously-included directories found matching 'dist' 07:07:52 INFO - no previously-included directories found matching '.git*' 07:07:52 INFO - Successfully installed jsonschema functools32 07:07:52 INFO - Cleaning up... 07:07:53 INFO - Return code: 0 07:07:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:53 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:07:53 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 07:07:53 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 07:07:53 INFO - Using env: {'DISPLAY': ':0', 07:07:53 INFO - 'HOME': '/home/cltbld', 07:07:53 INFO - 'LANG': 'en_US.UTF-8', 07:07:53 INFO - 'LANGUAGE': 'en_US:en', 07:07:53 INFO - 'LOGNAME': 'cltbld', 07:07:53 INFO - 'MAIL': '/var/mail/cltbld', 07:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:53 INFO - 'MOZ_NO_REMOTE': '1', 07:07:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:53 INFO - 'NO_EM_RESTART': '1', 07:07:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:53 INFO - 'PWD': '/builds/slave/test', 07:07:53 INFO - 'SHELL': '/bin/bash', 07:07:53 INFO - 'SHLVL': '1', 07:07:53 INFO - 'TERM': 'linux', 07:07:53 INFO - 'TMOUT': '86400', 07:07:53 INFO - 'USER': 'cltbld', 07:07:53 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:53 INFO - '_': '/tools/buildbot/bin/python'} 07:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:07:53 INFO - Cleaning up... 07:07:53 INFO - Return code: 0 07:07:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:07:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:53 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:07:53 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 07:07:53 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 07:07:53 INFO - Using env: {'DISPLAY': ':0', 07:07:53 INFO - 'HOME': '/home/cltbld', 07:07:53 INFO - 'LANG': 'en_US.UTF-8', 07:07:53 INFO - 'LANGUAGE': 'en_US:en', 07:07:53 INFO - 'LOGNAME': 'cltbld', 07:07:53 INFO - 'MAIL': '/var/mail/cltbld', 07:07:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:53 INFO - 'MOZ_NO_REMOTE': '1', 07:07:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:53 INFO - 'NO_EM_RESTART': '1', 07:07:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:53 INFO - 'PWD': '/builds/slave/test', 07:07:53 INFO - 'SHELL': '/bin/bash', 07:07:53 INFO - 'SHLVL': '1', 07:07:53 INFO - 'TERM': 'linux', 07:07:53 INFO - 'TMOUT': '86400', 07:07:53 INFO - 'USER': 'cltbld', 07:07:53 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:07:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:53 INFO - '_': '/tools/buildbot/bin/python'} 07:07:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:53 INFO - Downloading/unpacking blobuploader==1.2.4 07:07:53 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 07:07:53 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 07:07:53 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 07:07:53 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 07:07:53 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 07:07:53 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 07:07:55 INFO - Downloading blobuploader-1.2.4.tar.gz 07:07:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:07:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:07:55 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 07:07:55 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 07:07:55 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 07:07:55 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 07:07:55 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 07:07:55 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 07:07:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:07:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:07:55 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 07:07:55 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 07:07:55 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 07:07:55 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 07:07:55 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 07:07:55 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 07:07:56 INFO - Downloading docopt-0.6.1.tar.gz 07:07:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:07:56 INFO - Installing collected packages: blobuploader, requests, docopt 07:07:56 INFO - Running setup.py install for blobuploader 07:07:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:07:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:07:56 INFO - Running setup.py install for requests 07:07:56 INFO - Running setup.py install for docopt 07:07:56 INFO - Successfully installed blobuploader requests docopt 07:07:56 INFO - Cleaning up... 07:07:56 INFO - Return code: 0 07:07:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:07:56 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')]} 07:07:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:07:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:56 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')]} 07:07:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:07:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:07:56 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:07:56 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 07:07:56 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 07:07:56 INFO - Using env: {'DISPLAY': ':0', 07:07:56 INFO - 'HOME': '/home/cltbld', 07:07:56 INFO - 'LANG': 'en_US.UTF-8', 07:07:56 INFO - 'LANGUAGE': 'en_US:en', 07:07:56 INFO - 'LOGNAME': 'cltbld', 07:07:56 INFO - 'MAIL': '/var/mail/cltbld', 07:07:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:07:56 INFO - 'MOZ_NO_REMOTE': '1', 07:07:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:07:56 INFO - 'NO_EM_RESTART': '1', 07:07:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:07:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:07:56 INFO - 'PWD': '/builds/slave/test', 07:07:56 INFO - 'SHELL': '/bin/bash', 07:07:56 INFO - 'SHLVL': '1', 07:07:56 INFO - 'TERM': 'linux', 07:07:56 INFO - 'TMOUT': '86400', 07:07:56 INFO - 'USER': 'cltbld', 07:07:56 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:07:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:07:56 INFO - '_': '/tools/buildbot/bin/python'} 07:07:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:07:57 INFO - Running setup.py (path:/tmp/pip-4OHpNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:07:57 INFO - Running setup.py (path:/tmp/pip-JbEyjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:07:57 INFO - Running setup.py (path:/tmp/pip-TU42j3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:07:57 INFO - Running setup.py (path:/tmp/pip-FaFs_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:07:57 INFO - Running setup.py (path:/tmp/pip-RE9Awf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:07:57 INFO - Running setup.py (path:/tmp/pip-BKphxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:07:57 INFO - Running setup.py (path:/tmp/pip-QMG5s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:07:57 INFO - Running setup.py (path:/tmp/pip-UO8Lxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:07:57 INFO - Running setup.py (path:/tmp/pip-Zo0mly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:07:57 INFO - Running setup.py (path:/tmp/pip-PpH8b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:07:57 INFO - Running setup.py (path:/tmp/pip-4c5SJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:07:57 INFO - Running setup.py (path:/tmp/pip-IX_gdd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:07:57 INFO - Running setup.py (path:/tmp/pip-bkG7Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:07:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:07:58 INFO - Running setup.py (path:/tmp/pip-OxRAcq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:58 INFO - Running setup.py (path:/tmp/pip-9ctEEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:07:58 INFO - Running setup.py (path:/tmp/pip-6B7ndb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:07:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:07:58 INFO - Running setup.py (path:/tmp/pip-Bk49u5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:07:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:07:58 INFO - Running setup.py install for manifestparser 07:07:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:07:58 INFO - Running setup.py install for mozcrash 07:07:58 INFO - Running setup.py install for mozdebug 07:07:58 INFO - Running setup.py install for mozdevice 07:07:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:07:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:07:58 INFO - Running setup.py install for mozfile 07:07:58 INFO - Running setup.py install for mozhttpd 07:07:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:07:58 INFO - Running setup.py install for mozinfo 07:07:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:07:58 INFO - Running setup.py install for mozInstall 07:07:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Running setup.py install for mozleak 07:07:59 INFO - Running setup.py install for mozlog 07:07:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Running setup.py install for moznetwork 07:07:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Running setup.py install for mozprocess 07:07:59 INFO - Running setup.py install for mozprofile 07:07:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Running setup.py install for mozrunner 07:07:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:07:59 INFO - Running setup.py install for mozscreenshot 07:07:59 INFO - Running setup.py install for moztest 07:07:59 INFO - Running setup.py install for mozversion 07:08:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:08:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:08:00 INFO - Cleaning up... 07:08:00 INFO - Return code: 0 07:08:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:08:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:00 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:08:00 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 07:08:00 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 07:08:00 INFO - Using env: {'DISPLAY': ':0', 07:08:00 INFO - 'HOME': '/home/cltbld', 07:08:00 INFO - 'LANG': 'en_US.UTF-8', 07:08:00 INFO - 'LANGUAGE': 'en_US:en', 07:08:00 INFO - 'LOGNAME': 'cltbld', 07:08:00 INFO - 'MAIL': '/var/mail/cltbld', 07:08:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:00 INFO - 'MOZ_NO_REMOTE': '1', 07:08:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:00 INFO - 'NO_EM_RESTART': '1', 07:08:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:00 INFO - 'PWD': '/builds/slave/test', 07:08:00 INFO - 'SHELL': '/bin/bash', 07:08:00 INFO - 'SHLVL': '1', 07:08:00 INFO - 'TERM': 'linux', 07:08:00 INFO - 'TMOUT': '86400', 07:08:00 INFO - 'USER': 'cltbld', 07:08:00 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:08:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:00 INFO - '_': '/tools/buildbot/bin/python'} 07:08:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:08:00 INFO - Running setup.py (path:/tmp/pip-Q1lpIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:08:00 INFO - Running setup.py (path:/tmp/pip-UQoDa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:08:00 INFO - Running setup.py (path:/tmp/pip-BsTPx1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:08:00 INFO - Running setup.py (path:/tmp/pip-keglCC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:08:00 INFO - Running setup.py (path:/tmp/pip-9RHXmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:08:00 INFO - Running setup.py (path:/tmp/pip-1G97U3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:08:00 INFO - Running setup.py (path:/tmp/pip-kyK1uI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:08:00 INFO - Running setup.py (path:/tmp/pip-0mWpVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:08:00 INFO - Running setup.py (path:/tmp/pip-GvohWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:08:00 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)) 07:08:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:08:00 INFO - Running setup.py (path:/tmp/pip-YNxT1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:08:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:08:01 INFO - Running setup.py (path:/tmp/pip-twHBGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:08:01 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)) 07:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:08:01 INFO - Running setup.py (path:/tmp/pip-B4IamN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:08:01 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)) 07:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:08:01 INFO - Running setup.py (path:/tmp/pip-_prPYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:08:01 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)) 07:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:08:01 INFO - Running setup.py (path:/tmp/pip-bJE6Ec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:08:01 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)) 07:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:01 INFO - Running setup.py (path:/tmp/pip-DbfTOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:08:01 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)) 07:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:08:01 INFO - Running setup.py (path:/tmp/pip-_e1i3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:08:01 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)) 07:08:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:08:01 INFO - Running setup.py (path:/tmp/pip-dWC4Dm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:08:01 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)) 07:08:01 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)) 07:08:01 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)) 07:08:01 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)) 07:08:01 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)) 07:08:01 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)) 07:08:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:08:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:08:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:08:03 INFO - Downloading blessings-1.6.tar.gz 07:08:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:08:03 INFO - Installing collected packages: blessings 07:08:03 INFO - Running setup.py install for blessings 07:08:03 INFO - Successfully installed blessings 07:08:03 INFO - Cleaning up... 07:08:03 INFO - Return code: 0 07:08:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:08:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:08:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:08:03 INFO - Reading from file tmpfile_stdout 07:08:03 INFO - Current package versions: 07:08:03 INFO - argparse == 1.2.1 07:08:03 INFO - blessings == 1.6 07:08:03 INFO - blobuploader == 1.2.4 07:08:03 INFO - docopt == 0.6.1 07:08:03 INFO - functools32 == 3.2.3-2 07:08:03 INFO - jsonschema == 2.5.1 07:08:03 INFO - manifestparser == 1.1 07:08:03 INFO - mozInstall == 1.12 07:08:03 INFO - mozcrash == 0.17 07:08:03 INFO - mozdebug == 0.1 07:08:03 INFO - mozdevice == 0.48 07:08:03 INFO - mozfile == 1.2 07:08:03 INFO - mozhttpd == 0.7 07:08:03 INFO - mozinfo == 0.9 07:08:03 INFO - mozleak == 0.1 07:08:03 INFO - mozlog == 3.3 07:08:03 INFO - moznetwork == 0.27 07:08:03 INFO - mozprocess == 0.23 07:08:03 INFO - mozprofile == 0.28 07:08:03 INFO - mozrunner == 6.12 07:08:03 INFO - mozscreenshot == 0.1 07:08:03 INFO - mozsystemmonitor == 0.3 07:08:03 INFO - moztest == 0.7 07:08:03 INFO - mozversion == 1.4 07:08:03 INFO - psutil == 3.1.1 07:08:03 INFO - requests == 1.2.3 07:08:03 INFO - wsgiref == 0.1.2 07:08:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:08:03 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')]} 07:08:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:03 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')]} 07:08:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:03 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:08:03 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 07:08:03 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 07:08:03 INFO - Using env: {'DISPLAY': ':0', 07:08:03 INFO - 'HOME': '/home/cltbld', 07:08:03 INFO - 'LANG': 'en_US.UTF-8', 07:08:03 INFO - 'LANGUAGE': 'en_US:en', 07:08:03 INFO - 'LOGNAME': 'cltbld', 07:08:03 INFO - 'MAIL': '/var/mail/cltbld', 07:08:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:03 INFO - 'MOZ_NO_REMOTE': '1', 07:08:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:03 INFO - 'NO_EM_RESTART': '1', 07:08:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:03 INFO - 'PWD': '/builds/slave/test', 07:08:03 INFO - 'SHELL': '/bin/bash', 07:08:03 INFO - 'SHLVL': '1', 07:08:03 INFO - 'TERM': 'linux', 07:08:03 INFO - 'TMOUT': '86400', 07:08:03 INFO - 'USER': 'cltbld', 07:08:03 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:08:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:03 INFO - '_': '/tools/buildbot/bin/python'} 07:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 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)) 07:08:04 INFO - Cleaning up... 07:08:04 INFO - Return code: 0 07:08:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:08:04 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')]} 07:08:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:08:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:04 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')]} 07:08:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:08:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:08:04 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 0x7f91ce3d0f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c91230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, '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': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', '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 07:08:04 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 07:08:04 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 07:08:04 INFO - Using env: {'DISPLAY': ':0', 07:08:04 INFO - 'HOME': '/home/cltbld', 07:08:04 INFO - 'LANG': 'en_US.UTF-8', 07:08:04 INFO - 'LANGUAGE': 'en_US:en', 07:08:04 INFO - 'LOGNAME': 'cltbld', 07:08:04 INFO - 'MAIL': '/var/mail/cltbld', 07:08:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:04 INFO - 'MOZ_NO_REMOTE': '1', 07:08:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:04 INFO - 'NO_EM_RESTART': '1', 07:08:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:04 INFO - 'PWD': '/builds/slave/test', 07:08:04 INFO - 'SHELL': '/bin/bash', 07:08:04 INFO - 'SHLVL': '1', 07:08:04 INFO - 'TERM': 'linux', 07:08:04 INFO - 'TMOUT': '86400', 07:08:04 INFO - 'USER': 'cltbld', 07:08:04 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:08:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:04 INFO - '_': '/tools/buildbot/bin/python'} 07:08:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:08:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:08:04 INFO - Cleaning up... 07:08:04 INFO - Return code: 0 07:08:04 INFO - Running post-action listener: _resource_record_post_action 07:08:04 INFO - Running post-action listener: _start_resource_monitoring 07:08:04 INFO - Starting resource monitoring. 07:08:04 INFO - [mozharness: 2016-08-17 14:08:04.386043Z] Finished create-virtualenv step (success) 07:08:04 INFO - [mozharness: 2016-08-17 14:08:04.386505Z] Running install step. 07:08:04 INFO - Running pre-action listener: _resource_record_pre_action 07:08:04 INFO - Running main action method: install 07:08:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:08:04 INFO - Reading from file tmpfile_stdout 07:08:04 INFO - Detecting whether we're running mozinstall >=1.0... 07:08:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:08:04 INFO - Reading from file tmpfile_stdout 07:08:04 INFO - Output received: 07:08:04 INFO - Usage: mozinstall [options] installer 07:08:04 INFO - Options: 07:08:04 INFO - -h, --help show this help message and exit 07:08:04 INFO - -d DEST, --destination=DEST 07:08:04 INFO - Directory to install application into. [default: 07:08:04 INFO - "/builds/slave/test"] 07:08:04 INFO - --app=APP Application being installed. [default: firefox] 07:08:04 INFO - mkdir: /builds/slave/test/build/application 07:08:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:08:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:08:21 INFO - Reading from file tmpfile_stdout 07:08:21 INFO - Output received: 07:08:21 INFO - /builds/slave/test/build/application/firefox/firefox 07:08:21 INFO - Running post-action listener: _resource_record_post_action 07:08:21 INFO - [mozharness: 2016-08-17 14:08:21.632821Z] Finished install step (success) 07:08:21 INFO - [mozharness: 2016-08-17 14:08:21.633132Z] Running run-tests step. 07:08:21 INFO - Running pre-action listener: _resource_record_pre_action 07:08:21 INFO - Running main action method: run_tests 07:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:08:21 INFO - Python 2.7.3 07:08:21 INFO - Return code: 0 07:08:21 INFO - grabbing minidump binary from tooltool 07:08:21 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')]} 07:08:21 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 0x2c8b090>, '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 0x2c8e9b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c8ee40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:08:21 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 07:08:21 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 07:08:21 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 07:08:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:08:21 INFO - Return code: 0 07:08:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:08:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:08:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:08:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:08:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:08:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 07:08:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-016 --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 07:08:21 INFO - Using env: {'DISPLAY': ':0', 07:08:21 INFO - 'HOME': '/home/cltbld', 07:08:21 INFO - 'LANG': 'en_US.UTF-8', 07:08:21 INFO - 'LANGUAGE': 'en_US:en', 07:08:21 INFO - 'LOGNAME': 'cltbld', 07:08:21 INFO - 'MAIL': '/var/mail/cltbld', 07:08:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:08:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:08:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:08:21 INFO - 'MOZ_NO_REMOTE': '1', 07:08:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:08:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:08:21 INFO - 'NO_EM_RESTART': '1', 07:08:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:08:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:08:21 INFO - 'PWD': '/builds/slave/test', 07:08:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:08:21 INFO - 'SHELL': '/bin/bash', 07:08:21 INFO - 'SHLVL': '1', 07:08:21 INFO - 'TERM': 'linux', 07:08:21 INFO - 'TMOUT': '86400', 07:08:21 INFO - 'USER': 'cltbld', 07:08:21 INFO - 'XDG_SESSION_COOKIE': '00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241', 07:08:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:08:21 INFO - '_': '/tools/buildbot/bin/python'} 07:08:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-016', '--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 07:08:21 INFO - mozversion application_buildid: 20160817043003 07:08:21 INFO - mozversion application_changeset: e232e2267d5f5fb009cb5d3d1a446e5b28ae8915 07:08:21 INFO - mozversion application_display_name: Nightly 07:08:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:08:21 INFO - mozversion application_name: Firefox 07:08:21 INFO - mozversion application_remotingname: firefox 07:08:21 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:08:21 INFO - mozversion application_vendor: Mozilla 07:08:21 INFO - mozversion application_version: 51.0a1 07:08:21 INFO - mozversion platform_buildid: 20160817043003 07:08:21 INFO - mozversion platform_changeset: e232e2267d5f5fb009cb5d3d1a446e5b28ae8915 07:08:21 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 07:08:21 INFO - mozversion platform_version: 51.0a1 07:08:21 INFO - using testdate: 1471442901 07:08:21 INFO - actual date: 1471442901 07:08:21 INFO - starting webserver on 'localhost:43169' 07:08:21 INFO - SUITE-START | Running 2 tests 07:08:21 INFO - TEST-START | dromaeo_css 07:08:21 INFO - operating with platform_type : linux_ 07:08:21 INFO - Initialising browser for dromaeo_css test... 07:08:21 INFO - TEST-INFO | started process 10930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwiS94z/profile http://localhost:43169/getInfo.html) 07:08:25 INFO - PROCESS | 10930 | __metrics Screen width/height:1600/1200 07:08:25 INFO - PROCESS | 10930 | colorDepth:24 07:08:25 INFO - PROCESS | 10930 | Browser inner width/height: 1024/697 07:08:25 INFO - PROCESS | 10930 | __metrics 07:08:26 INFO - PROCESS | 10930 | 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" 07:08:26 INFO - PROCESS | 10930 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:08:27 INFO - PROCESS | 10930 | console.error: PushService: 07:08:27 INFO - PROCESS | 10930 | stateChangeProcessEnqueue: Error transitioning state 07:08:27 INFO - PROCESS | 10930 | UnknownError 07:08:27 INFO - TEST-INFO | 10930: exit 0 07:08:27 INFO - Browser initialized. 07:08:27 INFO - Running cycle 1/1 for dromaeo_css test... 07:08:27 INFO - TEST-INFO | started process 11013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwiS94z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:08:39 INFO - PROCESS | 11013 | RSS: Main: 156090368 07:08:39 INFO - PROCESS | 11013 | 07:09:31 INFO - PROCESS | 11013 | 1471442971076 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] 07:11:40 INFO - PROCESS | 11013 | Cycle 1(1): loaded http://localhost:43169/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43169/tests/dromaeo/cssquery-ext.html) 07:11:41 INFO - PROCESS | 11013 | RSS: Main: 259223552 07:11:41 INFO - PROCESS | 11013 | 07:14:43 INFO - PROCESS | 11013 | Cycle 1(1): loaded http://localhost:43169/tests/dromaeo/cssquery-ext.html (next: http://localhost:43169/tests/dromaeo/cssquery-jquery.html) 07:14:44 INFO - PROCESS | 11013 | RSS: Main: 254726144 07:14:44 INFO - PROCESS | 11013 | 07:17:46 INFO - PROCESS | 11013 | Cycle 1(1): loaded http://localhost:43169/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43169/tests/dromaeo/cssquery-mootools.html) 07:17:47 INFO - PROCESS | 11013 | RSS: Main: 184844288 07:17:47 INFO - PROCESS | 11013 | 07:20:49 INFO - PROCESS | 11013 | Cycle 1(1): loaded http://localhost:43169/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43169/tests/dromaeo/cssquery-prototype.html) 07:20:49 INFO - PROCESS | 11013 | RSS: Main: 233893888 07:20:49 INFO - PROCESS | 11013 | 07:23:52 INFO - PROCESS | 11013 | Cycle 1(1): loaded http://localhost:43169/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43169/tests/dromaeo/cssquery-yui.html) 07:23:53 INFO - PROCESS | 11013 | RSS: Main: 184614912 07:23:53 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | Cycle 1(1): loaded http://localhost:43169/tests/dromaeo/cssquery-yui.html (next: http://localhost:43169/tests/dromaeo/cssquery-dojo.html) 07:26:56 INFO - PROCESS | 11013 | RSS: Main: 274890752 07:26:56 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | __start_tp_report 07:26:56 INFO - PROCESS | 11013 | _x_x_mozilla_page_load 07:26:56 INFO - PROCESS | 11013 | _x_x_mozilla_page_load_details 07:26:56 INFO - PROCESS | 11013 | |i|pagename|runs| 07:26:56 INFO - PROCESS | 11013 | |0;dojo.html;3031.530112832512;3032.9839503766234;3039.544068389742;3056.899516174177;3057.587225724528;5175.741212939347;5191.818286359978;5271.156614941606;5386.622936394454;5423.430539793319;5242.18746094356;5261.842144735664;5290.947090529094;5299.894002119955;5355.518003379695;4775.785089670956;4809.615230781529;4831.927521087169;4835.008823191238;5012.373453318335;4897.7795999180125;4906.362172917516;4956.900861982758;4969.676970996892;4978.925316120243;4694.131585656647;4845.030993801254;4879.316895634614;4905.337779399771;4912.8771780705765;4822.903541929142;4823.807047718087;4825.469198388175;4902.338184345103;4940.110780059588;4833.29917162013;4862.051899879525;4863.076015557032;4871.220604703266;4918.680285781413;5157.226416037587;5207.427183009866;5253.212018197263;5268.157094864841;5326.92009619856;5763.711814409263;5763.8270851874095;5777.220075289824;5789.855253618651;5793.304803423574;5753.165790960287;5783.479486846162;5796.62321949072;5810.24466819315;5823;5183.81856635016;5323.574114070904;5359.14253719403;5375.543078838343;5417.404085550586;5944.999999999914;5950.494207992283;5951.4941229995065;5962.970185149134;5963.344032156459;5697.6296540724725;5730;5735.684537350402;5735.971320143372;5738.569607279471;5517.282753242133;5606.971965140148;5669.489745922804;5806.912896306559;5820.621659592113;5683.914741278884;5684.516816070598;5687.260656114679;5728;5741.282339707536;5626.2967129108865;5655;5659.094544872801;5662.150677398423;5721.914171287434;4318.913621727548;4320.308750599889;4346.6522678185665;4385.846495372647;4420.071784925201;3899;3899.883003509899;3909.706771992112;3931.7640941543027;3936.3505021671676;5332.866678333073;5339.973300133553;5340.359156901197;5342.091844386464;5386.757595908193;5590.608657393944;5684.63049901755;5716;5732.398098199629;5737.483626473638;5374.247605335257;5379.004884096455;5379.596530260246;5457.345118585795;5462.590305727086;4946.480619534969;4962.932969411594;4966.528179822911;4967.850964471072;5010.223415370623;5690.345610254791;5767.711614419179;5789.044807606698;5958.612690175125;5962.821115366546;5671.17767923657;5688.658680479184;5727.627704199213;5732.484076433141;5736.139579063174;5829;5845.240118784531;5848.970755146197;5853.268341457318;5853.2976042875125;5453.454654534515;5535.557155427476;5550.333959924835;5568;5598.720063996702;5687.971560142173;5720.3421606515785;5760.222369979999;5792.507636850914;5818.33089194748;5598.69207193592;5601.215829783915;5606.887862242814;5608.971955140199;5613.158026295925;5415.675059496442;5523.116301391758;5598.160275958476;5607.130894711327;5610.803621873215;5487.094629386106;5538;5562.527185189304;5593.216949627137;5594.328680558359;5656.717164141859;5665.688387138747;5712.457316554921;5714.171445140514;5714.685692286964;5317.601179911445;5366.382865970463;5463.235147079333;5524.944750552604;5544.584156188222 07:26:56 INFO - PROCESS | 11013 | |1;ext.html;568.7355379748416;592.2655906675722;594.5422025040718;614.0114415790578;616.5499185594518;32096;42990.570094299044;44388.77805610975;44409;44502.33246501305;45205.54794452054;45738.771306143426;45824.083518329695;45947;45955.08089838201;8740.868886966684;8848.51333176675;8859.689910853122;8894.199522043014;8894.644214231423;8718.041015488292;8851.77870553238;8853.778655533599;8855.73432797017;8858.468491890475;8878.866816997717;8932.062133475989;8934.419262747933;8950.955245223764;8960.059193784657;8840.690575829814;8859.689910853122;8892.110788921122;8911.866322005175;8912.55437228136;8780.780480487974;8929.151730585583;8929.196372326522;8932.464052156827;8936.553172341422;43309;43634.34548481775;43641.78179109116;43665.56334436647;43668.563314367086;3190.6171259448784;3307.5534802801553;3650.7627004244905;3662.121090938182;3848.8460461581785;32665.183370415692;33035.6696433035;33135;33508.32983340344;34207.82896085529;4398.560143985608;4462.576055274743;4593.047954619306;4654.394928659252;4686.882827929295;338563;339198.30400848086;339685;339787;340785;35316;36528.26943461169;55854.72072639611;56356.718216408655;56453.71773141108;37796.244075118346;37838.43242351367;38044;38052.429213561816;38114.80942595269;14627.195504247156;14692.926535367254;14725.705485890436;14729.484468043567;14736.48422305214;11896.762064758655;12050.39748012596;12088.274703517816;12092.153549251469;12097.75804483923;8596.312295016383;8615.396922215385;8691.087435819274;8712.607932643046;8736.082711315348;3379.847906844198;3389.8983030509125;3401.6428275031262;3404.540387047766;3413.453847384407;1016.7813920007234;1081.313366012572;1130.6042884990188;1141.560499207811;1154.1516985016005;32202.83898580492;32783;32919.17702057468;33051.504227436606;33069;949.767307009787;1062.3785085724783;1109.4951796932376;1157.5427706056062;1174.9706257343464;16225.918870405572;16828.831711682727;17025.914870425568;17043.65912681764;17054.573635659206;566875;571736;572046;572675;573604;59627;59953.700231498566;59990;60037.39962600405;60144;1215.070471089617;1310.8492523359935;1337.2377744685436;1347.4542810161704;1379.903406761517;31115.377692446476;33429.997100087036;33566.66433335733;33663.83168084144;33887;49631.503684962685;49831.501684982686;50086;50145.74927125341;50224.748876255384;386872;388113;388148;389833;389875;28586.14241572756;28763;29705.702942970292;29733.40533189281;30844.0746777597;48124;48144;48161;48434.27348589845;49183.26225106555;40080;40512;41864;41992.79003604963;43224.783876080415;40671.38992915168;40787.592124079565;41063.589364105974;42119.36820947627;42252.57747422609;40184;40440.79779601201;41230.38154427626;41507;41633;32727;36563.268734624624;36575.45136822897;36890.63109368872;37426.43860342043;27674.308142296602;28207.294817629725;28309.858450707616;28685;28732.569011464428 07:26:56 INFO - PROCESS | 11013 | |2;jquery.html;379.7930128080196;400.7575416872792;402.8811500607321;406.13290624516657;427.1052145754645;13118.832956491304;19266.807331926677;19300.51748706283;19325.51686207842;19326.03369831508;11737.413129343542;12270.938645306762;12294.139410241287;12304.5693400731;12454.937725311362;3916.902077448058;5315.654482458637;5462.6995515246645;5468.6171967962255;5716.914246286309;5769.16347129666;6014.548908831855;6017.127516510103;6019;6019.5485338599565;5903.173555702205;5990.850228744294;6505.0892874997535;6590.275069742324;6595.703193356287;6023.6385816851125;6573.37552932473;6627.37039981201;6640;6657;5822.854428639275;5848.97075514624;5855.238818953551;5855.502282306009;5858.12128180772;14310.713785724227;15319.46381876629;23093.422664433354;23694.881525592435;23855.522889542284;2273.4316420894775;2276.362618466832;2336.6378211377264;2396.2571602803187;2509.6110102934076;10128;10630.893691063067;10643.627473038485;11355.261907975955;14266.500672476413;1881.7365568820376;1900.6293772714325;1961.1076959983172;1985.811347921945;1996.2015193922402;80482.19517804806;81534.59232703799;83020.16979830185;83651.5817420909;83723.16276837215;10757.731056723644;11027.2280940335;11265.26775759573;11374.180131179137;11516.1362897783;15849.683006339808;15866.365345386055;15875.52373428799;15911.920440397724;15917.602059948362;8074.878876816852;8174.141715119827;8188.918110818935;8328.167183281745;8338.666453341919;7774.533527988338;7791.4935529190425;8001.399895007898;8006.239407256301;8027.237412445808;4782.641301902371;4862.222044472938;4872.049950259665;4889.315495830587;4950.108980383494;1914.5883635018258;1946.6593346164364;1983.9404817855489;1996.9301074462614;2018.1120307064843;876.9693060742843;937.6015193542717;946.1484663802632;1032.746976990642;1034.6895931220604;20098.296559620343;20423.285185018452;20494.795052049587;20500.07749651269;20503.487412814502;955.6225291010012;986.6102889358663;987.6987518806833;1028.0336483705294;1064.0583083970741;11202.215844890943;11343.716407089889;11374.601888933848;11389.487473063731;11457.255278406878;476039;492839;498589;498647;501828;88791.55604221938;89212;90829.54585227164;91144.54427727818;91251;951.286535098676;974.1573538888952;994.8213412626325;995.2933417273816;996.4121168510469;17244.568885777957;17742.29030838752;18261.817381826542;18267.634647307244;18301.084616037886;41760;42116.78941605272;43084;43285.13429731325;43737.343939841565;57413;57540.71229643825;57980.71009644925;58562.7071864638;59978.700106499185;8563.743087707378;8571.871421928801;8642.870356944777;8735.388522803343;9076.249026233418;10900.618478353219;13145.737085258432;13578.32108394596;13633;13758.862411376158;11488.827667584826;11500.712482188012;11906.166568340133;12091.637250882135;12580.99352051852;11683.532658693557;11735.354555499529;11739.11956603241;11866.347350895783;12238.143329966817;11691.590794322157;11710.180287379802;11743.17797754149;12101.152919295564;12520.561780337644;7490.70037198506;7860.449768516378;8317.708880189406;8767.60545775429;8929;11575.247608905394;12114.818277726017;12125.81811272814;12200.450979706113;12377.257364558156 07:26:56 INFO - PROCESS | 11013 | |3;mootools.html;1301;1456.7013762178753;1504.601280660625;1597.6964376768412;1808.0869161073656;8613.784655383619;9037.819243615131;9069.138431848973;9356.719298421058;9371.578278977446;9165.679201227958;9279.164875161234;9289;9289.349745517817;9290.163885250326;2046.4167712202025;2297.8046866016407;2364.9408764780887;2399.3401814500976;2400.3639035655565;2361.102780943239;2387.8089752819733;2389.82076344274;2393.234165067179;2397.832151749378;2316.328264803205;2347.4014126397747;2352.141468364052;2358.6815779869758;2389.9761002390014;2284;2348.283773449106;2359.0799588160544;2360.6104992676187;2382.833201675883;2411.8311718179734;2420.322309753272;2420.3707036170545;2420.721617014048;2420.7579242075826;9580.425174489554;9638.469884156368;9662.227021838236;9670.274729395323;9671.613135474572;5269.314989051399;5270.947290527122;5305.708186049766;5360.785568577291;5451.2640793492765;3380.5943286805496;3510.8771193008124;3526.788392696421;3572.2855428914063;3608.6210947850623;1132.3488993828582;1183.6094088950626;1211.176400047967;1214.095498853351;2170.4573856535867;12636.557720479832;13165.539206127836;13195.86804131956;13217.07480476367;13231.073824832265;4285.485741711014;4598.425196850394;4671.135839869635;4704.929426058612;4705.764711764398;10201.693949181536;10205.285630005828;10222.13111885498;10232.59069637221;10234.02776736209;5227.790888364499;5228.686278823283;5231.947680523146;5235.8429247121885;5236.345456817898;4270.10327831159;4759.595434388047;4841.273808928619;4892.951070489249;4895.632827537877;3733.5519737631657;3776.244751049801;3838.788866612307;3862.787546684959;3928.6267804559093;1140.9828852567218;1158.0851127609262;1190.1490434339316;1199.7300607363272;1200.8739082396223;812.1715849833137;834.8163404051099;835.258043963049;856.0711627883816;862.3403096631082;5692.430756924275;6071.332153463204;6146.661933593606;6180.196574445294;6267.435930766253;937.8265020971143;944.4003058058182;992.6476100984191;993.8509223616371;993.9602415903283;3890.2414029264587;3905.6094390561284;4015.15681706839;4036.798160091984;4091.815868285875;11533.365664888512;12010.879891200977;12043.337616431181;12053.457594408274;12073.094517911017;11646.750450980251;11789.115816313812;11897.286162830258;11944.701382465508;11976.520939162336;8768.5177315247;8807.11928807127;8907.153820387053;8918.286537077018;8931.151540603762;6035.758569657341;6045.818625441244;6046.062860256668;6066;6118.082287656887;10769.192310576582;10854.511546980562;10893.237473376788;10968.561257549927;11030.944845275722;10945.616903408343;10967.780644387227;11122.110231181648;11164.609238676607;11269.943650281695;6962.373386395248;7146.7141314346845;7169.533980291264;7672.309492145734;7944;8317.085120636604;8534.658613655385;8810.030896601496;8854.557272136239;8883;7969.402294827795;8022.638981245975;8108.270255677017;8200.302974247255;8249.546274954695;8039.397045221514;8071.192880711882;8139;8193.50838949665;8540.359473039423;6724.327567243432;6877.277885822116;6900.9654951726925;7005.404540614105;7026.1217347831525;5151.459096794782;5255.395629502656;5300.840974770763;5350.438203988525;5561.833145005657;5202;5279.630425870152;5352.839414817562;5360.303160589098;5414.864628384322 07:26:56 INFO - PROCESS | 11013 | |4;prototype.html;322.89505910579066;330.702367868918;333.3433136720661;339.9201187720886;341.8512946868111;5127.692338459686;5130.230465430178;5133.460986596409;5153.278541004258;5177.301064356316;4804.279358096287;4864.586510146633;5004.424491183515;5011.869955269014;5053.696778193311;4066.6340029397347;4081.1837632473475;4086.816093275795;4088.8773336799945;4096.815643296059;4096.262672718909;4105;4114.136031433388;4114.958850411503;4132.793360331986;3874.670652994499;3898.610138986093;4068.206699693561;4070.6743460523235;4078.632923036926;4051.210014047262;4054.1283624020684;4070.837166513321;4071.6131967463043;4093.283675356801;3622.4385220290897;3629.3830048891487;3641.8361173747244;3644.1071937375514;3646.890593282206;4740.95259047412;4998.225275082332;5000.149974504343;5016.147254966664;5018.139660393577;4675.929861052087;4693.78877950493;4720.645951553613;4724.527547245248;4725.149473094842;5343.305370301836;5354.732263386815;5359.892802143974;5364.892702145935;5369.463053694639;3876.6704830089475;3985.740926839746;4016.176683779818;4028.2144981728575;4059.6549293310104;5477.7821077769895;5536.2249285100115;5540.252065971043;5556.332820338557;5569.721513924287;4091.5908409158847;4100.507939047334;4104.0970986382945;4113.321301985199;4129.855455059059;4051.5948405159247;4161.667066634662;4168.083021735213;4184.916301673949;4188.727732697365;4587;4597.42532183477;4647.187596837236;4658.86023419298;4701.130290896127;5321.228421878806;5358.544523715528;5369.838904832784;5377.408485066718;5378.9462105379225;1417.6030711400617;1424.2380326525467;1436.9353379097965;1439.7048605035943;1443.603009172486;935.904991160344;939.5724945150077;950;959.4531117263093;959.4627008875052;4534.6372290216705;4573.794179261941;4585.426821647294;4607.2398054320665;4661.790219440133;4824.445188803333;4880.463149053601;4891.853244402673;4898.159067740702;4958.454569997298;4387.157393894572;4566.625354815477;4600.60894823937;4637.907241855144;4706.670533062721;2603.284096873375;2611.060018393377;2614.6470226519177;2615.2415799417954;2618.3061488705507;5020.221865610763;5024.799008039638;5048.5203905628905;5054;5057.747112644353;4792.712437253706;4874.926876096862;4882.828121250875;4912.729799861042;4997.450790254959;4866.245731911559;4869.464358920516;4913.508649135058;4954.67794593343;4960.3551538301235;4822.132016237111;4822.397200349957;4829.637777166657;4834.365270927283;4862.562369386772;4617.445906491243;4770.904581908412;4793.472718000948;4817.397825271841;4844.563989240985;4919.852404427873;4942.418426103613;4949.331840201527;4958.8760280993265;4968.8757781055765;3716.535433070866;3747.3816820224356;3752.380857158538;3777.640049582093;3784.5836957935157;4891.532540237986;4893.926591101065;5039;5190.377154741455;5475.370332411823;5335.466453354634;5366.2487251784005;5371.355437347544;5373.946260537345;5378.97310513445;5359.196120581944;5361.839144825662;5380.354357477127;5394.676319420847;5394.892102158014;5117.718525481134;5253.290805741329;5274.419813820554;5276.788928442819;5286.577073834161;5360;5360.16917377807;5374.4088150304215;5388.488093631099;5434.266374039612;5063.645544811828;5255.868603284948;5267.525922666979;5269.077911365419;5327.360716714019 07:26:56 INFO - PROCESS | 11013 | |5;yui.html;645.6674812471576;745.28452685422;758.2872100225787;779.5011192836582;793.5238856685987;16242.269097890594;16921.23854426552;17177.141142942866;17184.22670979806;17207.139643017847;14270;15907.12510811905;16084;16092.436764713237;16249.025058496498;793.2979313307707;801.5230937592123;815.0382548592688;815.2011029191395;816.1878930464196;813.3046245460135;815.808285053012;816.1308206759811;816.542736067801;816.7672213419177;847.4533925617973;847.7414388611471;850.8213275212213;860.1699360117485;860.423516244118;847.2713466418875;848.2916764501617;848.7793173774801;849.133883438895;850.31974420464;616.9907451388233;619.9287081985584;620.9379062093755;622.470899735226;622.7882519943196;14413.78379324311;14566.417343306255;14741.410343586243;14913.92543037278;14963.850361496463;14511.274436278143;15361.692766144726;15630;15635.296411661502;16026.198690065567;11593.6521904343;11674.591389301333;11773.175877688565;12001.399930003465;12158.939205304005;3812.7331086823933;3831;3867.5358956925347;3908.218356328746;3984.442178095069;53395.46604533944;64037;67596.6620166896;69799;81570;11742;11906.940465297617;12167.574134905413;12592.922167842204;12759.298238596966;22984.310470685905;23006.309810705705;23016.769832301798;23032.309030729106;23191.88404057969;8600.13998600135;8789.780255493664;8850.601722922484;9202.987671356146;9483.336166468282;4546.727196368229;4731.952680473151;4853.102176097434;5023.321851549994;5107.948920510822;3596.5144705464427;3798.734088613826;3800.8289626966853;3803.0492376905772;3806.276807406584;618.7741474361826;663.3466035954581;688.4320435640617;700.6111608057577;712.2450202785064;653.5817077070678;680.1905732178692;680.7855525509548;687.3744892148121;690.8998195261659;9742.171915387129;10040.411407106196;10061.79876402468;10127.70805020384;10137.442440665687;519.6388509985524;617.5646169450526;633.8130251575797;641.2689533931322;656.967151642416;4519.751413672279;4747.667663263538;4781.450133234723;4830.203016502308;4840.249761287007;124407;126341.36829315979;127891;128674;129451;108740;111561;114073;114320;118256;75360.62319688367;76859;77759;88131.55934220288;93595;9872.35829671069;9920.40477571348;10004.599816007278;10105.5452504636;10559.208059395729;50980.490195097576;51727.74136129295;51837.74081129721;54468;56057;73719;75490.62254688691;79848;79894;80924;12692.238465692088;13049.804252936023;13139.54011609589;13505.189688618715;14062.156270623795;14783.408663653763;15608.453704120302;15926.601834953755;16862.494125176265;17156;13562.457501700215;13564.728705426034;13848.723025539633;13979.95880032125;15544.611384715472;14139;14515.78226326627;15202.847971520143;15300.31148598338;15812.288447019682;14321.928390357982;14580.125192488485;14582.343794529485;14591.635209119433;15060.472883449027;11435.942820285845;11612.419379031184;12075.154739168174;12125.77612647454;12281.508739650671;10994.615188468366;11721.53512178605;11820.527178912747;11868.177813615213;11951.282923024646 07:26:56 INFO - PROCESS | 11013 | __end_tp_report 07:26:56 INFO - PROCESS | 11013 | __start_cc_report 07:26:56 INFO - PROCESS | 11013 | _x_x_mozilla_cycle_collect,937 07:26:56 INFO - PROCESS | 11013 | __end_cc_report 07:26:56 INFO - PROCESS | 11013 | __startTimestamp1471444016479__endTimestamp 07:26:56 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | ------- Summary: start ------- 07:26:56 INFO - PROCESS | 11013 | Number of tests: 6 07:26:56 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | [#0] dojo.html Cycles:165 Average:5306.41 Median:5523.12 stddev:607.90 (11.0%) stddev-sans-first:582.98 07:26:56 INFO - PROCESS | 11013 | Values: 3031.5 3033.0 3039.5 3056.9 3057.6 5175.7 5191.8 5271.2 5386.6 5423.4 5242.2 5261.8 5290.9 5299.9 5355.5 4775.8 4809.6 4831.9 4835.0 5012.4 4897.8 4906.4 4956.9 4969.7 4978.9 4694.1 4845.0 4879.3 4905.3 4912.9 4822.9 4823.8 4825.5 4902.3 4940.1 4833.3 4862.1 4863.1 4871.2 4918.7 5157.2 5207.4 5253.2 5268.2 5326.9 5763.7 5763.8 5777.2 5789.9 5793.3 5753.2 5783.5 5796.6 5810.2 5823.0 5183.8 5323.6 5359.1 5375.5 5417.4 5945.0 5950.5 5951.5 5963.0 5963.3 5697.6 5730.0 5735.7 5736.0 5738.6 5517.3 5607.0 5669.5 5806.9 5820.6 5683.9 5684.5 5687.3 5728.0 5741.3 5626.3 5655.0 5659.1 5662.2 5721.9 4318.9 4320.3 4346.7 4385.8 4420.1 3899.0 3899.9 3909.7 3931.8 3936.4 5332.9 5340.0 5340.4 5342.1 5386.8 5590.6 5684.6 5716.0 5732.4 5737.5 5374.2 5379.0 5379.6 5457.3 5462.6 4946.5 4962.9 4966.5 4967.9 5010.2 5690.3 5767.7 5789.0 5958.6 5962.8 5671.2 5688.7 5727.6 5732.5 5736.1 5829.0 5845.2 5849.0 5853.3 5853.3 5453.5 5535.6 5550.3 5568.0 5598.7 5688.0 5720.3 5760.2 5792.5 5818.3 5598.7 5601.2 5606.9 5609.0 5613.2 5415.7 5523.1 5598.2 5607.1 5610.8 5487.1 5538.0 5562.5 5593.2 5594.3 5656.7 5665.7 5712.5 5714.2 5714.7 5317.6 5366.4 5463.2 5524.9 5544.6 07:26:56 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | [#1] ext.html Cycles:180 Average:58451.65 Median:40591.69 stddev:118226.69 (291.3%) stddev-sans-first:118478.47 07:26:56 INFO - PROCESS | 11013 | Values: 568.7 592.3 594.5 614.0 616.5 32096.0 42990.6 44388.8 44409.0 44502.3 45205.5 45738.8 45824.1 45947.0 45955.1 8740.9 8848.5 8859.7 8894.2 8894.6 8718.0 8851.8 8853.8 8855.7 8858.5 8878.9 8932.1 8934.4 8951.0 8960.1 8840.7 8859.7 8892.1 8911.9 8912.6 8780.8 8929.2 8929.2 8932.5 8936.6 43309.0 43634.3 43641.8 43665.6 43668.6 3190.6 3307.6 3650.8 3662.1 3848.8 32665.2 33035.7 33135.0 33508.3 34207.8 4398.6 4462.6 4593.0 4654.4 4686.9 338563.0 339198.3 339685.0 339787.0 340785.0 35316.0 36528.3 55854.7 56356.7 56453.7 37796.2 37838.4 38044.0 38052.4 38114.8 14627.2 14692.9 14725.7 14729.5 14736.5 11896.8 12050.4 12088.3 12092.2 12097.8 8596.3 8615.4 8691.1 8712.6 8736.1 3379.8 3389.9 3401.6 3404.5 3413.5 1016.8 1081.3 1130.6 1141.6 1154.2 32202.8 32783.0 32919.2 33051.5 33069.0 949.8 1062.4 1109.5 1157.5 1175.0 16225.9 16828.8 17025.9 17043.7 17054.6 566875.0 571736.0 572046.0 572675.0 573604.0 59627.0 59953.7 59990.0 60037.4 60144.0 1215.1 1310.8 1337.2 1347.5 1379.9 31115.4 33430.0 33566.7 33663.8 33887.0 49631.5 49831.5 50086.0 50145.7 50224.7 386872.0 388113.0 388148.0 389833.0 389875.0 28586.1 28763.0 29705.7 29733.4 30844.1 48124.0 48144.0 48161.0 48434.3 49183.3 40080.0 40512.0 41864.0 41992.8 43224.8 40671.4 40787.6 41063.6 42119.4 42252.6 40184.0 40440.8 41230.4 41507.0 41633.0 32727.0 36563.3 36575.5 36890.6 37426.4 27674.3 28207.3 28309.9 28685.0 28732.6 07:26:56 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | [#2] jquery.html Cycles:180 Average:29005.31 Median:2158.83 stddev:81385.73 (3769.9%) stddev-sans-first:81585.65 07:26:56 INFO - PROCESS | 11013 | Values: 379.8 400.8 402.9 406.1 427.1 13118.8 19266.8 19300.5 19325.5 19326.0 11737.4 12270.9 12294.1 12304.6 12454.9 3916.9 5315.7 5462.7 5468.6 5716.9 5769.2 6014.5 6017.1 6019.0 6019.5 5903.2 5990.9 6505.1 6590.3 6595.7 6023.6 6573.4 6627.4 6640.0 6657.0 5822.9 5849.0 5855.2 5855.5 5858.1 14310.7 15319.5 23093.4 23694.9 23855.5 2273.4 2276.4 2336.6 2396.3 2509.6 10128.0 10630.9 10643.6 11355.3 14266.5 1881.7 1900.6 1961.1 1985.8 1996.2 80482.2 81534.6 83020.2 83651.6 83723.2 10757.7 11027.2 11265.3 11374.2 11516.1 15849.7 15866.4 15875.5 15911.9 15917.6 8074.9 8174.1 8188.9 8328.2 8338.7 7774.5 7791.5 8001.4 8006.2 8027.2 4782.6 4862.2 4872.0 4889.3 4950.1 1914.6 1946.7 1983.9 1996.9 2018.1 877.0 937.6 946.1 1032.7 1034.7 20098.3 20423.3 20494.8 20500.1 20503.5 955.6 986.6 987.7 1028.0 1064.1 11202.2 11343.7 11374.6 11389.5 11457.3 476039.0 492839.0 498589.0 498647.0 501828.0 88791.6 89212.0 90829.5 91144.5 91251.0 951.3 974.2 994.8 995.3 996.4 17244.6 17742.3 18261.8 18267.6 18301.1 41760.0 42116.8 43084.0 43285.1 43737.3 57413.0 57540.7 57980.7 58562.7 59978.7 8563.7 8571.9 8642.9 8735.4 9076.2 10900.6 13145.7 13578.3 13633.0 13758.9 11488.8 11500.7 11906.2 12091.6 12581.0 11683.5 11735.4 11739.1 11866.3 12238.1 11691.6 11710.2 11743.2 12101.2 12520.6 7490.7 7860.4 8317.7 8767.6 8929.0 11575.2 12114.8 12125.8 12200.5 12377.3 07:26:56 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | [#3] mootools.html Cycles:180 Average:6026.46 Median:4732.68 stddev:3554.74 (75.1%) stddev-sans-first:3546.97 07:26:56 INFO - PROCESS | 11013 | Values: 1301.0 1456.7 1504.6 1597.7 1808.1 8613.8 9037.8 9069.1 9356.7 9371.6 9165.7 9279.2 9289.0 9289.3 9290.2 2046.4 2297.8 2364.9 2399.3 2400.4 2361.1 2387.8 2389.8 2393.2 2397.8 2316.3 2347.4 2352.1 2358.7 2390.0 2284.0 2348.3 2359.1 2360.6 2382.8 2411.8 2420.3 2420.4 2420.7 2420.8 9580.4 9638.5 9662.2 9670.3 9671.6 5269.3 5270.9 5305.7 5360.8 5451.3 3380.6 3510.9 3526.8 3572.3 3608.6 1132.3 1183.6 1211.2 1214.1 2170.5 12636.6 13165.5 13195.9 13217.1 13231.1 4285.5 4598.4 4671.1 4704.9 4705.8 10201.7 10205.3 10222.1 10232.6 10234.0 5227.8 5228.7 5231.9 5235.8 5236.3 4270.1 4759.6 4841.3 4893.0 4895.6 3733.6 3776.2 3838.8 3862.8 3928.6 1141.0 1158.1 1190.1 1199.7 1200.9 812.2 834.8 835.3 856.1 862.3 5692.4 6071.3 6146.7 6180.2 6267.4 937.8 944.4 992.6 993.9 994.0 3890.2 3905.6 4015.2 4036.8 4091.8 11533.4 12010.9 12043.3 12053.5 12073.1 11646.8 11789.1 11897.3 11944.7 11976.5 8768.5 8807.1 8907.2 8918.3 8931.2 6035.8 6045.8 6046.1 6066.0 6118.1 10769.2 10854.5 10893.2 10968.6 11030.9 10945.6 10967.8 11122.1 11164.6 11269.9 6962.4 7146.7 7169.5 7672.3 7944.0 8317.1 8534.7 8810.0 8854.6 8883.0 7969.4 8022.6 8108.3 8200.3 8249.5 8039.4 8071.2 8139.0 8193.5 8540.4 6724.3 6877.3 6901.0 7005.4 7026.1 5151.5 5255.4 5300.8 5350.4 5561.8 5202.0 5279.6 5352.8 5360.3 5414.9 07:26:56 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | [#4] prototype.html Cycles:180 Average:4362.43 Median:4832.00 stddev:1220.62 (25.3%) stddev-sans-first:1185.79 07:26:56 INFO - PROCESS | 11013 | Values: 322.9 330.7 333.3 339.9 341.9 5127.7 5130.2 5133.5 5153.3 5177.3 4804.3 4864.6 5004.4 5011.9 5053.7 4066.6 4081.2 4086.8 4088.9 4096.8 4096.3 4105.0 4114.1 4115.0 4132.8 3874.7 3898.6 4068.2 4070.7 4078.6 4051.2 4054.1 4070.8 4071.6 4093.3 3622.4 3629.4 3641.8 3644.1 3646.9 4741.0 4998.2 5000.1 5016.1 5018.1 4675.9 4693.8 4720.6 4724.5 4725.1 5343.3 5354.7 5359.9 5364.9 5369.5 3876.7 3985.7 4016.2 4028.2 4059.7 5477.8 5536.2 5540.3 5556.3 5569.7 4091.6 4100.5 4104.1 4113.3 4129.9 4051.6 4161.7 4168.1 4184.9 4188.7 4587.0 4597.4 4647.2 4658.9 4701.1 5321.2 5358.5 5369.8 5377.4 5378.9 1417.6 1424.2 1436.9 1439.7 1443.6 935.9 939.6 950.0 959.5 959.5 4534.6 4573.8 4585.4 4607.2 4661.8 4824.4 4880.5 4891.9 4898.2 4958.5 4387.2 4566.6 4600.6 4637.9 4706.7 2603.3 2611.1 2614.6 2615.2 2618.3 5020.2 5024.8 5048.5 5054.0 5057.7 4792.7 4874.9 4882.8 4912.7 4997.5 4866.2 4869.5 4913.5 4954.7 4960.4 4822.1 4822.4 4829.6 4834.4 4862.6 4617.4 4770.9 4793.5 4817.4 4844.6 4919.9 4942.4 4949.3 4958.9 4968.9 3716.5 3747.4 3752.4 3777.6 3784.6 4891.5 4893.9 5039.0 5190.4 5475.4 5335.5 5366.2 5371.4 5373.9 5379.0 5359.2 5361.8 5380.4 5394.7 5394.9 5117.7 5253.3 5274.4 5276.8 5286.6 5360.0 5360.2 5374.4 5388.5 5434.3 5063.6 5255.9 5267.5 5269.1 5327.4 07:26:56 INFO - PROCESS | 11013 | 07:26:56 INFO - PROCESS | 11013 | [#5] yui.html Cycles:180 Average:21692.98 Median:3804.66 stddev:31719.49 (833.7%) stddev-sans-first:31769.10 07:26:56 INFO - PROCESS | 11013 | Values: 645.7 745.3 758.3 779.5 793.5 16242.3 16921.2 17177.1 17184.2 17207.1 14270.0 15907.1 16084.0 16092.4 16249.0 793.3 801.5 815.0 815.2 816.2 813.3 815.8 816.1 816.5 816.8 847.5 847.7 850.8 860.2 860.4 847.3 848.3 848.8 849.1 850.3 617.0 619.9 620.9 622.5 622.8 14413.8 14566.4 14741.4 14913.9 14963.9 14511.3 15361.7 15630.0 15635.3 16026.2 11593.7 11674.6 11773.2 12001.4 12158.9 3812.7 3831.0 3867.5 3908.2 3984.4 53395.5 64037.0 67596.7 69799.0 81570.0 11742.0 11906.9 12167.6 12592.9 12759.3 22984.3 23006.3 23016.8 23032.3 23191.9 8600.1 8789.8 8850.6 9203.0 9483.3 4546.7 4732.0 4853.1 5023.3 5107.9 3596.5 3798.7 3800.8 3803.0 3806.3 618.8 663.3 688.4 700.6 712.2 653.6 680.2 680.8 687.4 690.9 9742.2 10040.4 10061.8 10127.7 10137.4 519.6 617.6 633.8 641.3 657.0 4519.8 4747.7 4781.5 4830.2 4840.2 124407.0 126341.4 127891.0 128674.0 129451.0 108740.0 111561.0 114073.0 114320.0 118256.0 75360.6 76859.0 77759.0 88131.6 93595.0 9872.4 9920.4 10004.6 10105.5 10559.2 50980.5 51727.7 51837.7 54468.0 56057.0 73719.0 75490.6 79848.0 79894.0 80924.0 12692.2 13049.8 13139.5 13505.2 14062.2 14783.4 15608.5 15926.6 16862.5 17156.0 13562.5 13564.7 13848.7 13980.0 15544.6 14139.0 14515.8 15202.8 15300.3 15812.3 14321.9 14580.1 14582.3 14591.6 15060.5 11435.9 11612.4 12075.2 12125.8 12281.5 10994.6 11721.5 11820.5 11868.2 11951.3 07:26:56 INFO - PROCESS | 11013 | -------- Summary: end -------- 07:26:56 INFO - PROCESS | 11013 | 07:26:57 INFO - TEST-INFO | 11013: exit 0 07:26:57 INFO - TEST-OK | dromaeo_css | took 1115340ms 07:26:57 INFO - TEST-START | kraken 07:26:57 INFO - operating with platform_type : linux_ 07:26:57 INFO - Initialising browser for kraken test... 07:26:57 INFO - TEST-INFO | started process 11323 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphlOP9h/profile http://localhost:43169/getInfo.html) 07:27:01 INFO - PROCESS | 11323 | __metrics Screen width/height:1600/1200 07:27:01 INFO - PROCESS | 11323 | colorDepth:24 07:27:01 INFO - PROCESS | 11323 | Browser inner width/height: 1024/697 07:27:01 INFO - PROCESS | 11323 | __metrics 07:27:01 INFO - PROCESS | 11323 | 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" 07:27:01 INFO - PROCESS | 11323 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:27:02 INFO - PROCESS | 11323 | console.error: PushService: 07:27:02 INFO - PROCESS | 11323 | stateChangeProcessEnqueue: Error transitioning state 07:27:02 INFO - PROCESS | 11323 | UnknownError 07:27:03 INFO - TEST-INFO | 11323: exit 0 07:27:03 INFO - Browser initialized. 07:27:03 INFO - Running cycle 1/1 for kraken test... 07:27:03 INFO - TEST-INFO | started process 11405 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphlOP9h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:27:14 INFO - PROCESS | 11405 | RSS: Main: 155983872 07:27:14 INFO - PROCESS | 11405 | 07:27:17 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43169/tests/kraken/driver.html?testName=audio-beat-detection) 07:27:18 INFO - PROCESS | 11405 | RSS: Main: 203341824 07:27:18 INFO - PROCESS | 11405 | 07:27:20 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43169/tests/kraken/driver.html?testName=audio-dft) 07:27:21 INFO - PROCESS | 11405 | RSS: Main: 196136960 07:27:21 INFO - PROCESS | 11405 | 07:27:24 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43169/tests/kraken/driver.html?testName=audio-fft) 07:27:25 INFO - PROCESS | 11405 | RSS: Main: 276615168 07:27:25 INFO - PROCESS | 11405 | 07:27:27 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43169/tests/kraken/driver.html?testName=audio-oscillator) 07:27:27 INFO - PROCESS | 11405 | RSS: Main: 186413056 07:27:27 INFO - PROCESS | 11405 | 07:27:30 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43169/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:27:30 INFO - PROCESS | 11405 | RSS: Main: 191102976 07:27:30 INFO - PROCESS | 11405 | 07:27:33 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43169/tests/kraken/driver.html?testName=imaging-darkroom) 07:27:34 INFO - PROCESS | 11405 | RSS: Main: 226508800 07:27:34 INFO - PROCESS | 11405 | 07:27:37 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43169/tests/kraken/driver.html?testName=imaging-desaturate) 07:27:38 INFO - PROCESS | 11405 | RSS: Main: 226676736 07:27:38 INFO - PROCESS | 11405 | 07:27:41 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43169/tests/kraken/driver.html?testName=json-parse-financial) 07:27:41 INFO - PROCESS | 11405 | RSS: Main: 226107392 07:27:41 INFO - PROCESS | 11405 | 07:27:43 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43169/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:27:43 INFO - PROCESS | 11405 | RSS: Main: 188678144 07:27:43 INFO - PROCESS | 11405 | 07:27:45 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43169/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:27:46 INFO - PROCESS | 11405 | RSS: Main: 197464064 07:27:46 INFO - PROCESS | 11405 | 07:27:48 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43169/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:27:48 INFO - PROCESS | 11405 | RSS: Main: 193884160 07:27:48 INFO - PROCESS | 11405 | 07:27:51 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43169/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:27:52 INFO - PROCESS | 11405 | RSS: Main: 187678720 07:27:52 INFO - PROCESS | 11405 | 07:27:54 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43169/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:27:55 INFO - PROCESS | 11405 | RSS: Main: 192200704 07:27:55 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | Cycle 1(1): loaded http://localhost:43169/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43169/tests/kraken/driver.html?testName=ai-astar) 07:27:57 INFO - PROCESS | 11405 | RSS: Main: 243445760 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | __start_tp_report 07:27:57 INFO - PROCESS | 11405 | _x_x_mozilla_page_load 07:27:57 INFO - PROCESS | 11405 | _x_x_mozilla_page_load_details 07:27:57 INFO - PROCESS | 11405 | |i|pagename|runs| 07:27:57 INFO - PROCESS | 11405 | |0;ai-astar;105;113;110;112;108;112;111;112;111;112 07:27:57 INFO - PROCESS | 11405 | |1;audio-beat-detection;150;123;123;178;119;120;148;117;162;118 07:27:57 INFO - PROCESS | 11405 | |2;audio-dft;161;160;160;160;159;161;161;160;161;161 07:27:57 INFO - PROCESS | 11405 | |3;audio-fft;75;75;78;113;76;76;75;73;99;75 07:27:57 INFO - PROCESS | 11405 | |4;audio-oscillator;84;84;125;85;84;85;122;84;85;127 07:27:57 INFO - PROCESS | 11405 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;117;116;116 07:27:57 INFO - PROCESS | 11405 | |6;imaging-darkroom;146;146;146;146;147;147;146;146;145;147 07:27:57 INFO - PROCESS | 11405 | |7;imaging-desaturate;95;95;95;95;96;95;96;95;94;95 07:27:57 INFO - PROCESS | 11405 | |8;json-parse-financial;51;48;51;51;50;52;50;50;52;51 07:27:57 INFO - PROCESS | 11405 | |9;json-stringify-tinderbox;60;61;61;61;62;61;62;62;61;62 07:27:57 INFO - PROCESS | 11405 | |10;stanford-crypto-aes;88;78;78;78;84;78;81;77;78;78 07:27:57 INFO - PROCESS | 11405 | |11;stanford-crypto-ccm;133;122;122;123;122;134;122;122;139;122 07:27:57 INFO - PROCESS | 11405 | |12;stanford-crypto-pbkdf2;163;167;163;165;163;162;165;162;162;163 07:27:57 INFO - PROCESS | 11405 | |13;stanford-crypto-sha256-iterative;68;66;66;67;66;64;64;67;64;64 07:27:57 INFO - PROCESS | 11405 | __end_tp_report 07:27:57 INFO - PROCESS | 11405 | __start_cc_report 07:27:57 INFO - PROCESS | 11405 | _x_x_mozilla_cycle_collect,1667 07:27:57 INFO - PROCESS | 11405 | __end_cc_report 07:27:57 INFO - PROCESS | 11405 | __startTimestamp1471444077286__endTimestamp 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | ------- Summary: start ------- 07:27:57 INFO - PROCESS | 11405 | Number of tests: 14 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:2.41 (2.2%) stddev-sans-first:1.48 07:27:57 INFO - PROCESS | 11405 | Values: 105.0 113.0 110.0 112.0 108.0 112.0 111.0 112.0 111.0 112.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#1] audio-beat-detection Cycles:10 Average:135.80 Median:135.50 stddev:21.98 (16.2%) stddev-sans-first:22.70 07:27:57 INFO - PROCESS | 11405 | Values: 150.0 123.0 123.0 178.0 119.0 120.0 148.0 117.0 162.0 118.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#2] audio-dft Cycles:10 Average:160.40 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 07:27:57 INFO - PROCESS | 11405 | Values: 161.0 160.0 160.0 160.0 159.0 161.0 161.0 160.0 161.0 161.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.50 stddev:13.39 (17.7%) stddev-sans-first:13.99 07:27:57 INFO - PROCESS | 11405 | Values: 75.0 75.0 78.0 113.0 76.0 76.0 75.0 73.0 99.0 75.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:84.00 stddev:19.48 (23.2%) stddev-sans-first:20.13 07:27:57 INFO - PROCESS | 11405 | Values: 84.0 84.0 125.0 85.0 84.0 85.0 122.0 84.0 85.0 127.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 07:27:57 INFO - PROCESS | 11405 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 116.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 07:27:57 INFO - PROCESS | 11405 | Values: 146.0 146.0 146.0 146.0 147.0 147.0 146.0 146.0 145.0 147.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 07:27:57 INFO - PROCESS | 11405 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 96.0 95.0 94.0 95.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:51.00 stddev:1.17 (2.3%) stddev-sans-first:1.24 07:27:57 INFO - PROCESS | 11405 | Values: 51.0 48.0 51.0 51.0 50.0 52.0 50.0 50.0 52.0 51.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.50 stddev:0.67 (1.1%) stddev-sans-first:0.53 07:27:57 INFO - PROCESS | 11405 | Values: 60.0 61.0 61.0 61.0 62.0 61.0 62.0 62.0 61.0 62.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:78.00 stddev:3.55 (4.6%) stddev-sans-first:2.20 07:27:57 INFO - PROCESS | 11405 | Values: 88.0 78.0 78.0 78.0 84.0 78.0 81.0 77.0 78.0 78.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#11] stanford-crypto-ccm Cycles:10 Average:126.10 Median:122.50 stddev:6.56 (5.4%) stddev-sans-first:6.46 07:27:57 INFO - PROCESS | 11405 | Values: 133.0 122.0 122.0 123.0 122.0 134.0 122.0 122.0 139.0 122.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:163.00 stddev:1.65 (1.0%) stddev-sans-first:1.74 07:27:57 INFO - PROCESS | 11405 | Values: 163.0 167.0 163.0 165.0 163.0 162.0 165.0 162.0 162.0 163.0 07:27:57 INFO - PROCESS | 11405 | 07:27:57 INFO - PROCESS | 11405 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:1.51 (2.3%) stddev-sans-first:1.32 07:27:57 INFO - PROCESS | 11405 | Values: 68.0 66.0 66.0 67.0 66.0 64.0 64.0 67.0 64.0 64.0 07:27:57 INFO - PROCESS | 11405 | -------- Summary: end -------- 07:27:57 INFO - PROCESS | 11405 | 07:27:58 INFO - TEST-INFO | 11405: exit 0 07:27:58 INFO - TEST-OK | kraken | took 61078ms 07:27:58 INFO - SUITE-END | took 1176s 07:27:58 INFO - Completed test suite (00:19:36) 07:27:58 INFO - javascript benchmark 07:27:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3031.530112832512, 3032.9839503766234, 3039.544068389742, 3056.899516174177, 3057.587225724528, 5175.741212939347, 5191.818286359978, 5271.156614941606, 5386.622936394454, 5423.430539793319, 5242.18746094356, 5261.842144735664, 5290.947090529094, 5299.894002119955, 5355.518003379695, 4775.785089670956, 4809.615230781529, 4831.927521087169, 4835.008823191238, 5012.373453318335, 4897.7795999180125, 4906.362172917516, 4956.900861982758, 4969.676970996892, 4978.925316120243, 4694.131585656647, 4845.030993801254, 4879.316895634614, 4905.337779399771, 4912.8771780705765, 4822.903541929142, 4823.807047718087, 4825.469198388175, 4902.338184345103, 4940.110780059588, 4833.29917162013, 4862.051899879525, 4863.076015557032, 4871.220604703266, 4918.680285781413, 5157.226416037587, 5207.427183009866, 5253.212018197263, 5268.157094864841, 5326.92009619856, 5763.711814409263, 5763.8270851874095, 5777.220075289824, 5789.855253618651, 5793.304803423574, 5753.165790960287, 5783.479486846162, 5796.62321949072, 5810.24466819315, 5823.0, 5183.81856635016, 5323.574114070904, 5359.14253719403, 5375.543078838343, 5417.404085550586, 5944.999999999914, 5950.494207992283, 5951.4941229995065, 5962.970185149134, 5963.344032156459, 5697.6296540724725, 5730.0, 5735.684537350402, 5735.971320143372, 5738.569607279471, 5517.282753242133, 5606.971965140148, 5669.489745922804, 5806.912896306559, 5820.621659592113, 5683.914741278884, 5684.516816070598, 5687.260656114679, 5728.0, 5741.282339707536, 5626.2967129108865, 5655.0, 5659.094544872801, 5662.150677398423, 5721.914171287434, 4318.913621727548, 4320.308750599889, 4346.6522678185665, 4385.846495372647, 4420.071784925201, 3899.0, 3899.883003509899, 3909.706771992112, 3931.7640941543027, 3936.3505021671676, 5332.866678333073, 5339.973300133553, 5340.359156901197, 5342.091844386464, 5386.757595908193, 5590.608657393944, 5684.63049901755, 5716.0, 5732.398098199629, 5737.483626473638, 5374.247605335257, 5379.004884096455, 5379.596530260246, 5457.345118585795, 5462.590305727086, 4946.480619534969, 4962.932969411594, 4966.528179822911, 4967.850964471072, 5010.223415370623, 5690.345610254791, 5767.711614419179, 5789.044807606698, 5958.612690175125, 5962.821115366546, 5671.17767923657, 5688.658680479184, 5727.627704199213, 5732.484076433141, 5736.139579063174, 5829.0, 5845.240118784531, 5848.970755146197, 5853.268341457318, 5853.2976042875125, 5453.454654534515, 5535.557155427476, 5550.333959924835, 5568.0, 5598.720063996702, 5687.971560142173, 5720.3421606515785, 5760.222369979999, 5792.507636850914, 5818.33089194748, 5598.69207193592, 5601.215829783915, 5606.887862242814, 5608.971955140199, 5613.158026295925, 5415.675059496442, 5523.116301391758, 5598.160275958476, 5607.130894711327, 5610.803621873215, 5487.094629386106, 5538.0, 5562.527185189304, 5593.216949627137, 5594.328680558359, 5656.717164141859, 5665.688387138747, 5712.457316554921, 5714.171445140514, 5714.685692286964, 5317.601179911445, 5366.382865970463, 5463.235147079333, 5524.944750552604, 5544.584156188222], "value": 5264.788377124095, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.7355379748416, 592.2655906675722, 594.5422025040718, 614.0114415790578, 616.5499185594518, 32096.0, 42990.570094299044, 44388.77805610975, 44409.0, 44502.33246501305, 45205.54794452054, 45738.771306143426, 45824.083518329695, 45947.0, 45955.08089838201, 8740.868886966684, 8848.51333176675, 8859.689910853122, 8894.199522043014, 8894.644214231423, 8718.041015488292, 8851.77870553238, 8853.778655533599, 8855.73432797017, 8858.468491890475, 8878.866816997717, 8932.062133475989, 8934.419262747933, 8950.955245223764, 8960.059193784657, 8840.690575829814, 8859.689910853122, 8892.110788921122, 8911.866322005175, 8912.55437228136, 8780.780480487974, 8929.151730585583, 8929.196372326522, 8932.464052156827, 8936.553172341422, 43309.0, 43634.34548481775, 43641.78179109116, 43665.56334436647, 43668.563314367086, 3190.6171259448784, 3307.5534802801553, 3650.7627004244905, 3662.121090938182, 3848.8460461581785, 32665.183370415692, 33035.6696433035, 33135.0, 33508.32983340344, 34207.82896085529, 4398.560143985608, 4462.576055274743, 4593.047954619306, 4654.394928659252, 4686.882827929295, 338563.0, 339198.30400848086, 339685.0, 339787.0, 340785.0, 35316.0, 36528.26943461169, 55854.72072639611, 56356.718216408655, 56453.71773141108, 37796.244075118346, 37838.43242351367, 38044.0, 38052.429213561816, 38114.80942595269, 14627.195504247156, 14692.926535367254, 14725.705485890436, 14729.484468043567, 14736.48422305214, 11896.762064758655, 12050.39748012596, 12088.274703517816, 12092.153549251469, 12097.75804483923, 8596.312295016383, 8615.396922215385, 8691.087435819274, 8712.607932643046, 8736.082711315348, 3379.847906844198, 3389.8983030509125, 3401.6428275031262, 3404.540387047766, 3413.453847384407, 1016.7813920007234, 1081.313366012572, 1130.6042884990188, 1141.560499207811, 1154.1516985016005, 32202.83898580492, 32783.0, 32919.17702057468, 33051.504227436606, 33069.0, 949.767307009787, 1062.3785085724783, 1109.4951796932376, 1157.5427706056062, 1174.9706257343464, 16225.918870405572, 16828.831711682727, 17025.914870425568, 17043.65912681764, 17054.573635659206, 566875.0, 571736.0, 572046.0, 572675.0, 573604.0, 59627.0, 59953.700231498566, 59990.0, 60037.39962600405, 60144.0, 1215.070471089617, 1310.8492523359935, 1337.2377744685436, 1347.4542810161704, 1379.903406761517, 31115.377692446476, 33429.997100087036, 33566.66433335733, 33663.83168084144, 33887.0, 49631.503684962685, 49831.501684982686, 50086.0, 50145.74927125341, 50224.748876255384, 386872.0, 388113.0, 388148.0, 389833.0, 389875.0, 28586.14241572756, 28763.0, 29705.702942970292, 29733.40533189281, 30844.0746777597, 48124.0, 48144.0, 48161.0, 48434.27348589845, 49183.26225106555, 40080.0, 40512.0, 41864.0, 41992.79003604963, 43224.783876080415, 40671.38992915168, 40787.592124079565, 41063.589364105974, 42119.36820947627, 42252.57747422609, 40184.0, 40440.79779601201, 41230.38154427626, 41507.0, 41633.0, 32727.0, 36563.268734624624, 36575.45136822897, 36890.63109368872, 37426.43860342043, 27674.308142296602, 28207.294817629725, 28309.858450707616, 28685.0, 28732.569011464428], "value": 18929.60141140216, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.7930128080196, 400.7575416872792, 402.8811500607321, 406.13290624516657, 427.1052145754645, 13118.832956491304, 19266.807331926677, 19300.51748706283, 19325.51686207842, 19326.03369831508, 11737.413129343542, 12270.938645306762, 12294.139410241287, 12304.5693400731, 12454.937725311362, 3916.902077448058, 5315.654482458637, 5462.6995515246645, 5468.6171967962255, 5716.914246286309, 5769.16347129666, 6014.548908831855, 6017.127516510103, 6019.0, 6019.5485338599565, 5903.173555702205, 5990.850228744294, 6505.0892874997535, 6590.275069742324, 6595.703193356287, 6023.6385816851125, 6573.37552932473, 6627.37039981201, 6640.0, 6657.0, 5822.854428639275, 5848.97075514624, 5855.238818953551, 5855.502282306009, 5858.12128180772, 14310.713785724227, 15319.46381876629, 23093.422664433354, 23694.881525592435, 23855.522889542284, 2273.4316420894775, 2276.362618466832, 2336.6378211377264, 2396.2571602803187, 2509.6110102934076, 10128.0, 10630.893691063067, 10643.627473038485, 11355.261907975955, 14266.500672476413, 1881.7365568820376, 1900.6293772714325, 1961.1076959983172, 1985.811347921945, 1996.2015193922402, 80482.19517804806, 81534.59232703799, 83020.16979830185, 83651.5817420909, 83723.16276837215, 10757.731056723644, 11027.2280940335, 11265.26775759573, 11374.180131179137, 11516.1362897783, 15849.683006339808, 15866.365345386055, 15875.52373428799, 15911.920440397724, 15917.602059948362, 8074.878876816852, 8174.141715119827, 8188.918110818935, 8328.167183281745, 8338.666453341919, 7774.533527988338, 7791.4935529190425, 8001.399895007898, 8006.239407256301, 8027.237412445808, 4782.641301902371, 4862.222044472938, 4872.049950259665, 4889.315495830587, 4950.108980383494, 1914.5883635018258, 1946.6593346164364, 1983.9404817855489, 1996.9301074462614, 2018.1120307064843, 876.9693060742843, 937.6015193542717, 946.1484663802632, 1032.746976990642, 1034.6895931220604, 20098.296559620343, 20423.285185018452, 20494.795052049587, 20500.07749651269, 20503.487412814502, 955.6225291010012, 986.6102889358663, 987.6987518806833, 1028.0336483705294, 1064.0583083970741, 11202.215844890943, 11343.716407089889, 11374.601888933848, 11389.487473063731, 11457.255278406878, 476039.0, 492839.0, 498589.0, 498647.0, 501828.0, 88791.55604221938, 89212.0, 90829.54585227164, 91144.54427727818, 91251.0, 951.286535098676, 974.1573538888952, 994.8213412626325, 995.2933417273816, 996.4121168510469, 17244.568885777957, 17742.29030838752, 18261.817381826542, 18267.634647307244, 18301.084616037886, 41760.0, 42116.78941605272, 43084.0, 43285.13429731325, 43737.343939841565, 57413.0, 57540.71229643825, 57980.71009644925, 58562.7071864638, 59978.700106499185, 8563.743087707378, 8571.871421928801, 8642.870356944777, 8735.388522803343, 9076.249026233418, 10900.618478353219, 13145.737085258432, 13578.32108394596, 13633.0, 13758.862411376158, 11488.827667584826, 11500.712482188012, 11906.166568340133, 12091.637250882135, 12580.99352051852, 11683.532658693557, 11735.354555499529, 11739.11956603241, 11866.347350895783, 12238.143329966817, 11691.590794322157, 11710.180287379802, 11743.17797754149, 12101.152919295564, 12520.561780337644, 7490.70037198506, 7860.449768516378, 8317.708880189406, 8767.60545775429, 8929.0, 11575.247608905394, 12114.818277726017, 12125.81811272814, 12200.450979706113, 12377.257364558156], "value": 9220.493716833953, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1301.0, 1456.7013762178753, 1504.601280660625, 1597.6964376768412, 1808.0869161073656, 8613.784655383619, 9037.819243615131, 9069.138431848973, 9356.719298421058, 9371.578278977446, 9165.679201227958, 9279.164875161234, 9289.0, 9289.349745517817, 9290.163885250326, 2046.4167712202025, 2297.8046866016407, 2364.9408764780887, 2399.3401814500976, 2400.3639035655565, 2361.102780943239, 2387.8089752819733, 2389.82076344274, 2393.234165067179, 2397.832151749378, 2316.328264803205, 2347.4014126397747, 2352.141468364052, 2358.6815779869758, 2389.9761002390014, 2284.0, 2348.283773449106, 2359.0799588160544, 2360.6104992676187, 2382.833201675883, 2411.8311718179734, 2420.322309753272, 2420.3707036170545, 2420.721617014048, 2420.7579242075826, 9580.425174489554, 9638.469884156368, 9662.227021838236, 9670.274729395323, 9671.613135474572, 5269.314989051399, 5270.947290527122, 5305.708186049766, 5360.785568577291, 5451.2640793492765, 3380.5943286805496, 3510.8771193008124, 3526.788392696421, 3572.2855428914063, 3608.6210947850623, 1132.3488993828582, 1183.6094088950626, 1211.176400047967, 1214.095498853351, 2170.4573856535867, 12636.557720479832, 13165.539206127836, 13195.86804131956, 13217.07480476367, 13231.073824832265, 4285.485741711014, 4598.425196850394, 4671.135839869635, 4704.929426058612, 4705.764711764398, 10201.693949181536, 10205.285630005828, 10222.13111885498, 10232.59069637221, 10234.02776736209, 5227.790888364499, 5228.686278823283, 5231.947680523146, 5235.8429247121885, 5236.345456817898, 4270.10327831159, 4759.595434388047, 4841.273808928619, 4892.951070489249, 4895.632827537877, 3733.5519737631657, 3776.244751049801, 3838.788866612307, 3862.787546684959, 3928.6267804559093, 1140.9828852567218, 1158.0851127609262, 1190.1490434339316, 1199.7300607363272, 1200.8739082396223, 812.1715849833137, 834.8163404051099, 835.258043963049, 856.0711627883816, 862.3403096631082, 5692.430756924275, 6071.332153463204, 6146.661933593606, 6180.196574445294, 6267.435930766253, 937.8265020971143, 944.4003058058182, 992.6476100984191, 993.8509223616371, 993.9602415903283, 3890.2414029264587, 3905.6094390561284, 4015.15681706839, 4036.798160091984, 4091.815868285875, 11533.365664888512, 12010.879891200977, 12043.337616431181, 12053.457594408274, 12073.094517911017, 11646.750450980251, 11789.115816313812, 11897.286162830258, 11944.701382465508, 11976.520939162336, 8768.5177315247, 8807.11928807127, 8907.153820387053, 8918.286537077018, 8931.151540603762, 6035.758569657341, 6045.818625441244, 6046.062860256668, 6066.0, 6118.082287656887, 10769.192310576582, 10854.511546980562, 10893.237473376788, 10968.561257549927, 11030.944845275722, 10945.616903408343, 10967.780644387227, 11122.110231181648, 11164.609238676607, 11269.943650281695, 6962.373386395248, 7146.7141314346845, 7169.533980291264, 7672.309492145734, 7944.0, 8317.085120636604, 8534.658613655385, 8810.030896601496, 8854.557272136239, 8883.0, 7969.402294827795, 8022.638981245975, 8108.270255677017, 8200.302974247255, 8249.546274954695, 8039.397045221514, 8071.192880711882, 8139.0, 8193.50838949665, 8540.359473039423, 6724.327567243432, 6877.277885822116, 6900.9654951726925, 7005.404540614105, 7026.1217347831525, 5151.459096794782, 5255.395629502656, 5300.840974770763, 5350.438203988525, 5561.833145005657, 5202.0, 5279.630425870152, 5352.839414817562, 5360.303160589098, 5414.864628384322], "value": 4759.800514409765, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.89505910579066, 330.702367868918, 333.3433136720661, 339.9201187720886, 341.8512946868111, 5127.692338459686, 5130.230465430178, 5133.460986596409, 5153.278541004258, 5177.301064356316, 4804.279358096287, 4864.586510146633, 5004.424491183515, 5011.869955269014, 5053.696778193311, 4066.6340029397347, 4081.1837632473475, 4086.816093275795, 4088.8773336799945, 4096.815643296059, 4096.262672718909, 4105.0, 4114.136031433388, 4114.958850411503, 4132.793360331986, 3874.670652994499, 3898.610138986093, 4068.206699693561, 4070.6743460523235, 4078.632923036926, 4051.210014047262, 4054.1283624020684, 4070.837166513321, 4071.6131967463043, 4093.283675356801, 3622.4385220290897, 3629.3830048891487, 3641.8361173747244, 3644.1071937375514, 3646.890593282206, 4740.95259047412, 4998.225275082332, 5000.149974504343, 5016.147254966664, 5018.139660393577, 4675.929861052087, 4693.78877950493, 4720.645951553613, 4724.527547245248, 4725.149473094842, 5343.305370301836, 5354.732263386815, 5359.892802143974, 5364.892702145935, 5369.463053694639, 3876.6704830089475, 3985.740926839746, 4016.176683779818, 4028.2144981728575, 4059.6549293310104, 5477.7821077769895, 5536.2249285100115, 5540.252065971043, 5556.332820338557, 5569.721513924287, 4091.5908409158847, 4100.507939047334, 4104.0970986382945, 4113.321301985199, 4129.855455059059, 4051.5948405159247, 4161.667066634662, 4168.083021735213, 4184.916301673949, 4188.727732697365, 4587.0, 4597.42532183477, 4647.187596837236, 4658.86023419298, 4701.130290896127, 5321.228421878806, 5358.544523715528, 5369.838904832784, 5377.408485066718, 5378.9462105379225, 1417.6030711400617, 1424.2380326525467, 1436.9353379097965, 1439.7048605035943, 1443.603009172486, 935.904991160344, 939.5724945150077, 950.0, 959.4531117263093, 959.4627008875052, 4534.6372290216705, 4573.794179261941, 4585.426821647294, 4607.2398054320665, 4661.790219440133, 4824.445188803333, 4880.463149053601, 4891.853244402673, 4898.159067740702, 4958.454569997298, 4387.157393894572, 4566.625354815477, 4600.60894823937, 4637.907241855144, 4706.670533062721, 2603.284096873375, 2611.060018393377, 2614.6470226519177, 2615.2415799417954, 2618.3061488705507, 5020.221865610763, 5024.799008039638, 5048.5203905628905, 5054.0, 5057.747112644353, 4792.712437253706, 4874.926876096862, 4882.828121250875, 4912.729799861042, 4997.450790254959, 4866.245731911559, 4869.464358920516, 4913.508649135058, 4954.67794593343, 4960.3551538301235, 4822.132016237111, 4822.397200349957, 4829.637777166657, 4834.365270927283, 4862.562369386772, 4617.445906491243, 4770.904581908412, 4793.472718000948, 4817.397825271841, 4844.563989240985, 4919.852404427873, 4942.418426103613, 4949.331840201527, 4958.8760280993265, 4968.8757781055765, 3716.535433070866, 3747.3816820224356, 3752.380857158538, 3777.640049582093, 3784.5836957935157, 4891.532540237986, 4893.926591101065, 5039.0, 5190.377154741455, 5475.370332411823, 5335.466453354634, 5366.2487251784005, 5371.355437347544, 5373.946260537345, 5378.97310513445, 5359.196120581944, 5361.839144825662, 5380.354357477127, 5394.676319420847, 5394.892102158014, 5117.718525481134, 5253.290805741329, 5274.419813820554, 5276.788928442819, 5286.577073834161, 5360.0, 5360.16917377807, 5374.4088150304215, 5388.488093631099, 5434.266374039612, 5063.645544811828, 5255.868603284948, 5267.525922666979, 5269.077911365419, 5327.360716714019], "value": 3983.9972450293258, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [645.6674812471576, 745.28452685422, 758.2872100225787, 779.5011192836582, 793.5238856685987, 16242.269097890594, 16921.23854426552, 17177.141142942866, 17184.22670979806, 17207.139643017847, 14270.0, 15907.12510811905, 16084.0, 16092.436764713237, 16249.025058496498, 793.2979313307707, 801.5230937592123, 815.0382548592688, 815.2011029191395, 816.1878930464196, 813.3046245460135, 815.808285053012, 816.1308206759811, 816.542736067801, 816.7672213419177, 847.4533925617973, 847.7414388611471, 850.8213275212213, 860.1699360117485, 860.423516244118, 847.2713466418875, 848.2916764501617, 848.7793173774801, 849.133883438895, 850.31974420464, 616.9907451388233, 619.9287081985584, 620.9379062093755, 622.470899735226, 622.7882519943196, 14413.78379324311, 14566.417343306255, 14741.410343586243, 14913.92543037278, 14963.850361496463, 14511.274436278143, 15361.692766144726, 15630.0, 15635.296411661502, 16026.198690065567, 11593.6521904343, 11674.591389301333, 11773.175877688565, 12001.399930003465, 12158.939205304005, 3812.7331086823933, 3831.0, 3867.5358956925347, 3908.218356328746, 3984.442178095069, 53395.46604533944, 64037.0, 67596.6620166896, 69799.0, 81570.0, 11742.0, 11906.940465297617, 12167.574134905413, 12592.922167842204, 12759.298238596966, 22984.310470685905, 23006.309810705705, 23016.769832301798, 23032.309030729106, 23191.88404057969, 8600.13998600135, 8789.780255493664, 8850.601722922484, 9202.987671356146, 9483.336166468282, 4546.727196368229, 4731.952680473151, 4853.102176097434, 5023.321851549994, 5107.948920510822, 3596.5144705464427, 3798.734088613826, 3800.8289626966853, 3803.0492376905772, 3806.276807406584, 618.7741474361826, 663.3466035954581, 688.4320435640617, 700.6111608057577, 712.2450202785064, 653.5817077070678, 680.1905732178692, 680.7855525509548, 687.3744892148121, 690.8998195261659, 9742.171915387129, 10040.411407106196, 10061.79876402468, 10127.70805020384, 10137.442440665687, 519.6388509985524, 617.5646169450526, 633.8130251575797, 641.2689533931322, 656.967151642416, 4519.751413672279, 4747.667663263538, 4781.450133234723, 4830.203016502308, 4840.249761287007, 124407.0, 126341.36829315979, 127891.0, 128674.0, 129451.0, 108740.0, 111561.0, 114073.0, 114320.0, 118256.0, 75360.62319688367, 76859.0, 77759.0, 88131.55934220288, 93595.0, 9872.35829671069, 9920.40477571348, 10004.599816007278, 10105.5452504636, 10559.208059395729, 50980.490195097576, 51727.74136129295, 51837.74081129721, 54468.0, 56057.0, 73719.0, 75490.62254688691, 79848.0, 79894.0, 80924.0, 12692.238465692088, 13049.804252936023, 13139.54011609589, 13505.189688618715, 14062.156270623795, 14783.408663653763, 15608.453704120302, 15926.601834953755, 16862.494125176265, 17156.0, 13562.457501700215, 13564.728705426034, 13848.723025539633, 13979.95880032125, 15544.611384715472, 14139.0, 14515.78226326627, 15202.847971520143, 15300.31148598338, 15812.288447019682, 14321.928390357982, 14580.125192488485, 14582.343794529485, 14591.635209119433, 15060.472883449027, 11435.942820285845, 11612.419379031184, 12075.154739168174, 12125.77612647454, 12281.508739650671, 10994.615188468366, 11721.53512178605, 11820.527178912747, 11868.177813615213, 11951.282923024646], "value": 7777.561869766939, "unit": "score"}], "name": "dromaeo_css", "value": 7167.133217475392, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 110.0, 112.0, 108.0, 112.0, 111.0, 112.0, 111.0, 112.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 123.0, 123.0, 178.0, 119.0, 120.0, 148.0, 117.0, 162.0, 118.0], "value": 135.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 160.0, 159.0, 161.0, 161.0, 160.0, 161.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 78.0, 113.0, 76.0, 76.0, 75.0, 73.0, 99.0, 75.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 125.0, 85.0, 84.0, 85.0, 122.0, 84.0, 85.0, 127.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 145.0, 147.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 94.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 48.0, 51.0, 51.0, 50.0, 52.0, 50.0, 50.0, 52.0, 51.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 62.0, 61.0, 62.0, 62.0, 61.0, 62.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 78.0, 78.0, 78.0, 84.0, 78.0, 81.0, 77.0, 78.0, 78.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 122.0, 122.0, 123.0, 122.0, 134.0, 122.0, 122.0, 139.0, 122.0], "value": 126.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 167.0, 163.0, 165.0, 163.0, 162.0, 165.0, 162.0, 162.0, 163.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 67.0, 66.0, 64.0, 64.0, 67.0, 64.0, 64.0], "value": 65.6, "unit": "score"}], "name": "kraken", "value": 1489.6999999999998, "alertThreshold": 2.0}]} 07:27:58 INFO - Return code: 0 07:27:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:27:58 INFO - # TBPL SUCCESS # 07:27:58 INFO - Running post-action listener: _resource_record_post_action 07:27:58 INFO - [mozharness: 2016-08-17 14:27:58.575362Z] Finished run-tests step (success) 07:27:58 INFO - Running post-run listener: _resource_record_post_run 07:27:58 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 419229696; Read time: 188; Write time: 235992 07:27:58 INFO - TinderboxPrint: CPU usage
13.1% 07:27:58 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 188 07:27:58 INFO - TinderboxPrint: I/O write bytes / time
419,229,696 / 235,992 07:27:58 INFO - TinderboxPrint: CPU idle
8,281.0 (86.9%) 07:27:58 INFO - TinderboxPrint: CPU user
1,204.0 (12.6%) 07:27:58 INFO - TinderboxPrint: Swap in / out
0 / 0 07:27:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 118296576; Read time: 88; Write time: 69436 07:27:58 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289259520; Read time: 88; Write time: 157848 07:27:59 INFO - Running post-run listener: _upload_blobber_files 07:27:59 INFO - Blob upload gear active. 07:27:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:27:59 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 07:27:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:27:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:27:59 INFO - (blobuploader) - INFO - Open directory for files ... 07:27:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:27:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:27:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:28:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:00 INFO - (blobuploader) - INFO - Done attempting. 07:28:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:28:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:28:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:28:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:10 INFO - (blobuploader) - INFO - Done attempting. 07:28:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:28:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:28:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:28:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:28:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:28:11 INFO - (blobuploader) - INFO - Done attempting. 07:28:11 INFO - (blobuploader) - INFO - Iteration through files over. 07:28:11 INFO - Return code: 0 07:28:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:28:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:28:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/878c11411f55cb0006bcc1791fe74056a1ff6edd99048078f6cdaafac6ace668b52ab77648bf6e480a340c4e14c32ba8022fb427d6340a26ca7a4a27412236a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22d2bc25da70193888abc9e075715c06354bd958cb8c35db3c8e51366a544233cd47812f68a8415a0f4ff803e1ec986547729b46c22f0f76492f6eb8d5aeb9fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/95392311bd3a5499ccafe5e1fad2017f45d06a29d851bbe6a7cd4b4b10cbe6dbd407dbc682c24cf35bb0deeee6a642d35b507fd67e88c7b864cbb2300d347bf1"} 07:28:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:28:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:28:11 INFO - Contents: 07:28:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/878c11411f55cb0006bcc1791fe74056a1ff6edd99048078f6cdaafac6ace668b52ab77648bf6e480a340c4e14c32ba8022fb427d6340a26ca7a4a27412236a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22d2bc25da70193888abc9e075715c06354bd958cb8c35db3c8e51366a544233cd47812f68a8415a0f4ff803e1ec986547729b46c22f0f76492f6eb8d5aeb9fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/95392311bd3a5499ccafe5e1fad2017f45d06a29d851bbe6a7cd4b4b10cbe6dbd407dbc682c24cf35bb0deeee6a642d35b507fd67e88c7b864cbb2300d347bf1"} 07:28:11 INFO - Running post-run listener: copy_logs_to_upload_dir 07:28:11 INFO - Copying logs to upload dir... 07:28:11 INFO - mkdir: /builds/slave/test/build/upload/logs 07:28:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.309503 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-08-17 07:28:10.700935) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 07:28:10.704537) ========= 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=00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/878c11411f55cb0006bcc1791fe74056a1ff6edd99048078f6cdaafac6ace668b52ab77648bf6e480a340c4e14c32ba8022fb427d6340a26ca7a4a27412236a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22d2bc25da70193888abc9e075715c06354bd958cb8c35db3c8e51366a544233cd47812f68a8415a0f4ff803e1ec986547729b46c22f0f76492f6eb8d5aeb9fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/95392311bd3a5499ccafe5e1fad2017f45d06a29d851bbe6a7cd4b4b10cbe6dbd407dbc682c24cf35bb0deeee6a642d35b507fd67e88c7b864cbb2300d347bf1"} build_url:https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011314 build_url: 'https://queue.taskcluster.net/v1/task/W4ujJXYfQ265ldJ7kg8Onw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/878c11411f55cb0006bcc1791fe74056a1ff6edd99048078f6cdaafac6ace668b52ab77648bf6e480a340c4e14c32ba8022fb427d6340a26ca7a4a27412236a2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/22d2bc25da70193888abc9e075715c06354bd958cb8c35db3c8e51366a544233cd47812f68a8415a0f4ff803e1ec986547729b46c22f0f76492f6eb8d5aeb9fa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/95392311bd3a5499ccafe5e1fad2017f45d06a29d851bbe6a7cd4b4b10cbe6dbd407dbc682c24cf35bb0deeee6a642d35b507fd67e88c7b864cbb2300d347bf1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-17 07:28:10.732393) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 07:28:10.732751) ========= 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=00204705a9daa57ed339a427000001b9-1471435533.230644-1833912241 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004390 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-17 07:28:10.817186) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 07:28:10.817542) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-17 07:28:10.817868) ========= ========= Total master_lag: 0.22 =========