builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-040 starttime: 1470328860.24 results: success (0) buildid: 20160804092620 builduid: 2a1ccb5f9cd44bc6918970e801d24f30 revision: d97d3eb03261667623768d6d55fb264891044fcd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.239751) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.241395) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.241706) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.256901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.257299) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-04 09:41:00-- 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% 50.0M=0s 2016-08-04 09:41:00 (50.0 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.141961 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.413822) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.414174) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017172 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.464699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:00.465104) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d97d3eb03261667623768d6d55fb264891044fcd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d97d3eb03261667623768d6d55fb264891044fcd --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=9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-04 09:41:00,789 truncating revision to first 12 chars 2016-08-04 09:41:00,789 Setting DEBUG logging. 2016-08-04 09:41:00,789 attempt 1/10 2016-08-04 09:41:00,789 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d97d3eb03261?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-04 09:41:01,100 unpacking tar archive at: autoland-d97d3eb03261/testing/mozharness/ program finished with exit code 0 elapsedTime=0.702838 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:01.183018) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:01.183460) ========= script_repo_revision: d97d3eb03261667623768d6d55fb264891044fcd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:01.183925) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:01.184188) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-04 09:41:01.194999) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-08-04 09:41:01.195372) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:41:01 INFO - MultiFileLogger online at 20160804 09:41:01 in /builds/slave/test 09:41:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 09:41:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:41:01 INFO - {'append_to_log': False, 09:41:01 INFO - 'base_work_dir': '/builds/slave/test', 09:41:01 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:41:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:41:01 INFO - 'branch': 'Autoland-Non-PGO', 09:41:01 INFO - 'buildbot_json_path': 'buildprops.json', 09:41:01 INFO - 'config_files': ('talos/linux_config.py',), 09:41:01 INFO - 'default_actions': ('clobber', 09:41:01 INFO - 'read-buildbot-config', 09:41:01 INFO - 'download-and-extract', 09:41:01 INFO - 'populate-webroot', 09:41:01 INFO - 'create-virtualenv', 09:41:01 INFO - 'install', 09:41:01 INFO - 'run-tests'), 09:41:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:41:01 INFO - 'download_minidump_stackwalk': True, 09:41:01 INFO - 'download_symbols': 'ondemand', 09:41:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:41:01 INFO - 'tooltool.py': '/tools/tooltool.py', 09:41:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:41:01 INFO - '/tools/misc-python/virtualenv.py')}, 09:41:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:41:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:41:01 INFO - 'installer_path': 'installer.exe', 09:41:01 INFO - 'log_level': 'info', 09:41:01 INFO - 'log_name': 'talos', 09:41:01 INFO - 'log_to_console': True, 09:41:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:41:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:41:01 INFO - 'opt_config_files': (), 09:41:01 INFO - 'pip_index': False, 09:41:01 INFO - 'sps_profile': False, 09:41:01 INFO - 'sps_profile_interval': 0, 09:41:01 INFO - 'suite': 'dromaeojs', 09:41:01 INFO - 'system_bits': '32', 09:41:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:41:01 INFO - 'title': 'talos-linux64-ix-040', 09:41:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:41:01 INFO - 'use_talos_json': True, 09:41:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:41:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:41:01 INFO - 'work_dir': 'build'} 09:41:01 INFO - [mozharness: 2016-08-04 16:41:01.616702Z] Running clobber step. 09:41:01 INFO - Running pre-action listener: _resource_record_pre_action 09:41:01 INFO - Running main action method: clobber 09:41:01 INFO - rmtree: /builds/slave/test/build 09:41:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:41:02 INFO - Running post-action listener: _resource_record_post_action 09:41:02 INFO - [mozharness: 2016-08-04 16:41:02.072182Z] Finished clobber step (success) 09:41:02 INFO - [mozharness: 2016-08-04 16:41:02.072283Z] Running read-buildbot-config step. 09:41:02 INFO - Running pre-action listener: _resource_record_pre_action 09:41:02 INFO - Running main action method: read_buildbot_config 09:41:02 INFO - Using buildbot properties: 09:41:02 INFO - { 09:41:02 INFO - "project": "", 09:41:02 INFO - "product": "firefox", 09:41:02 INFO - "script_repo_revision": "production", 09:41:02 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:41:02 INFO - "repository": "", 09:41:02 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 09:41:02 INFO - "buildid": "20160804092620", 09:41:02 INFO - "pgo_build": "False", 09:41:02 INFO - "basedir": "/builds/slave/test", 09:41:02 INFO - "buildnumber": 164, 09:41:02 INFO - "slavename": "talos-linux64-ix-040", 09:41:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:41:02 INFO - "platform": "ubuntu64_hw", 09:41:02 INFO - "scheduler": "tests-autoland-linux64-talos", 09:41:02 INFO - "branch": "autoland", 09:41:02 INFO - "repo_path": "integration/autoland", 09:41:02 INFO - "revision": "d97d3eb03261667623768d6d55fb264891044fcd", 09:41:02 INFO - "stage_platform": "linux64", 09:41:02 INFO - "builduid": "2a1ccb5f9cd44bc6918970e801d24f30", 09:41:02 INFO - "slavebuilddir": "test" 09:41:02 INFO - } 09:41:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 09:41:02 INFO - Running post-action listener: _resource_record_post_action 09:41:02 INFO - [mozharness: 2016-08-04 16:41:02.074728Z] Finished read-buildbot-config step (success) 09:41:02 INFO - [mozharness: 2016-08-04 16:41:02.074817Z] Running download-and-extract step. 09:41:02 INFO - Running pre-action listener: _resource_record_pre_action 09:41:02 INFO - Running main action method: download_and_extract 09:41:02 INFO - mkdir: /builds/slave/test/build/tests 09:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:02 INFO - trying https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:41:02 INFO - Downloading https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:02 INFO - Downloaded 1361 bytes. 09:41:02 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 09:41:02 INFO - Using the following test package requirements: 09:41:02 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:41:02 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:41:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:41:02 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:41:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:41:02 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:41:02 INFO - u'jsshell-linux-x86_64.zip'], 09:41:02 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:41:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:41:02 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 09:41:02 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:41:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:41:02 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:41:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:41:02 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:41:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:41:02 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 09:41:02 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:41:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:41:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:02 INFO - trying https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:41:02 INFO - Downloading https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:03 INFO - Downloaded 20249693 bytes. 09:41:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:41:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:41:04 INFO - Return code: 0 09:41:04 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 09:41: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')]} 09:41:04 INFO - trying https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 09:41:04 INFO - Downloading https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:05 INFO - Downloaded 20249693 bytes. 09:41:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:41:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:41:06 INFO - Return code: 0 09:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:06 INFO - trying https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 09:41:06 INFO - Downloading https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:08 INFO - Downloaded 14413723 bytes. 09:41:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:41:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:41:08 INFO - Return code: 0 09:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:08 INFO - trying https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:41:08 INFO - Downloading https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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 09:41:11 INFO - Downloaded 55653270 bytes. 09:41:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:41:11 INFO - mkdir: /builds/slave/test/properties 09:41:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:41:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:41:11 INFO - Contents: 09:41:11 INFO - build_url:https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 09:41:12 INFO - Running post-action listener: _resource_record_post_action 09:41:12 INFO - Running post-action listener: set_extra_try_arguments 09:41:12 INFO - [mozharness: 2016-08-04 16:41:12.126508Z] Finished download-and-extract step (success) 09:41:12 INFO - [mozharness: 2016-08-04 16:41:12.126740Z] Running populate-webroot step. 09:41:12 INFO - Running pre-action listener: _resource_record_pre_action 09:41:12 INFO - Running main action method: populate_webroot 09:41:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:41:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:41:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:41:12 INFO - '--tppagecycles', 09:41:12 INFO - '7'], 09:41:12 INFO - 'tests': ['tsvgm']}}, 09:41:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:41:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:41:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:41:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:41:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:41:12 INFO - 'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:41:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:41:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:41:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:41:12 INFO - 'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['damp', 'tps']}, 09:41:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:41:12 INFO - 'tests': ['damp', 'tps']}, 09:41:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['dromaeo_dom']}, 09:41:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:41:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['basic_compositor_video']}, 09:41:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:41:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['a11yr', 09:41:12 INFO - 'ts_paint', 09:41:12 INFO - 'tpaint', 09:41:12 INFO - 'sessionrestore', 09:41:12 INFO - 'sessionrestore_no_auto_restore', 09:41:12 INFO - 'tabpaint']}, 09:41:12 INFO - 'other-e10s': {'tests': ['a11yr', 09:41:12 INFO - 'ts_paint', 09:41:12 INFO - 'tpaint', 09:41:12 INFO - 'sessionrestore', 09:41:12 INFO - 'sessionrestore_no_auto_restore', 09:41:12 INFO - 'tabpaint']}, 09:41:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:41:12 INFO - 'ts_paint', 09:41:12 INFO - 'tpaint', 09:41:12 INFO - 'sessionrestore', 09:41:12 INFO - 'sessionrestore_no_auto_restore', 09:41:12 INFO - 'tabpaint']}, 09:41:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:41:12 INFO - 'ts_paint', 09:41:12 INFO - 'tpaint', 09:41:12 INFO - 'sessionrestore', 09:41:12 INFO - 'sessionrestore_no_auto_restore', 09:41:12 INFO - 'tabpaint']}, 09:41:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['a11yr', 09:41:12 INFO - 'ts_paint', 09:41:12 INFO - 'tpaint', 09:41:12 INFO - 'sessionrestore', 09:41:12 INFO - 'sessionrestore_no_auto_restore', 09:41:12 INFO - 'tabpaint']}, 09:41:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['a11yr', 09:41:12 INFO - 'ts_paint', 09:41:12 INFO - 'tpaint', 09:41:12 INFO - 'sessionrestore', 09:41:12 INFO - 'sessionrestore_no_auto_restore', 09:41:12 INFO - 'tabpaint']}, 09:41:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['tsvgx', 09:41:12 INFO - 'tsvgr_opacity', 09:41:12 INFO - 'tart', 09:41:12 INFO - 'tscrollx', 09:41:12 INFO - 'cart']}, 09:41:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:41:12 INFO - 'tsvgr_opacity', 09:41:12 INFO - 'tart', 09:41:12 INFO - 'tscrollx', 09:41:12 INFO - 'cart']}, 09:41:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:41:12 INFO - 'talos_options': ['--disable-e10s'], 09:41:12 INFO - 'tests': ['tp5o']}, 09:41:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:41:12 INFO - 'tests': ['tp5o']}, 09:41:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:41:12 INFO - 'talos_options': ['--disable-e10s', 09:41:12 INFO - '--xperf_path', 09:41:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:41:12 INFO - 'tests': ['tp5n']}, 09:41:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:41:12 INFO - 'talos_options': ['--xperf_path', 09:41:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:41:12 INFO - 'tests': ['tp5n']}}, 09:41:12 INFO - 'talos.zip': {'path': '', 09:41:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:41:12 INFO - Running post-action listener: _resource_record_post_action 09:41:12 INFO - [mozharness: 2016-08-04 16:41:12.148362Z] Finished populate-webroot step (success) 09:41:12 INFO - [mozharness: 2016-08-04 16:41:12.148451Z] Running create-virtualenv step. 09:41:12 INFO - Running pre-action listener: _resource_record_pre_action 09:41:12 INFO - Running main action method: create_virtualenv 09:41:12 INFO - Creating virtualenv /builds/slave/test/build/venv 09:41:12 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 09:41:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:41:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:41:12 INFO - Using real prefix '/usr' 09:41:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:41:12 INFO - Installing distribute.............................................................................................................................................................................................done. 09:41:14 INFO - Installing pip.................done. 09:41:14 INFO - Return code: 0 09:41:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:14 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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:14 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 09:41:14 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 09:41:14 INFO - Using env: {'DISPLAY': ':0', 09:41:14 INFO - 'HOME': '/home/cltbld', 09:41:14 INFO - 'LANG': 'en_US.UTF-8', 09:41:14 INFO - 'LANGUAGE': 'en_US:en', 09:41:14 INFO - 'LOGNAME': 'cltbld', 09:41:14 INFO - 'MAIL': '/var/mail/cltbld', 09:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:14 INFO - 'MOZ_NO_REMOTE': '1', 09:41:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:14 INFO - 'NO_EM_RESTART': '1', 09:41:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:14 INFO - 'PWD': '/builds/slave/test', 09:41:14 INFO - 'SHELL': '/bin/bash', 09:41:14 INFO - 'SHLVL': '1', 09:41:14 INFO - 'TERM': 'linux', 09:41:14 INFO - 'TMOUT': '86400', 09:41:14 INFO - 'USER': 'cltbld', 09:41:14 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:14 INFO - '_': '/tools/buildbot/bin/python'} 09:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:14 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 09:41:14 INFO - Cleaning up... 09:41:14 INFO - Return code: 0 09:41:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:14 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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:14 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 09:41:14 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 09:41:14 INFO - Using env: {'DISPLAY': ':0', 09:41:14 INFO - 'HOME': '/home/cltbld', 09:41:14 INFO - 'LANG': 'en_US.UTF-8', 09:41:14 INFO - 'LANGUAGE': 'en_US:en', 09:41:14 INFO - 'LOGNAME': 'cltbld', 09:41:14 INFO - 'MAIL': '/var/mail/cltbld', 09:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:14 INFO - 'MOZ_NO_REMOTE': '1', 09:41:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:14 INFO - 'NO_EM_RESTART': '1', 09:41:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:14 INFO - 'PWD': '/builds/slave/test', 09:41:14 INFO - 'SHELL': '/bin/bash', 09:41:14 INFO - 'SHLVL': '1', 09:41:14 INFO - 'TERM': 'linux', 09:41:14 INFO - 'TMOUT': '86400', 09:41:14 INFO - 'USER': 'cltbld', 09:41:14 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:14 INFO - '_': '/tools/buildbot/bin/python'} 09:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:15 INFO - Downloading/unpacking psutil>=3.1.1 09:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:41:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:41:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:41:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:41:17 INFO - Installing collected packages: psutil 09:41:17 INFO - Running setup.py install for psutil 09:41:17 INFO - building 'psutil._psutil_linux' extension 09:41:17 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 09:41:17 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 09:41:17 INFO - building 'psutil._psutil_posix' extension 09:41:17 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 09:41:17 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 09:41:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:41:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:41:17 INFO - Successfully installed psutil 09:41:17 INFO - Cleaning up... 09:41:17 INFO - Return code: 0 09:41:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:41:17 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')]} 09:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:17 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')]} 09:41:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:17 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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:17 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 09:41:17 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 09:41:17 INFO - Using env: {'DISPLAY': ':0', 09:41:17 INFO - 'HOME': '/home/cltbld', 09:41:17 INFO - 'LANG': 'en_US.UTF-8', 09:41:17 INFO - 'LANGUAGE': 'en_US:en', 09:41:17 INFO - 'LOGNAME': 'cltbld', 09:41:17 INFO - 'MAIL': '/var/mail/cltbld', 09:41:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:17 INFO - 'MOZ_NO_REMOTE': '1', 09:41:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:17 INFO - 'NO_EM_RESTART': '1', 09:41:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:17 INFO - 'PWD': '/builds/slave/test', 09:41:17 INFO - 'SHELL': '/bin/bash', 09:41:17 INFO - 'SHLVL': '1', 09:41:17 INFO - 'TERM': 'linux', 09:41:17 INFO - 'TMOUT': '86400', 09:41:17 INFO - 'USER': 'cltbld', 09:41:17 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:17 INFO - '_': '/tools/buildbot/bin/python'} 09:41:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:41:17 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 09:41:17 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 09:41:17 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 09:41:17 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 09:41:17 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 09:41:17 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 09:41:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:41:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:41:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:41:19 INFO - Installing collected packages: mozsystemmonitor 09:41:19 INFO - Running setup.py install for mozsystemmonitor 09:41:19 INFO - Successfully installed mozsystemmonitor 09:41:19 INFO - Cleaning up... 09:41:19 INFO - Return code: 0 09:41:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:19 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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:19 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 09:41:19 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 09:41:19 INFO - Using env: {'DISPLAY': ':0', 09:41:19 INFO - 'HOME': '/home/cltbld', 09:41:19 INFO - 'LANG': 'en_US.UTF-8', 09:41:19 INFO - 'LANGUAGE': 'en_US:en', 09:41:19 INFO - 'LOGNAME': 'cltbld', 09:41:19 INFO - 'MAIL': '/var/mail/cltbld', 09:41:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:19 INFO - 'MOZ_NO_REMOTE': '1', 09:41:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:19 INFO - 'NO_EM_RESTART': '1', 09:41:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:19 INFO - 'PWD': '/builds/slave/test', 09:41:19 INFO - 'SHELL': '/bin/bash', 09:41:19 INFO - 'SHLVL': '1', 09:41:19 INFO - 'TERM': 'linux', 09:41:19 INFO - 'TMOUT': '86400', 09:41:19 INFO - 'USER': 'cltbld', 09:41:19 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:19 INFO - '_': '/tools/buildbot/bin/python'} 09:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:20 INFO - Downloading/unpacking jsonschema==2.5.1 09:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:41:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:41:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:41:22 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 09:41:22 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 09:41:22 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 09:41:22 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 09:41:22 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 09:41:22 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 09:41:22 INFO - Downloading functools32-3.2.3-2.tar.gz 09:41:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:41:22 INFO - warning: no files found matching '*.txt' 09:41:22 INFO - no previously-included directories found matching 'build' 09:41:22 INFO - no previously-included directories found matching 'dist' 09:41:22 INFO - no previously-included directories found matching '.git*' 09:41:22 INFO - Installing collected packages: jsonschema, functools32 09:41:22 INFO - Running setup.py install for functools32 09:41:22 INFO - warning: no files found matching '*.txt' 09:41:22 INFO - no previously-included directories found matching 'build' 09:41:22 INFO - no previously-included directories found matching 'dist' 09:41:22 INFO - no previously-included directories found matching '.git*' 09:41:22 INFO - Successfully installed jsonschema functools32 09:41:22 INFO - Cleaning up... 09:41:22 INFO - Return code: 0 09:41:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:41:22 INFO - Using env: {'DISPLAY': ':0', 09:41:22 INFO - 'HOME': '/home/cltbld', 09:41:22 INFO - 'LANG': 'en_US.UTF-8', 09:41:22 INFO - 'LANGUAGE': 'en_US:en', 09:41:22 INFO - 'LOGNAME': 'cltbld', 09:41:22 INFO - 'MAIL': '/var/mail/cltbld', 09:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:22 INFO - 'MOZ_NO_REMOTE': '1', 09:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:22 INFO - 'NO_EM_RESTART': '1', 09:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:22 INFO - 'PWD': '/builds/slave/test', 09:41:22 INFO - 'SHELL': '/bin/bash', 09:41:22 INFO - 'SHLVL': '1', 09:41:22 INFO - 'TERM': 'linux', 09:41:22 INFO - 'TMOUT': '86400', 09:41:22 INFO - 'USER': 'cltbld', 09:41:22 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:22 INFO - '_': '/tools/buildbot/bin/python'} 09:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:41:22 INFO - Cleaning up... 09:41:22 INFO - Return code: 0 09:41:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:41:22 INFO - Using env: {'DISPLAY': ':0', 09:41:22 INFO - 'HOME': '/home/cltbld', 09:41:22 INFO - 'LANG': 'en_US.UTF-8', 09:41:22 INFO - 'LANGUAGE': 'en_US:en', 09:41:22 INFO - 'LOGNAME': 'cltbld', 09:41:22 INFO - 'MAIL': '/var/mail/cltbld', 09:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:22 INFO - 'MOZ_NO_REMOTE': '1', 09:41:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:22 INFO - 'NO_EM_RESTART': '1', 09:41:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:22 INFO - 'PWD': '/builds/slave/test', 09:41:22 INFO - 'SHELL': '/bin/bash', 09:41:22 INFO - 'SHLVL': '1', 09:41:22 INFO - 'TERM': 'linux', 09:41:22 INFO - 'TMOUT': '86400', 09:41:22 INFO - 'USER': 'cltbld', 09:41:22 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:22 INFO - '_': '/tools/buildbot/bin/python'} 09:41:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:22 INFO - Downloading/unpacking blobuploader==1.2.4 09:41:22 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 09:41:22 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 09:41:22 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 09:41:22 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 09:41:22 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 09:41:22 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 09:41:24 INFO - Downloading blobuploader-1.2.4.tar.gz 09:41:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:41:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:41:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:41:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:41:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:41:25 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 09:41:25 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 09:41:25 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 09:41:25 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 09:41:25 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 09:41:25 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 09:41:25 INFO - Downloading docopt-0.6.1.tar.gz 09:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:41:25 INFO - Installing collected packages: blobuploader, requests, docopt 09:41:25 INFO - Running setup.py install for blobuploader 09:41:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:41:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:41:25 INFO - Running setup.py install for requests 09:41:26 INFO - Running setup.py install for docopt 09:41:26 INFO - Successfully installed blobuploader requests docopt 09:41:26 INFO - Cleaning up... 09:41:26 INFO - Return code: 0 09:41:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:41: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')]} 09:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41: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')]} 09:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:26 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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:26 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 09:41:26 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 09:41:26 INFO - Using env: {'DISPLAY': ':0', 09:41:26 INFO - 'HOME': '/home/cltbld', 09:41:26 INFO - 'LANG': 'en_US.UTF-8', 09:41:26 INFO - 'LANGUAGE': 'en_US:en', 09:41:26 INFO - 'LOGNAME': 'cltbld', 09:41:26 INFO - 'MAIL': '/var/mail/cltbld', 09:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:26 INFO - 'MOZ_NO_REMOTE': '1', 09:41:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:26 INFO - 'NO_EM_RESTART': '1', 09:41:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:26 INFO - 'PWD': '/builds/slave/test', 09:41:26 INFO - 'SHELL': '/bin/bash', 09:41:26 INFO - 'SHLVL': '1', 09:41:26 INFO - 'TERM': 'linux', 09:41:26 INFO - 'TMOUT': '86400', 09:41:26 INFO - 'USER': 'cltbld', 09:41:26 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:26 INFO - '_': '/tools/buildbot/bin/python'} 09:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:41:26 INFO - Running setup.py (path:/tmp/pip-wzCuvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:41:26 INFO - Running setup.py (path:/tmp/pip-lexVvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:41:26 INFO - Running setup.py (path:/tmp/pip-72GmKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:41:26 INFO - Running setup.py (path:/tmp/pip-9ICXgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:41:26 INFO - Running setup.py (path:/tmp/pip-UR4c0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:41:26 INFO - Running setup.py (path:/tmp/pip-GY7noj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:41:26 INFO - Running setup.py (path:/tmp/pip-YPXI1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:41:26 INFO - Running setup.py (path:/tmp/pip-vfPf1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:41:27 INFO - Running setup.py (path:/tmp/pip-JToKqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:41:27 INFO - Running setup.py (path:/tmp/pip-Gga6Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:41:27 INFO - Running setup.py (path:/tmp/pip-ryWY8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:41:27 INFO - Running setup.py (path:/tmp/pip-pgtWiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:41:27 INFO - Running setup.py (path:/tmp/pip-zkyvk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:41:27 INFO - Running setup.py (path:/tmp/pip-AC8nRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:27 INFO - Running setup.py (path:/tmp/pip-KUXDfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:41:27 INFO - Running setup.py (path:/tmp/pip-rcI1to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:41:27 INFO - Running setup.py (path:/tmp/pip-CaSdzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:41:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:41:27 INFO - Running setup.py install for manifestparser 09:41:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:41:27 INFO - Running setup.py install for mozcrash 09:41:27 INFO - Running setup.py install for mozdebug 09:41:27 INFO - Running setup.py install for mozdevice 09:41:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Running setup.py install for mozfile 09:41:28 INFO - Running setup.py install for mozhttpd 09:41:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Running setup.py install for mozinfo 09:41:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Running setup.py install for mozInstall 09:41:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Running setup.py install for mozleak 09:41:28 INFO - Running setup.py install for mozlog 09:41:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Running setup.py install for moznetwork 09:41:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Running setup.py install for mozprocess 09:41:28 INFO - Running setup.py install for mozprofile 09:41:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:41:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:41:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:41:29 INFO - Running setup.py install for mozrunner 09:41:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:41:29 INFO - Running setup.py install for mozscreenshot 09:41:29 INFO - Running setup.py install for moztest 09:41:29 INFO - Running setup.py install for mozversion 09:41:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:41:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:41:29 INFO - Cleaning up... 09:41:29 INFO - Return code: 0 09:41:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:29 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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:29 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 09:41:29 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 09:41:29 INFO - Using env: {'DISPLAY': ':0', 09:41:29 INFO - 'HOME': '/home/cltbld', 09:41:29 INFO - 'LANG': 'en_US.UTF-8', 09:41:29 INFO - 'LANGUAGE': 'en_US:en', 09:41:29 INFO - 'LOGNAME': 'cltbld', 09:41:29 INFO - 'MAIL': '/var/mail/cltbld', 09:41:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:29 INFO - 'MOZ_NO_REMOTE': '1', 09:41:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:29 INFO - 'NO_EM_RESTART': '1', 09:41:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:29 INFO - 'PWD': '/builds/slave/test', 09:41:29 INFO - 'SHELL': '/bin/bash', 09:41:29 INFO - 'SHLVL': '1', 09:41:29 INFO - 'TERM': 'linux', 09:41:29 INFO - 'TMOUT': '86400', 09:41:29 INFO - 'USER': 'cltbld', 09:41:29 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:29 INFO - '_': '/tools/buildbot/bin/python'} 09:41:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:41:29 INFO - Running setup.py (path:/tmp/pip-y62IT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:41:29 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)) 09:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:41:29 INFO - Running setup.py (path:/tmp/pip-tsZmgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:41:29 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)) 09:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:41:29 INFO - Running setup.py (path:/tmp/pip-Vbz6Wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:41:29 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)) 09:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:41:29 INFO - Running setup.py (path:/tmp/pip-4wgEyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:41:29 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)) 09:41:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:41:29 INFO - Running setup.py (path:/tmp/pip-62RuaO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:41:30 INFO - Running setup.py (path:/tmp/pip-rkAzhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:41:30 INFO - Running setup.py (path:/tmp/pip-QIHwuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:41:30 INFO - Running setup.py (path:/tmp/pip-TRpoFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:41:30 INFO - Running setup.py (path:/tmp/pip-6BQ6w9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:41:30 INFO - Running setup.py (path:/tmp/pip-1k0mCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:41:30 INFO - Running setup.py (path:/tmp/pip-U4pu48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:41:30 INFO - Running setup.py (path:/tmp/pip-eAY1F_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:41:30 INFO - Running setup.py (path:/tmp/pip-tHJ5Ph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:41:30 INFO - Running setup.py (path:/tmp/pip-7my9Ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:30 INFO - Running setup.py (path:/tmp/pip-Nl6auE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:41:30 INFO - Running setup.py (path:/tmp/pip-DzNDQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:41:30 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)) 09:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:41:30 INFO - Running setup.py (path:/tmp/pip-ulRo9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:41:30 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)) 09:41:30 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)) 09:41:30 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)) 09:41:30 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)) 09:41:30 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)) 09:41:30 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)) 09:41:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:41:30 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 09:41:30 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 09:41:30 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 09:41:30 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 09:41:30 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 09:41:30 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 09:41:32 INFO - Downloading blessings-1.6.tar.gz 09:41:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:41:32 INFO - Installing collected packages: blessings 09:41:32 INFO - Running setup.py install for blessings 09:41:33 INFO - Successfully installed blessings 09:41:33 INFO - Cleaning up... 09:41:33 INFO - Return code: 0 09:41:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:41:33 INFO - Reading from file tmpfile_stdout 09:41:33 INFO - Current package versions: 09:41:33 INFO - argparse == 1.2.1 09:41:33 INFO - blessings == 1.6 09:41:33 INFO - blobuploader == 1.2.4 09:41:33 INFO - docopt == 0.6.1 09:41:33 INFO - functools32 == 3.2.3-2 09:41:33 INFO - jsonschema == 2.5.1 09:41:33 INFO - manifestparser == 1.1 09:41:33 INFO - mozInstall == 1.12 09:41:33 INFO - mozcrash == 0.17 09:41:33 INFO - mozdebug == 0.1 09:41:33 INFO - mozdevice == 0.48 09:41:33 INFO - mozfile == 1.2 09:41:33 INFO - mozhttpd == 0.7 09:41:33 INFO - mozinfo == 0.9 09:41:33 INFO - mozleak == 0.1 09:41:33 INFO - mozlog == 3.3 09:41:33 INFO - moznetwork == 0.27 09:41:33 INFO - mozprocess == 0.23 09:41:33 INFO - mozprofile == 0.28 09:41:33 INFO - mozrunner == 6.12 09:41:33 INFO - mozscreenshot == 0.1 09:41:33 INFO - mozsystemmonitor == 0.3 09:41:33 INFO - moztest == 0.7 09:41:33 INFO - mozversion == 1.4 09:41:33 INFO - psutil == 3.1.1 09:41:33 INFO - requests == 1.2.3 09:41:33 INFO - wsgiref == 0.1.2 09:41:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:33 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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:33 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 09:41:33 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 09:41:33 INFO - Using env: {'DISPLAY': ':0', 09:41:33 INFO - 'HOME': '/home/cltbld', 09:41:33 INFO - 'LANG': 'en_US.UTF-8', 09:41:33 INFO - 'LANGUAGE': 'en_US:en', 09:41:33 INFO - 'LOGNAME': 'cltbld', 09:41:33 INFO - 'MAIL': '/var/mail/cltbld', 09:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:33 INFO - 'MOZ_NO_REMOTE': '1', 09:41:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:33 INFO - 'NO_EM_RESTART': '1', 09:41:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:33 INFO - 'PWD': '/builds/slave/test', 09:41:33 INFO - 'SHELL': '/bin/bash', 09:41:33 INFO - 'SHLVL': '1', 09:41:33 INFO - 'TERM': 'linux', 09:41:33 INFO - 'TMOUT': '86400', 09:41:33 INFO - 'USER': 'cltbld', 09:41:33 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:33 INFO - '_': '/tools/buildbot/bin/python'} 09:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 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)) 09:41:33 INFO - Cleaning up... 09:41:33 INFO - Return code: 0 09:41:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:33 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 0x2973030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2ad68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c34180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, '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': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', '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 09:41:33 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 09:41:33 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 09:41:33 INFO - Using env: {'DISPLAY': ':0', 09:41:33 INFO - 'HOME': '/home/cltbld', 09:41:33 INFO - 'LANG': 'en_US.UTF-8', 09:41:33 INFO - 'LANGUAGE': 'en_US:en', 09:41:33 INFO - 'LOGNAME': 'cltbld', 09:41:33 INFO - 'MAIL': '/var/mail/cltbld', 09:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:33 INFO - 'MOZ_NO_REMOTE': '1', 09:41:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:33 INFO - 'NO_EM_RESTART': '1', 09:41:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:33 INFO - 'PWD': '/builds/slave/test', 09:41:33 INFO - 'SHELL': '/bin/bash', 09:41:33 INFO - 'SHLVL': '1', 09:41:33 INFO - 'TERM': 'linux', 09:41:33 INFO - 'TMOUT': '86400', 09:41:33 INFO - 'USER': 'cltbld', 09:41:33 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:33 INFO - '_': '/tools/buildbot/bin/python'} 09:41:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:41:33 INFO - Cleaning up... 09:41:33 INFO - Return code: 0 09:41:33 INFO - Running post-action listener: _resource_record_post_action 09:41:33 INFO - Running post-action listener: _start_resource_monitoring 09:41:33 INFO - Starting resource monitoring. 09:41:33 INFO - [mozharness: 2016-08-04 16:41:33.758258Z] Finished create-virtualenv step (success) 09:41:33 INFO - [mozharness: 2016-08-04 16:41:33.758751Z] Running install step. 09:41:33 INFO - Running pre-action listener: _resource_record_pre_action 09:41:33 INFO - Running main action method: install 09:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:41:33 INFO - Reading from file tmpfile_stdout 09:41:33 INFO - Detecting whether we're running mozinstall >=1.0... 09:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:41:34 INFO - Reading from file tmpfile_stdout 09:41:34 INFO - Output received: 09:41:34 INFO - Usage: mozinstall [options] installer 09:41:34 INFO - Options: 09:41:34 INFO - -h, --help show this help message and exit 09:41:34 INFO - -d DEST, --destination=DEST 09:41:34 INFO - Directory to install application into. [default: 09:41:34 INFO - "/builds/slave/test"] 09:41:34 INFO - --app=APP Application being installed. [default: firefox] 09:41:34 INFO - mkdir: /builds/slave/test/build/application 09:41:34 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'] 09:41:34 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 09:41:50 INFO - Reading from file tmpfile_stdout 09:41:50 INFO - Output received: 09:41:50 INFO - /builds/slave/test/build/application/firefox/firefox 09:41:50 INFO - Running post-action listener: _resource_record_post_action 09:41:50 INFO - [mozharness: 2016-08-04 16:41:50.694326Z] Finished install step (success) 09:41:50 INFO - [mozharness: 2016-08-04 16:41:50.694574Z] Running run-tests step. 09:41:50 INFO - Running pre-action listener: _resource_record_pre_action 09:41:50 INFO - Running main action method: run_tests 09:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:41:50 INFO - Python 2.7.3 09:41:50 INFO - Return code: 0 09:41:50 INFO - grabbing minidump binary from tooltool 09:41:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:50 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 0x2c323b0>, '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 0x2c332b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c33740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:41:50 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 09:41:50 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 09:41:50 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 09:41:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:41:50 INFO - Return code: 0 09:41:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:41:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:41:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:41:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:41:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:41:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 09:41:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-040 --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 09:41:50 INFO - Using env: {'DISPLAY': ':0', 09:41:50 INFO - 'HOME': '/home/cltbld', 09:41:50 INFO - 'LANG': 'en_US.UTF-8', 09:41:50 INFO - 'LANGUAGE': 'en_US:en', 09:41:50 INFO - 'LOGNAME': 'cltbld', 09:41:50 INFO - 'MAIL': '/var/mail/cltbld', 09:41:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:41:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:41:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:50 INFO - 'MOZ_NO_REMOTE': '1', 09:41:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:41:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:41:50 INFO - 'NO_EM_RESTART': '1', 09:41:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:41:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:50 INFO - 'PWD': '/builds/slave/test', 09:41:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:41:50 INFO - 'SHELL': '/bin/bash', 09:41:50 INFO - 'SHLVL': '1', 09:41:50 INFO - 'TERM': 'linux', 09:41:50 INFO - 'TMOUT': '86400', 09:41:50 INFO - 'USER': 'cltbld', 09:41:50 INFO - 'XDG_SESSION_COOKIE': '9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566', 09:41:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:50 INFO - '_': '/tools/buildbot/bin/python'} 09:41:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-040', '--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 09:41:50 INFO - mozversion application_buildid: 20160804092620 09:41:50 INFO - mozversion application_changeset: d97d3eb03261667623768d6d55fb264891044fcd 09:41:50 INFO - mozversion application_display_name: Nightly 09:41:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:41:50 INFO - mozversion application_name: Firefox 09:41:50 INFO - mozversion application_remotingname: firefox 09:41:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:41:50 INFO - mozversion application_vendor: Mozilla 09:41:50 INFO - mozversion application_version: 51.0a1 09:41:50 INFO - mozversion platform_buildid: 20160804092620 09:41:50 INFO - mozversion platform_changeset: d97d3eb03261667623768d6d55fb264891044fcd 09:41:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:41:50 INFO - mozversion platform_version: 51.0a1 09:41:50 INFO - using testdate: 1470328910 09:41:50 INFO - actual date: 1470328910 09:41:50 INFO - starting webserver on 'localhost:58434' 09:41:50 INFO - SUITE-START | Running 2 tests 09:41:50 INFO - TEST-START | dromaeo_css 09:41:50 INFO - operating with platform_type : linux_ 09:41:50 INFO - Initialising browser for dromaeo_css test... 09:41:50 INFO - TEST-INFO | started process 21678 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuyWt2A/profile http://localhost:58434/getInfo.html) 09:41:55 INFO - PROCESS | 21678 | __metrics Screen width/height:1600/1200 09:41:55 INFO - PROCESS | 21678 | colorDepth:24 09:41:55 INFO - PROCESS | 21678 | Browser inner width/height: 1024/697 09:41:55 INFO - PROCESS | 21678 | __metrics 09:41:56 INFO - PROCESS | 21678 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:41:56 INFO - PROCESS | 21678 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:41:57 INFO - TEST-INFO | 21678: exit 0 09:41:57 INFO - Browser initialized. 09:41:57 INFO - Running cycle 1/1 for dromaeo_css test... 09:41:57 INFO - TEST-INFO | started process 21759 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuyWt2A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:42:08 INFO - PROCESS | 21759 | RSS: Main: 158101504 09:42:08 INFO - PROCESS | 21759 | 09:43:00 INFO - PROCESS | 21759 | 1470328980493 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:45:12 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:58434/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58434/tests/dromaeo/cssquery-ext.html) 09:45:13 INFO - PROCESS | 21759 | RSS: Main: 235470848 09:45:13 INFO - PROCESS | 21759 | 09:48:16 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:58434/tests/dromaeo/cssquery-ext.html (next: http://localhost:58434/tests/dromaeo/cssquery-jquery.html) 09:48:16 INFO - PROCESS | 21759 | RSS: Main: 249217024 09:48:16 INFO - PROCESS | 21759 | 09:51:18 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:58434/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58434/tests/dromaeo/cssquery-mootools.html) 09:51:19 INFO - PROCESS | 21759 | RSS: Main: 189874176 09:51:19 INFO - PROCESS | 21759 | 09:54:21 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:58434/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58434/tests/dromaeo/cssquery-prototype.html) 09:54:22 INFO - PROCESS | 21759 | RSS: Main: 189333504 09:54:22 INFO - PROCESS | 21759 | 09:57:25 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:58434/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58434/tests/dromaeo/cssquery-yui.html) 09:57:25 INFO - PROCESS | 21759 | RSS: Main: 189571072 09:57:25 INFO - PROCESS | 21759 | 10:00:28 INFO - PROCESS | 21759 | Cycle 1(1): loaded http://localhost:58434/tests/dromaeo/cssquery-yui.html (next: http://localhost:58434/tests/dromaeo/cssquery-dojo.html) 10:00:29 INFO - PROCESS | 21759 | RSS: Main: 282112000 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - PROCESS | 21759 | __start_tp_report 10:00:29 INFO - PROCESS | 21759 | _x_x_mozilla_page_load 10:00:29 INFO - PROCESS | 21759 | _x_x_mozilla_page_load_details 10:00:29 INFO - PROCESS | 21759 | |i|pagename|runs| 10:00:29 INFO - PROCESS | 21759 | |0;dojo.html;2525.86107764073;2532.227670560478;2563.307906865146;2568.6917569891602;2569.6659434273543;4523.2988886722615;4618.56123668251;4623.237165867632;4636.397268355114;4653.581177694007;4643.698159619622;4657.278121891104;4667.836625718101;4678.555537223967;4712.858614241578;4253.02180498484;4278.529361770203;4292.957070429303;4307.569243075683;4372.2130016597;4338.826446942118;4380.058287468194;4383.824647014116;4395.670324725643;4398.23030969579;4193.433886425324;4301.268784306644;4310.892227694333;4338.934915976263;4405.339199120125;4222.894427639303;4242.851500197509;4265.104328091104;4278.465191851019;4287.892802679958;4224.746515209097;4231.640310573605;4279.657627389802;4293.377460268275;4324.610785029331;4695.506971767949;4703.2474804031535;4711.93981354196;4754.619630429557;4761.381020467317;4869.586085182764;4887.144749668793;4964.726940018334;4986.252062190701;5056.646034777567;4979.975100124512;5058.392992840846;5059.3169922060415;5060.392752869642;5071.771770270347;4598.126355992351;4726.5746082852365;4727.810887564459;4729.527047295312;4730.361401210861;5032.798688052437;5087.338645976073;5116.079105760999;5150.974245128826;5152.4847515248175;4905.558499735041;4965.851024469272;4967.40391153064;4967.453580106186;4970.378702662168;5002.599792016703;5015.448300686921;5053.36832895888;5054.039732450822;5055.9241611375855;4816.783244753994;4858;4889.364382630307;4945.184044632595;4967.180415231446;4963.702177869267;4966.975165124151;4967.105920934194;4968.527989840959;5000.424951130666;4311.633511151587;4315.395844581761;4321.827126914951;4347.673924455678;4356.281213599784;3679.411294192916;3708.295423869456;3714.294284086015;3734.8879533614036;3741.1021354874633;4275.551067137968;4296.291111966553;4311.956880431218;4332.523422423531;4339;5029.471905449948;5034.84895453137;5034.974825125924;5040.747962601855;5052.444231134572;4175.603317684876;4268.231718290675;4289;4314.697971142053;4331.935020974688;4587.128445595326;4604.97697511517;4613.008203236321;4629.398178236807;4653.139169253699;4761.40482439695;4762.309465127468;4764.4520880099235;4778.761061946889;4805.327254184347;4872.658913875995;4948.82679106244;4980.153373926513;5071.290019397214;5087.796488140433;4696.436427628706;4719.834805781781;4726.858194254178;4738.147133516;4738.384010078667;4915.139850526215;4923.113839508779;4938.037082768826;4941.085899108676;4962.478939711423;4854.75726213695;4993.076280888047;5003.668960593421;5003.724795136302;5050.570701490415;4858.076965376567;4882.511748825231;4906.9018619626695;4938.407391113089;4990.126727822544;4922.901541969069;4934.851954441372;4938.678985865908;4940.827071052496;4986.401631804207;4698;4871.926921096116;4883.487233840538;4885;4924.581410580141;4887.560119589254;4921.9015619688125;4942.802287908444;4963.4540200577185;5032.270320803536;4826.855194344175;4836.879078023077;4844.128056949783;4850.078485087964;4886.9022619546695 10:00:29 INFO - PROCESS | 21759 | |1;ext.html;566.5863919338883;574.9252597162368;575.2378099018802;581.6422899916553;590.5246276747218;28916;39307.606923930754;40218.79890600543;40332.798336008316;44303.335449968195;41383.79308103455;41800;41825.790871045676;41831.58168418315;41855;7514.7369842055405;7650.8469830603635;7656.425768067417;7657.617119144048;7661.157272699998;7696.846063078735;7761.805954851117;7785.532868027908;7787.805304867367;7812.648430820626;7790.376769858398;7829.41279404047;7837.451378403514;7848.175941526115;7851.0578730552415;7811.726589569366;7841.21587841217;7852.842943141134;7853.214678532158;7855.685772569091;7710.730124445618;7790.415718821111;7797.805054873616;7798.25916537928;7798.6490607922215;38711.806440967615;39008;39049.41425878614;39058.41412378816;39075;2929.370185410126;3156.1793933577437;3399.932001359959;3498.8978471781306;3841.3469710149343;26548.60177097345;29147.85426072856;29723;29804.254893627614;29868.70131298703;3550.573931128281;3601.693856022241;3601.8559257629663;3750.7186960977765;3754.868579599728;272151;276316;276533;277751;278521;31470.685293146777;55986;56717.43282567122;56746;56759.43240567624;32032.839835801136;34433.65566344355;34442.82778586141;34461.65538344585;34494.31011379758;13241.139325943976;13275.933620332029;13375.732485350238;13378.598642040753;13387.330633468287;11014.173936954761;11071.5571377144;11073.280236784583;11084.61203857861;11088.501017454233;7864.134945156028;7888.211178882066;7915.722949696733;7994.80012999668;8000.479968802009;3071.447139514864;3083.491223948068;3104.379124175174;3117.6414712308374;3128;858.6093327535956;894.2130924786146;936.1387523478417;990.5294984882267;1004.2267454060333;26707.33231669185;28449.71550284512;28591;29032.56451153192;29088.709112909022;929.0477260807726;945.5319616789733;954.289054654287;960.7934294126657;984.4339504784778;14665.780013299809;15059.933817869114;15197.544073677807;15320.080795152326;15662.138582378078;398816;402999;403696;404580;405193;50111.24833127506;50639.24041139386;50639.7468012665;50679.49320506822;50692.23961640579;1039.9740006499899;1073.7047311989265;1079.8056349856945;1178.8821117888315;1188.5958774016876;28463.715362846106;29657.25856853596;29744.256393590334;30105.397892042474;30281.69718302789;42980.785096074316;43442.999999998734;43562.56437435712;43588.782056090786;43665;251303;251926;252045;252478;252586;25000.249992500256;26165.60751588766;26166.73833261719;26167.084152054587;26183.214503564923;42241.78879105585;42542.36186457144;42651.146977060904;43112;43830;36487.635123648426;36623.45064824083;36831.86835599164;37114.814425927696;37380.81309593543;36352.45471317985;36640.45039324465;36667.44998825073;36703.63296367109;37564.24871502609;36192.457113142795;38636.420453692655;40017.79991100026;40332.59667403289;41715.7914210427;36040;36176;36355.45466817947;39065;39300.410493843185;30201.848990754905;30672.233194170323;31024.689753103085;31068.689313107483;31618.367632647678 10:00:29 INFO - PROCESS | 21759 | |2;jquery.html;339.79782029692325;351.1799947123468;359.88123919106687;366.3863029425712;380.84195059050495;12802.23186608803;16207.513774586752;16302.836971630295;17381.565460863483;17500.912495437504;13955.930220348884;14297.356618952146;15917.283722232498;15975.121368324766;16014.43949461769;4869.366982292297;4940.505949405048;4999.125153098194;5020.523050310233;5062.190049592066;3697.7781333119965;4874.098291816008;4939.28380384844;4944.950550494485;4949.554540091409;5552.77788888444;5762.078067509199;5821.50517206038;5874.3244526879525;5879.265091863517;5678.829635110953;5761.193432919395;5814.186013958049;5847.5614328925285;5851.210086638292;5141.794328226866;5151.7681704323395;5151.918097199555;5153.793848246066;5153.871153221162;21437.67843482349;21449.57100857974;21658.89170554153;21830.126794928183;21937.341879743475;1909.0678231946285;1922.3656193456127;1945.6400565895212;1990.6814909614536;2066.8863212523306;12786.296753678544;13515;13765.38055787492;14123.929380353135;14218.146911185258;1722.3799432204396;1790.3196785221533;1798.8201179881908;1800.0909540682003;1809.509124641252;51796.48203517954;68179.65910170418;68973.65513172452;71002;71477;9190.494522801177;9384.390014649027;9517.809643807224;9573.377730447499;9675.951620241854;13107.410166542528;13153.408096635674;13166.539171128965;13167.012474064291;13170.143940643828;6924.688389022506;6984.720611175598;7081.114860642419;7159;7173.820654483576;6674;6829.282925292872;7009.859802804018;7190.9280907191305;7361.227071157451;5942.346341902387;6021;6073.299476146686;6123.969380153071;6210.72051757672;1686.232764092335;1731.463246393622;1743.9389621363446;1748.4667176511286;1764.2237415537122;814.4461765999059;841.5119230846094;850.1965642467932;887.9067697891758;998.3510718033336;16955.745663815054;17778.37775677845;17979.280828766703;18079.186436610384;18595.907020464812;834.1825011488775;856.3363393370188;885.1944730295529;895.9596818143199;925.9720713267577;9477.573509192103;10210.387376757419;10311.948440257751;10324.277300588887;10443.530041148015;399897;407568;407769;408135;408511;72482;72874.27125728675;73692.26307736961;75818;76900.23099769042;899.7750562359541;903.6159632156307;916.2029034739686;923.0815338737999;929.9256059515222;15375.692486150438;15721.371145154524;16127.67744645077;16271.51185464438;16479.505614831574;34846.12884677904;35460.46809297811;35628.287434250655;35924.46113308355;36549.26901461903;46262;46529.76735116303;46766.76616616895;46966.76516617395;47678.76160619175;6565.080888675494;6717.462602991846;6776.390124888783;7109.75115870942;7242.167150777517;10201.693949181536;11270.154738394489;11356.205065645656;11437.142214333791;11480.598179064027;9039.3672442931;9255.305852060988;9465;9660.661876834278;9864.753381165241;9505.287103467406;9721.70834874955;10278.537465814206;10315.48422578853;10567.577296908037;5206.765695543634;5317.25558421829;9051.547422628973;9399.060093990547;9916.950415247878;7538.133114691881;7561.3194812467145;7593.126790419173;7670.884936725842;7960.402969777174;8642.22220000203;10084.243681723754;10215.642452514126;10345.37927724339;10440.895591043993 10:00:29 INFO - PROCESS | 21759 | |3;mootools.html;1212.7271363943119;1316.0590178022712;1369.8835598974088;1412;1660.1533218058787;6067.908981365277;6859.279775623562;7113.430925525959;7116.3239492248285;7142.67857946393;6912.239653638096;7035.859282814341;7045.612491312976;7223.891641625367;7231.204567497584;2115.4288342147647;2151.9569608607817;2154.762976072631;2156.02978659603;2158.4927542027613;2081.739782527184;2116.5872654832315;2126.5746850629857;2128.0104751290646;2135.1139277199945;2016.7781544030145;2031;2042.0402410866868;2047.7645070816823;2072.678734796109;2084.6143463459234;2098.6537221358535;2098.95802083959;2099.139352865318;2102.432343267309;2038.7178422416043;2082.011044753745;2086.8539202255847;2087.4676957375923;2092.1631347460984;7058.505904586681;7137.35763781257;7198.5320954137815;7222.422206223489;7283.781486555412;4104.979475102606;4243.6605071594195;4258.212230737293;4258.48898132223;4260.084081922401;2794.175718163147;2931.7801164912717;2970.064429704636;2972.7324540791433;2979.8808047678067;924.9306302027308;925.9027802080752;938.1978408460805;944.9669261575881;952.6998995316453;9581.473018983954;9695.51522423876;9737.853932191023;9750.75623109428;9764.658236961743;3907.1013666856215;3928.5482169550296;4064;4068.3694027425795;4097.89755256115;7706.614669266514;7833.33416659579;7834.4124190685925;7842.45102842807;7856.489328193649;4403.295472724413;4407.845725399596;4412.977935110304;4413.624841888475;4427.114577084531;3483.547138871981;3803.201327721154;3921.784301863368;4150.066235097079;4150.06623509714;3319.5850518685165;3323.9501407478906;3431.897043088711;3452.896413107611;3454.4472884338384;993.513178542509;996;999.9350042247231;1003.8996100389902;1029.902159294866;678.060885673346;747.4095464582919;779.3687113438133;827.8261565071282;843.1442086282428;4803.279508073747;4934.062528119646;5020.995800839846;5062.341895553554;5125.256837758504;394.6625635082021;401.528204359876;402.141428051113;408.4812288393723;411.6480409250108;3337.1490269981;3446.9314512501205;3500.6674365935196;3539.4867744177463;3608.386574282326;8750;8989.775255618531;9071.909280907239;9081;9101;9030.142136497023;9037.231835293942;9046.457212567268;9048.592813323416;9053.547322633842;6957.61733104674;7021.122359705037;7023.051887995055;7024.981377700205;7026.718931242693;4850.1997170466475;4904.583110435653;4934.195806629739;4961.1318019345745;5154;8270.048944371474;8372.079071302274;8406.663733450594;8460.873086903579;8489.74530764078;8089;8461.534615596202;8461.830763384822;8485.278751306209;8666.826663466822;5364.892702146013;5870.119482077723;5895.7052147393315;5925.140854576149;5930.881382372415;6286.717097730704;6318.178636777189;6632.403083722488;6803.455723542006;6826.283240259701;6232.937670623236;6238.594491358047;6266.185395898504;6306.65313407767;6361.427471527585;6078.939210608014;6087.026075827846;6089.33017368098;6170.598911070766;6315.5894866833505;5760.654360738369;5907.616004959664;5962.761489540543;6057.57596968208;6057.848553786191;4074.327735923658;4148.62662360389;4215.810288536962;4270.188664153801;4397.516273209881;4237.343211802176;4280.636145927507;4300.84947026865;4309.676774242007;4430.069685366109 10:00:29 INFO - PROCESS | 21759 | |4;prototype.html;290.2830009875606;294.4523186872417;300.24338666560254;300.6662604508995;302.8955010521369;4664.790084446204;4694.248920172773;4711.363965864607;4729.952700472994;4750.603318627181;4684.836030738925;4696.718196908188;4697.2954056891485;4735.4317481902135;4767.189577771778;3532.9116772080647;3585.533880595532;3625.8912232632933;3720.869769558059;3727.2545490901794;3660.469231961364;3666.2484190740834;3681.926361472769;3682.8342724577324;3684.355237833382;3667.9266414671824;3671.6695497405226;3676.356637588411;3684.5762737285154;3703.351913415155;3545.7340699447645;3578.3916734155255;3693.7045036397158;3712.4964389422325;3741.214344987529;3282.7537934654756;3286.8356582170795;3290.555774970372;3291.0949488890747;3314.1880239341276;4619.159217081505;4621.1219868224925;4644.396228490308;4645.767711614406;4648.1865673507;4191;4238.7032907696475;4262.275413179736;4271.864067966017;4277.700560960734;4888.584470320027;4896.632752543574;4898.314236006961;4907.730074845882;4908.288298196777;3608.1520842601967;3609.675129238381;3610.9097272568397;3613.765105268149;3623.148560088377;5184.740762961875;5187.948120518822;5203.609729270282;5210.713410762407;5211.452797456251;3392.3045775615938;3558.0749005258817;3576.2311103112434;3589.8025608591256;3727.440883867387;3618.656227658421;3630.6006339302658;3637.9991004947174;3642.817859107034;3646.872359467406;4135.379693046068;4144.849914065332;4156.480439945007;4168.43726096973;4175.31107367281;4648;4931.852044438672;4957.603391728725;4972;4994.700317980933;1244.1850587864963;1264.4310060472824;1275.4069357748692;1281.35932033983;1285.1068507387463;797.1028951096354;812.8374325134997;819.7171975668385;821.8643923752634;824.3158178711655;3972;4204.162190521386;4221.78891055446;4373.475182978063;4390.297552391602;5087.821926232638;5088.211327244283;5101.158308879068;5115.820946266863;5117.232415137684;4371.78141092944;4376.934345984812;4379.474463064389;4383;4384.276594361894;2616.8691565421655;2654.5487267164244;2675.7725593324394;2682.3964607963444;2699.3521554827093;4755.2153894607;4765.309030190603;4767.141914455362;4771.212749896228;4779.641526885428;5140.434552199255;5190.480951904779;5194.22086687;5203.687778733288;5204.271402003723;4547.613452856478;4714.4106986626675;4794.400699912511;4798.272621856198;4803.687760295569;5074.16276314404;5102;5107.9489205107475;5112.667676601009;5130.666506677054;5021.698698078127;5024.29659847629;5051.4340554428245;5075;5082.136036873814;4711;4781.545753153445;4803.663743538059;4810.628970743322;4814.638902082288;3613.2412193439673;3626.637336266352;3637.654422829827;3657.5976642569826;3658.3780757270797;5151.433342332415;5157.381114266312;5160.458151893997;5163.483651634807;5186.559142472932;4784.856454306377;4912.066707325596;4969.776360063878;4981.651284410056;5001.149804533311;4887.242477416006;4887.462379138363;4901.436334821398;4905.23968784839;4911.435184953633;4874.7562621868055;4882;4883.755812209304;4891.828785992473;4906.748779061317;4710.411198600175;4750.8337208197545;4935.62982776286;4956.727379994135;5011.824586139468;4684.688287279567;4760.833370832004;4807.7836497357;4807.918218400831;4808.759562021815 10:00:29 INFO - PROCESS | 21759 | |5;yui.html;635.4916067146282;638.7125793392971;692.5048590257966;709.6948312225741;710.6233696141045;13178.20930744156;14377.209253491053;14380.209088500129;14390.784138237908;14406.135631862082;13183;13612.795808062887;14171.220582867938;14240.430382784676;14727.779083313759;641.5348872067736;771.7453240430673;788.9644965976516;789.1595450844832;789.9210078992112;752.1200195770933;760.4828716472796;761.9771406857775;784.6900474312644;784.8508783331177;816.7386436340373;819.5328662662284;820.6799348254185;821.1542111625006;821.195228675898;671.1576970901536;808.6441965535175;809.2554849538438;809.9028116626013;810.2464707821723;585.1719816459704;585.8476796033019;587.5417174603816;601.0653433910268;604.2265899648457;13020.86979130206;13031.087823852335;13070;13073.934630326787;13085.607431777062;12351.629451116482;12629.557965471256;12912.612621621367;13050.673733156653;13380.598582042554;11526.769464610745;11563.479643416067;11987.280763154151;12193.512259509609;12355.75288494225;4110.588941105865;4173.8956526087095;4190.036291652907;4256.87229383119;4303.225419424502;73136.63431682861;73381;74999.62500187519;76274.61862690651;76595;10051.145652619609;10240.948795256125;10376.16990640747;10384.42885641297;10550.472476376304;19053.237870485304;19069.61860762777;19094.71357929632;19097.331593394163;19116.904415477835;6557.704903279364;6577.909959181195;6654.83362915931;6662.86674266512;7017.859642807115;3720.6465385788306;3776.490173826553;3785.6971442284553;3884.6892248620043;3934.724569280123;2765.820221685584;2865.584490248902;2865.9570106448004;2868.512352900012;2877.798554101235;465.8765427161805;472.35523510408046;477.136383146499;551.1897510659325;580.3181262017113;530.5543343591402;544.2625242796033;558.7681112338335;560.1849309255024;562.8086450606731;7596.962015189889;7974.362051035903;8022.518648881086;8113.918860811435;8114.229148230908;564.8277275430951;579.5711173731493;586.1091141464949;591.1871177131444;591.9674417906971;3765.284595926765;3779.905502362463;3782.9218672678703;3939.094008378032;3956.723029387973;61073;82067.5896620525;106476.46761766142;107168.92831071746;107328.46335768428;56303.43696563064;73991.63004185053;89611;95405;97074;83240.58379708063;83247.5837620808;83251;83560.58219708984;84325.15674843418;7142.107236595426;7263.6005019724425;7269.382102521345;7547.245275472409;7604.961975190088;47405.48943551209;47873.5212647883;48305;49271.260931085344;50053;61315.38684613097;61384;70143.64928175327;73707.63146184235;73818;9756.951215243878;9877;10312.432816195182;10442.425666588108;10721.142308615135;11069.335839849635;11262.549498019987;11325.603603873826;12048.879511204776;12463;10293.43386113771;10361.948190259;10588.788224235626;10768.69703127317;10832.350058996486;9979.700608981742;10132.138768204784;10144.79710405773;10361.948190259;10474.790504190027;8720.607572659372;8777;9957.601695932082;10085.142762864945;10324;8262.586870656323;8867.866981995405;9156.862647060432;9182.862257066015;9319.114684105;8346.374021948257;8708.78228044304;8925.062868398983;8934.28525717928;9099.818003639757 10:00:29 INFO - PROCESS | 21759 | __end_tp_report 10:00:29 INFO - PROCESS | 21759 | __start_cc_report 10:00:29 INFO - PROCESS | 21759 | _x_x_mozilla_cycle_collect,1138 10:00:29 INFO - PROCESS | 21759 | __end_cc_report 10:00:29 INFO - PROCESS | 21759 | __startTimestamp1470330029183__endTimestamp 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - PROCESS | 21759 | ------- Summary: start ------- 10:00:29 INFO - PROCESS | 21759 | Number of tests: 6 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - PROCESS | 21759 | [#0] dojo.html Cycles:165 Average:4635.10 Median:4762.31 stddev:492.99 (10.4%) stddev-sans-first:465.91 10:00:29 INFO - PROCESS | 21759 | Values: 2525.9 2532.2 2563.3 2568.7 2569.7 4523.3 4618.6 4623.2 4636.4 4653.6 4643.7 4657.3 4667.8 4678.6 4712.9 4253.0 4278.5 4293.0 4307.6 4372.2 4338.8 4380.1 4383.8 4395.7 4398.2 4193.4 4301.3 4310.9 4338.9 4405.3 4222.9 4242.9 4265.1 4278.5 4287.9 4224.7 4231.6 4279.7 4293.4 4324.6 4695.5 4703.2 4711.9 4754.6 4761.4 4869.6 4887.1 4964.7 4986.3 5056.6 4980.0 5058.4 5059.3 5060.4 5071.8 4598.1 4726.6 4727.8 4729.5 4730.4 5032.8 5087.3 5116.1 5151.0 5152.5 4905.6 4965.9 4967.4 4967.5 4970.4 5002.6 5015.4 5053.4 5054.0 5055.9 4816.8 4858.0 4889.4 4945.2 4967.2 4963.7 4967.0 4967.1 4968.5 5000.4 4311.6 4315.4 4321.8 4347.7 4356.3 3679.4 3708.3 3714.3 3734.9 3741.1 4275.6 4296.3 4312.0 4332.5 4339.0 5029.5 5034.8 5035.0 5040.7 5052.4 4175.6 4268.2 4289.0 4314.7 4331.9 4587.1 4605.0 4613.0 4629.4 4653.1 4761.4 4762.3 4764.5 4778.8 4805.3 4872.7 4948.8 4980.2 5071.3 5087.8 4696.4 4719.8 4726.9 4738.1 4738.4 4915.1 4923.1 4938.0 4941.1 4962.5 4854.8 4993.1 5003.7 5003.7 5050.6 4858.1 4882.5 4906.9 4938.4 4990.1 4922.9 4934.9 4938.7 4940.8 4986.4 4698.0 4871.9 4883.5 4885.0 4924.6 4887.6 4921.9 4942.8 4963.5 5032.3 4826.9 4836.9 4844.1 4850.1 4886.9 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - PROCESS | 21759 | [#1] ext.html Cycles:180 Average:46319.37 Median:20702.80 stddev:83701.88 (404.3%) stddev-sans-first:83866.19 10:00:29 INFO - PROCESS | 21759 | Values: 566.6 574.9 575.2 581.6 590.5 28916.0 39307.6 40218.8 40332.8 44303.3 41383.8 41800.0 41825.8 41831.6 41855.0 7514.7 7650.8 7656.4 7657.6 7661.2 7696.8 7761.8 7785.5 7787.8 7812.6 7790.4 7829.4 7837.5 7848.2 7851.1 7811.7 7841.2 7852.8 7853.2 7855.7 7710.7 7790.4 7797.8 7798.3 7798.6 38711.8 39008.0 39049.4 39058.4 39075.0 2929.4 3156.2 3399.9 3498.9 3841.3 26548.6 29147.9 29723.0 29804.3 29868.7 3550.6 3601.7 3601.9 3750.7 3754.9 272151.0 276316.0 276533.0 277751.0 278521.0 31470.7 55986.0 56717.4 56746.0 56759.4 32032.8 34433.7 34442.8 34461.7 34494.3 13241.1 13275.9 13375.7 13378.6 13387.3 11014.2 11071.6 11073.3 11084.6 11088.5 7864.1 7888.2 7915.7 7994.8 8000.5 3071.4 3083.5 3104.4 3117.6 3128.0 858.6 894.2 936.1 990.5 1004.2 26707.3 28449.7 28591.0 29032.6 29088.7 929.0 945.5 954.3 960.8 984.4 14665.8 15059.9 15197.5 15320.1 15662.1 398816.0 402999.0 403696.0 404580.0 405193.0 50111.2 50639.2 50639.7 50679.5 50692.2 1040.0 1073.7 1079.8 1178.9 1188.6 28463.7 29657.3 29744.3 30105.4 30281.7 42980.8 43443.0 43562.6 43588.8 43665.0 251303.0 251926.0 252045.0 252478.0 252586.0 25000.2 26165.6 26166.7 26167.1 26183.2 42241.8 42542.4 42651.1 43112.0 43830.0 36487.6 36623.5 36831.9 37114.8 37380.8 36352.5 36640.5 36667.4 36703.6 37564.2 36192.5 38636.4 40017.8 40332.6 41715.8 36040.0 36176.0 36355.5 39065.0 39300.4 30201.8 30672.2 31024.7 31068.7 31618.4 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - PROCESS | 21759 | [#2] jquery.html Cycles:180 Average:24466.79 Median:4942.73 stddev:66855.82 (1352.6%) stddev-sans-first:67018.82 10:00:29 INFO - PROCESS | 21759 | Values: 339.8 351.2 359.9 366.4 380.8 12802.2 16207.5 16302.8 17381.6 17500.9 13955.9 14297.4 15917.3 15975.1 16014.4 4869.4 4940.5 4999.1 5020.5 5062.2 3697.8 4874.1 4939.3 4945.0 4949.6 5552.8 5762.1 5821.5 5874.3 5879.3 5678.8 5761.2 5814.2 5847.6 5851.2 5141.8 5151.8 5151.9 5153.8 5153.9 21437.7 21449.6 21658.9 21830.1 21937.3 1909.1 1922.4 1945.6 1990.7 2066.9 12786.3 13515.0 13765.4 14123.9 14218.1 1722.4 1790.3 1798.8 1800.1 1809.5 51796.5 68179.7 68973.7 71002.0 71477.0 9190.5 9384.4 9517.8 9573.4 9676.0 13107.4 13153.4 13166.5 13167.0 13170.1 6924.7 6984.7 7081.1 7159.0 7173.8 6674.0 6829.3 7009.9 7190.9 7361.2 5942.3 6021.0 6073.3 6124.0 6210.7 1686.2 1731.5 1743.9 1748.5 1764.2 814.4 841.5 850.2 887.9 998.4 16955.7 17778.4 17979.3 18079.2 18595.9 834.2 856.3 885.2 896.0 926.0 9477.6 10210.4 10311.9 10324.3 10443.5 399897.0 407568.0 407769.0 408135.0 408511.0 72482.0 72874.3 73692.3 75818.0 76900.2 899.8 903.6 916.2 923.1 929.9 15375.7 15721.4 16127.7 16271.5 16479.5 34846.1 35460.5 35628.3 35924.5 36549.3 46262.0 46529.8 46766.8 46966.8 47678.8 6565.1 6717.5 6776.4 7109.8 7242.2 10201.7 11270.2 11356.2 11437.1 11480.6 9039.4 9255.3 9465.0 9660.7 9864.8 9505.3 9721.7 10278.5 10315.5 10567.6 5206.8 5317.3 9051.5 9399.1 9917.0 7538.1 7561.3 7593.1 7670.9 7960.4 8642.2 10084.2 10215.6 10345.4 10440.9 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - PROCESS | 21759 | [#3] mootools.html Cycles:180 Average:4753.62 Median:5139.63 stddev:2630.18 (51.2%) stddev-sans-first:2624.10 10:00:29 INFO - PROCESS | 21759 | Values: 1212.7 1316.1 1369.9 1412.0 1660.2 6067.9 6859.3 7113.4 7116.3 7142.7 6912.2 7035.9 7045.6 7223.9 7231.2 2115.4 2152.0 2154.8 2156.0 2158.5 2081.7 2116.6 2126.6 2128.0 2135.1 2016.8 2031.0 2042.0 2047.8 2072.7 2084.6 2098.7 2099.0 2099.1 2102.4 2038.7 2082.0 2086.9 2087.5 2092.2 7058.5 7137.4 7198.5 7222.4 7283.8 4105.0 4243.7 4258.2 4258.5 4260.1 2794.2 2931.8 2970.1 2972.7 2979.9 924.9 925.9 938.2 945.0 952.7 9581.5 9695.5 9737.9 9750.8 9764.7 3907.1 3928.5 4064.0 4068.4 4097.9 7706.6 7833.3 7834.4 7842.5 7856.5 4403.3 4407.8 4413.0 4413.6 4427.1 3483.5 3803.2 3921.8 4150.1 4150.1 3319.6 3324.0 3431.9 3452.9 3454.4 993.5 996.0 999.9 1003.9 1029.9 678.1 747.4 779.4 827.8 843.1 4803.3 4934.1 5021.0 5062.3 5125.3 394.7 401.5 402.1 408.5 411.6 3337.1 3446.9 3500.7 3539.5 3608.4 8750.0 8989.8 9071.9 9081.0 9101.0 9030.1 9037.2 9046.5 9048.6 9053.5 6957.6 7021.1 7023.1 7025.0 7026.7 4850.2 4904.6 4934.2 4961.1 5154.0 8270.0 8372.1 8406.7 8460.9 8489.7 8089.0 8461.5 8461.8 8485.3 8666.8 5364.9 5870.1 5895.7 5925.1 5930.9 6286.7 6318.2 6632.4 6803.5 6826.3 6232.9 6238.6 6266.2 6306.7 6361.4 6078.9 6087.0 6089.3 6170.6 6315.6 5760.7 5907.6 5962.8 6057.6 6057.8 4074.3 4148.6 4215.8 4270.2 4397.5 4237.3 4280.6 4300.8 4309.7 4430.1 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - PROCESS | 21759 | [#4] prototype.html Cycles:180 Average:4105.03 Median:4695.48 stddev:1193.28 (25.4%) stddev-sans-first:1161.77 10:00:29 INFO - PROCESS | 21759 | Values: 290.3 294.5 300.2 300.7 302.9 4664.8 4694.2 4711.4 4730.0 4750.6 4684.8 4696.7 4697.3 4735.4 4767.2 3532.9 3585.5 3625.9 3720.9 3727.3 3660.5 3666.2 3681.9 3682.8 3684.4 3667.9 3671.7 3676.4 3684.6 3703.4 3545.7 3578.4 3693.7 3712.5 3741.2 3282.8 3286.8 3290.6 3291.1 3314.2 4619.2 4621.1 4644.4 4645.8 4648.2 4191.0 4238.7 4262.3 4271.9 4277.7 4888.6 4896.6 4898.3 4907.7 4908.3 3608.2 3609.7 3610.9 3613.8 3623.1 5184.7 5187.9 5203.6 5210.7 5211.5 3392.3 3558.1 3576.2 3589.8 3727.4 3618.7 3630.6 3638.0 3642.8 3646.9 4135.4 4144.8 4156.5 4168.4 4175.3 4648.0 4931.9 4957.6 4972.0 4994.7 1244.2 1264.4 1275.4 1281.4 1285.1 797.1 812.8 819.7 821.9 824.3 3972.0 4204.2 4221.8 4373.5 4390.3 5087.8 5088.2 5101.2 5115.8 5117.2 4371.8 4376.9 4379.5 4383.0 4384.3 2616.9 2654.5 2675.8 2682.4 2699.4 4755.2 4765.3 4767.1 4771.2 4779.6 5140.4 5190.5 5194.2 5203.7 5204.3 4547.6 4714.4 4794.4 4798.3 4803.7 5074.2 5102.0 5107.9 5112.7 5130.7 5021.7 5024.3 5051.4 5075.0 5082.1 4711.0 4781.5 4803.7 4810.6 4814.6 3613.2 3626.6 3637.7 3657.6 3658.4 5151.4 5157.4 5160.5 5163.5 5186.6 4784.9 4912.1 4969.8 4981.7 5001.1 4887.2 4887.5 4901.4 4905.2 4911.4 4874.8 4882.0 4883.8 4891.8 4906.7 4710.4 4750.8 4935.6 4956.7 5011.8 4684.7 4760.8 4807.8 4807.9 4808.8 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - PROCESS | 21759 | [#5] yui.html Cycles:180 Average:18291.87 Median:28433.12 stddev:26867.36 (94.5%) stddev-sans-first:26910.02 10:00:29 INFO - PROCESS | 21759 | Values: 635.5 638.7 692.5 709.7 710.6 13178.2 14377.2 14380.2 14390.8 14406.1 13183.0 13612.8 14171.2 14240.4 14727.8 641.5 771.7 789.0 789.2 789.9 752.1 760.5 762.0 784.7 784.9 816.7 819.5 820.7 821.2 821.2 671.2 808.6 809.3 809.9 810.2 585.2 585.8 587.5 601.1 604.2 13020.9 13031.1 13070.0 13073.9 13085.6 12351.6 12629.6 12912.6 13050.7 13380.6 11526.8 11563.5 11987.3 12193.5 12355.8 4110.6 4173.9 4190.0 4256.9 4303.2 73136.6 73381.0 74999.6 76274.6 76595.0 10051.1 10240.9 10376.2 10384.4 10550.5 19053.2 19069.6 19094.7 19097.3 19116.9 6557.7 6577.9 6654.8 6662.9 7017.9 3720.6 3776.5 3785.7 3884.7 3934.7 2765.8 2865.6 2866.0 2868.5 2877.8 465.9 472.4 477.1 551.2 580.3 530.6 544.3 558.8 560.2 562.8 7597.0 7974.4 8022.5 8113.9 8114.2 564.8 579.6 586.1 591.2 592.0 3765.3 3779.9 3782.9 3939.1 3956.7 61073.0 82067.6 106476.5 107168.9 107328.5 56303.4 73991.6 89611.0 95405.0 97074.0 83240.6 83247.6 83251.0 83560.6 84325.2 7142.1 7263.6 7269.4 7547.2 7605.0 47405.5 47873.5 48305.0 49271.3 50053.0 61315.4 61384.0 70143.6 73707.6 73818.0 9757.0 9877.0 10312.4 10442.4 10721.1 11069.3 11262.5 11325.6 12048.9 12463.0 10293.4 10361.9 10588.8 10768.7 10832.4 9979.7 10132.1 10144.8 10361.9 10474.8 8720.6 8777.0 9957.6 10085.1 10324.0 8262.6 8867.9 9156.9 9182.9 9319.1 8346.4 8708.8 8925.1 8934.3 9099.8 10:00:29 INFO - PROCESS | 21759 | -------- Summary: end -------- 10:00:29 INFO - PROCESS | 21759 | 10:00:29 INFO - TEST-INFO | 21759: exit 0 10:00:30 INFO - TEST-OK | dromaeo_css | took 1119033ms 10:00:30 INFO - TEST-START | kraken 10:00:30 INFO - operating with platform_type : linux_ 10:00:30 INFO - Initialising browser for kraken test... 10:00:30 INFO - TEST-INFO | started process 21949 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdh8vCK/profile http://localhost:58434/getInfo.html) 10:00:34 INFO - PROCESS | 21949 | __metrics Screen width/height:1600/1200 10:00:34 INFO - PROCESS | 21949 | colorDepth:24 10:00:34 INFO - PROCESS | 21949 | Browser inner width/height: 1024/697 10:00:34 INFO - PROCESS | 21949 | __metrics 10:00:35 INFO - PROCESS | 21949 | 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" 10:00:35 INFO - PROCESS | 21949 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:00:36 INFO - TEST-INFO | 21949: exit 0 10:00:36 INFO - Browser initialized. 10:00:36 INFO - Running cycle 1/1 for kraken test... 10:00:36 INFO - TEST-INFO | started process 22034 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpdh8vCK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:48 INFO - PROCESS | 22034 | RSS: Main: 157954048 10:00:48 INFO - PROCESS | 22034 | 10:00:50 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58434/tests/kraken/driver.html?testName=audio-beat-detection) 10:00:51 INFO - PROCESS | 22034 | RSS: Main: 214478848 10:00:51 INFO - PROCESS | 22034 | 10:00:54 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58434/tests/kraken/driver.html?testName=audio-dft) 10:00:55 INFO - PROCESS | 22034 | RSS: Main: 193273856 10:00:55 INFO - PROCESS | 22034 | 10:00:58 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58434/tests/kraken/driver.html?testName=audio-fft) 10:00:59 INFO - PROCESS | 22034 | RSS: Main: 272220160 10:00:59 INFO - PROCESS | 22034 | 10:01:01 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58434/tests/kraken/driver.html?testName=audio-oscillator) 10:01:01 INFO - PROCESS | 22034 | RSS: Main: 180850688 10:01:01 INFO - PROCESS | 22034 | 10:01:04 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58434/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:01:04 INFO - PROCESS | 22034 | RSS: Main: 184745984 10:01:04 INFO - PROCESS | 22034 | 10:01:07 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58434/tests/kraken/driver.html?testName=imaging-darkroom) 10:01:08 INFO - PROCESS | 22034 | RSS: Main: 222113792 10:01:08 INFO - PROCESS | 22034 | 10:01:12 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58434/tests/kraken/driver.html?testName=imaging-desaturate) 10:01:12 INFO - PROCESS | 22034 | RSS: Main: 215666688 10:01:12 INFO - PROCESS | 22034 | 10:01:15 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58434/tests/kraken/driver.html?testName=json-parse-financial) 10:01:16 INFO - PROCESS | 22034 | RSS: Main: 218873856 10:01:16 INFO - PROCESS | 22034 | 10:01:17 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58434/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:01:18 INFO - PROCESS | 22034 | RSS: Main: 183496704 10:01:18 INFO - PROCESS | 22034 | 10:01:20 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58434/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:01:21 INFO - PROCESS | 22034 | RSS: Main: 189403136 10:01:21 INFO - PROCESS | 22034 | 10:01:23 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58434/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:01:23 INFO - PROCESS | 22034 | RSS: Main: 193499136 10:01:23 INFO - PROCESS | 22034 | 10:01:26 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58434/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:01:27 INFO - PROCESS | 22034 | RSS: Main: 189050880 10:01:27 INFO - PROCESS | 22034 | 10:01:30 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58434/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:01:30 INFO - PROCESS | 22034 | RSS: Main: 192999424 10:01:30 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | Cycle 1(1): loaded http://localhost:58434/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58434/tests/kraken/driver.html?testName=ai-astar) 10:01:32 INFO - PROCESS | 22034 | RSS: Main: 244322304 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | __start_tp_report 10:01:32 INFO - PROCESS | 22034 | _x_x_mozilla_page_load 10:01:32 INFO - PROCESS | 22034 | _x_x_mozilla_page_load_details 10:01:32 INFO - PROCESS | 22034 | |i|pagename|runs| 10:01:32 INFO - PROCESS | 22034 | |0;ai-astar;104;113;114;113;113;114;114;113;113;113 10:01:32 INFO - PROCESS | 22034 | |1;audio-beat-detection;156;126;125;186;125;152;143;125;191;130 10:01:32 INFO - PROCESS | 22034 | |2;audio-dft;162;160;161;161;160;162;161;162;160;162 10:01:32 INFO - PROCESS | 22034 | |3;audio-fft;75;76;75;121;76;77;76;75;98;75 10:01:32 INFO - PROCESS | 22034 | |4;audio-oscillator;85;85;125;86;85;85;125;84;86;125 10:01:32 INFO - PROCESS | 22034 | |5;imaging-gaussian-blur;117;117;117;117;116;117;117;117;117;119 10:01:32 INFO - PROCESS | 22034 | |6;imaging-darkroom;147;147;147;147;146;146;147;148;147;147 10:01:32 INFO - PROCESS | 22034 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;94;95 10:01:32 INFO - PROCESS | 22034 | |8;json-parse-financial;63;70;64;64;64;63;63;63;63;67 10:01:32 INFO - PROCESS | 22034 | |9;json-stringify-tinderbox;65;65;65;65;65;65;68;69;64;65 10:01:32 INFO - PROCESS | 22034 | |10;stanford-crypto-aes;83;86;79;79;88;82;85;80;82;83 10:01:32 INFO - PROCESS | 22034 | |11;stanford-crypto-ccm;140;129;145;131;137;128;125;129;205;133 10:01:32 INFO - PROCESS | 22034 | |12;stanford-crypto-pbkdf2;170;170;168;170;167;165;169;168;166;165 10:01:32 INFO - PROCESS | 22034 | |13;stanford-crypto-sha256-iterative;67;74;67;67;67;66;66;66;65;68 10:01:32 INFO - PROCESS | 22034 | __end_tp_report 10:01:32 INFO - PROCESS | 22034 | __start_cc_report 10:01:32 INFO - PROCESS | 22034 | _x_x_mozilla_cycle_collect,1944 10:01:32 INFO - PROCESS | 22034 | __end_cc_report 10:01:32 INFO - PROCESS | 22034 | __startTimestamp1470330092931__endTimestamp 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | ------- Summary: start ------- 10:01:32 INFO - PROCESS | 22034 | Number of tests: 14 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:2.99 (2.6%) stddev-sans-first:0.50 10:01:32 INFO - PROCESS | 22034 | Values: 104.0 113.0 114.0 113.0 113.0 114.0 114.0 113.0 113.0 113.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#1] audio-beat-detection Cycles:10 Average:145.90 Median:147.50 stddev:25.27 (17.1%) stddev-sans-first:26.54 10:01:32 INFO - PROCESS | 22034 | Values: 156.0 126.0 125.0 186.0 125.0 152.0 143.0 125.0 191.0 130.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:0.88 (0.5%) stddev-sans-first:0.87 10:01:32 INFO - PROCESS | 22034 | Values: 162.0 160.0 161.0 161.0 160.0 162.0 161.0 162.0 160.0 162.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:15.29 (20.1%) stddev-sans-first:15.98 10:01:32 INFO - PROCESS | 22034 | Values: 75.0 76.0 75.0 121.0 76.0 77.0 76.0 75.0 98.0 75.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:85.00 stddev:19.26 (22.7%) stddev-sans-first:19.93 10:01:32 INFO - PROCESS | 22034 | Values: 85.0 85.0 125.0 86.0 85.0 85.0 125.0 84.0 86.0 125.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.78 10:01:32 INFO - PROCESS | 22034 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 119.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 10:01:32 INFO - PROCESS | 22034 | Values: 147.0 147.0 147.0 147.0 146.0 146.0 147.0 148.0 147.0 147.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:01:32 INFO - PROCESS | 22034 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.00 stddev:2.32 (3.6%) stddev-sans-first:2.40 10:01:32 INFO - PROCESS | 22034 | Values: 63.0 70.0 64.0 64.0 64.0 63.0 63.0 63.0 63.0 67.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:65.00 stddev:1.58 (2.4%) stddev-sans-first:1.66 10:01:32 INFO - PROCESS | 22034 | Values: 65.0 65.0 65.0 65.0 65.0 65.0 68.0 69.0 64.0 65.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:2.98 (3.6%) stddev-sans-first:3.16 10:01:32 INFO - PROCESS | 22034 | Values: 83.0 86.0 79.0 79.0 88.0 82.0 85.0 80.0 82.0 83.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:135.00 stddev:23.57 (17.5%) stddev-sans-first:25.00 10:01:32 INFO - PROCESS | 22034 | Values: 140.0 129.0 145.0 131.0 137.0 128.0 125.0 129.0 205.0 133.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.50 stddev:1.99 (1.2%) stddev-sans-first:1.94 10:01:32 INFO - PROCESS | 22034 | Values: 170.0 170.0 168.0 170.0 167.0 165.0 169.0 168.0 166.0 165.0 10:01:32 INFO - PROCESS | 22034 | 10:01:32 INFO - PROCESS | 22034 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:2.50 (3.7%) stddev-sans-first:2.65 10:01:32 INFO - PROCESS | 22034 | Values: 67.0 74.0 67.0 67.0 67.0 66.0 66.0 66.0 65.0 68.0 10:01:32 INFO - PROCESS | 22034 | -------- Summary: end -------- 10:01:32 INFO - PROCESS | 22034 | 10:01:33 INFO - TEST-INFO | 22034: exit 0 10:01:34 INFO - TEST-OK | kraken | took 64063ms 10:01:34 INFO - SUITE-END | took 1183s 10:01:34 INFO - Completed test suite (00:19:43) 10:01:34 INFO - javascript benchmark 10:01:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2525.86107764073, 2532.227670560478, 2563.307906865146, 2568.6917569891602, 2569.6659434273543, 4523.2988886722615, 4618.56123668251, 4623.237165867632, 4636.397268355114, 4653.581177694007, 4643.698159619622, 4657.278121891104, 4667.836625718101, 4678.555537223967, 4712.858614241578, 4253.02180498484, 4278.529361770203, 4292.957070429303, 4307.569243075683, 4372.2130016597, 4338.826446942118, 4380.058287468194, 4383.824647014116, 4395.670324725643, 4398.23030969579, 4193.433886425324, 4301.268784306644, 4310.892227694333, 4338.934915976263, 4405.339199120125, 4222.894427639303, 4242.851500197509, 4265.104328091104, 4278.465191851019, 4287.892802679958, 4224.746515209097, 4231.640310573605, 4279.657627389802, 4293.377460268275, 4324.610785029331, 4695.506971767949, 4703.2474804031535, 4711.93981354196, 4754.619630429557, 4761.381020467317, 4869.586085182764, 4887.144749668793, 4964.726940018334, 4986.252062190701, 5056.646034777567, 4979.975100124512, 5058.392992840846, 5059.3169922060415, 5060.392752869642, 5071.771770270347, 4598.126355992351, 4726.5746082852365, 4727.810887564459, 4729.527047295312, 4730.361401210861, 5032.798688052437, 5087.338645976073, 5116.079105760999, 5150.974245128826, 5152.4847515248175, 4905.558499735041, 4965.851024469272, 4967.40391153064, 4967.453580106186, 4970.378702662168, 5002.599792016703, 5015.448300686921, 5053.36832895888, 5054.039732450822, 5055.9241611375855, 4816.783244753994, 4858.0, 4889.364382630307, 4945.184044632595, 4967.180415231446, 4963.702177869267, 4966.975165124151, 4967.105920934194, 4968.527989840959, 5000.424951130666, 4311.633511151587, 4315.395844581761, 4321.827126914951, 4347.673924455678, 4356.281213599784, 3679.411294192916, 3708.295423869456, 3714.294284086015, 3734.8879533614036, 3741.1021354874633, 4275.551067137968, 4296.291111966553, 4311.956880431218, 4332.523422423531, 4339.0, 5029.471905449948, 5034.84895453137, 5034.974825125924, 5040.747962601855, 5052.444231134572, 4175.603317684876, 4268.231718290675, 4289.0, 4314.697971142053, 4331.935020974688, 4587.128445595326, 4604.97697511517, 4613.008203236321, 4629.398178236807, 4653.139169253699, 4761.40482439695, 4762.309465127468, 4764.4520880099235, 4778.761061946889, 4805.327254184347, 4872.658913875995, 4948.82679106244, 4980.153373926513, 5071.290019397214, 5087.796488140433, 4696.436427628706, 4719.834805781781, 4726.858194254178, 4738.147133516, 4738.384010078667, 4915.139850526215, 4923.113839508779, 4938.037082768826, 4941.085899108676, 4962.478939711423, 4854.75726213695, 4993.076280888047, 5003.668960593421, 5003.724795136302, 5050.570701490415, 4858.076965376567, 4882.511748825231, 4906.9018619626695, 4938.407391113089, 4990.126727822544, 4922.901541969069, 4934.851954441372, 4938.678985865908, 4940.827071052496, 4986.401631804207, 4698.0, 4871.926921096116, 4883.487233840538, 4885.0, 4924.581410580141, 4887.560119589254, 4921.9015619688125, 4942.802287908444, 4963.4540200577185, 5032.270320803536, 4826.855194344175, 4836.879078023077, 4844.128056949783, 4850.078485087964, 4886.9022619546695], "value": 4602.537434865231, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.5863919338883, 574.9252597162368, 575.2378099018802, 581.6422899916553, 590.5246276747218, 28916.0, 39307.606923930754, 40218.79890600543, 40332.798336008316, 44303.335449968195, 41383.79308103455, 41800.0, 41825.790871045676, 41831.58168418315, 41855.0, 7514.7369842055405, 7650.8469830603635, 7656.425768067417, 7657.617119144048, 7661.157272699998, 7696.846063078735, 7761.805954851117, 7785.532868027908, 7787.805304867367, 7812.648430820626, 7790.376769858398, 7829.41279404047, 7837.451378403514, 7848.175941526115, 7851.0578730552415, 7811.726589569366, 7841.21587841217, 7852.842943141134, 7853.214678532158, 7855.685772569091, 7710.730124445618, 7790.415718821111, 7797.805054873616, 7798.25916537928, 7798.6490607922215, 38711.806440967615, 39008.0, 39049.41425878614, 39058.41412378816, 39075.0, 2929.370185410126, 3156.1793933577437, 3399.932001359959, 3498.8978471781306, 3841.3469710149343, 26548.60177097345, 29147.85426072856, 29723.0, 29804.254893627614, 29868.70131298703, 3550.573931128281, 3601.693856022241, 3601.8559257629663, 3750.7186960977765, 3754.868579599728, 272151.0, 276316.0, 276533.0, 277751.0, 278521.0, 31470.685293146777, 55986.0, 56717.43282567122, 56746.0, 56759.43240567624, 32032.839835801136, 34433.65566344355, 34442.82778586141, 34461.65538344585, 34494.31011379758, 13241.139325943976, 13275.933620332029, 13375.732485350238, 13378.598642040753, 13387.330633468287, 11014.173936954761, 11071.5571377144, 11073.280236784583, 11084.61203857861, 11088.501017454233, 7864.134945156028, 7888.211178882066, 7915.722949696733, 7994.80012999668, 8000.479968802009, 3071.447139514864, 3083.491223948068, 3104.379124175174, 3117.6414712308374, 3128.0, 858.6093327535956, 894.2130924786146, 936.1387523478417, 990.5294984882267, 1004.2267454060333, 26707.33231669185, 28449.71550284512, 28591.0, 29032.56451153192, 29088.709112909022, 929.0477260807726, 945.5319616789733, 954.289054654287, 960.7934294126657, 984.4339504784778, 14665.780013299809, 15059.933817869114, 15197.544073677807, 15320.080795152326, 15662.138582378078, 398816.0, 402999.0, 403696.0, 404580.0, 405193.0, 50111.24833127506, 50639.24041139386, 50639.7468012665, 50679.49320506822, 50692.23961640579, 1039.9740006499899, 1073.7047311989265, 1079.8056349856945, 1178.8821117888315, 1188.5958774016876, 28463.715362846106, 29657.25856853596, 29744.256393590334, 30105.397892042474, 30281.69718302789, 42980.785096074316, 43442.999999998734, 43562.56437435712, 43588.782056090786, 43665.0, 251303.0, 251926.0, 252045.0, 252478.0, 252586.0, 25000.249992500256, 26165.60751588766, 26166.73833261719, 26167.084152054587, 26183.214503564923, 42241.78879105585, 42542.36186457144, 42651.146977060904, 43112.0, 43830.0, 36487.635123648426, 36623.45064824083, 36831.86835599164, 37114.814425927696, 37380.81309593543, 36352.45471317985, 36640.45039324465, 36667.44998825073, 36703.63296367109, 37564.24871502609, 36192.457113142795, 38636.420453692655, 40017.79991100026, 40332.59667403289, 41715.7914210427, 36040.0, 36176.0, 36355.45466817947, 39065.0, 39300.410493843185, 30201.848990754905, 30672.233194170323, 31024.689753103085, 31068.689313107483, 31618.367632647678], "value": 16788.16779337487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.79782029692325, 351.1799947123468, 359.88123919106687, 366.3863029425712, 380.84195059050495, 12802.23186608803, 16207.513774586752, 16302.836971630295, 17381.565460863483, 17500.912495437504, 13955.930220348884, 14297.356618952146, 15917.283722232498, 15975.121368324766, 16014.43949461769, 4869.366982292297, 4940.505949405048, 4999.125153098194, 5020.523050310233, 5062.190049592066, 3697.7781333119965, 4874.098291816008, 4939.28380384844, 4944.950550494485, 4949.554540091409, 5552.77788888444, 5762.078067509199, 5821.50517206038, 5874.3244526879525, 5879.265091863517, 5678.829635110953, 5761.193432919395, 5814.186013958049, 5847.5614328925285, 5851.210086638292, 5141.794328226866, 5151.7681704323395, 5151.918097199555, 5153.793848246066, 5153.871153221162, 21437.67843482349, 21449.57100857974, 21658.89170554153, 21830.126794928183, 21937.341879743475, 1909.0678231946285, 1922.3656193456127, 1945.6400565895212, 1990.6814909614536, 2066.8863212523306, 12786.296753678544, 13515.0, 13765.38055787492, 14123.929380353135, 14218.146911185258, 1722.3799432204396, 1790.3196785221533, 1798.8201179881908, 1800.0909540682003, 1809.509124641252, 51796.48203517954, 68179.65910170418, 68973.65513172452, 71002.0, 71477.0, 9190.494522801177, 9384.390014649027, 9517.809643807224, 9573.377730447499, 9675.951620241854, 13107.410166542528, 13153.408096635674, 13166.539171128965, 13167.012474064291, 13170.143940643828, 6924.688389022506, 6984.720611175598, 7081.114860642419, 7159.0, 7173.820654483576, 6674.0, 6829.282925292872, 7009.859802804018, 7190.9280907191305, 7361.227071157451, 5942.346341902387, 6021.0, 6073.299476146686, 6123.969380153071, 6210.72051757672, 1686.232764092335, 1731.463246393622, 1743.9389621363446, 1748.4667176511286, 1764.2237415537122, 814.4461765999059, 841.5119230846094, 850.1965642467932, 887.9067697891758, 998.3510718033336, 16955.745663815054, 17778.37775677845, 17979.280828766703, 18079.186436610384, 18595.907020464812, 834.1825011488775, 856.3363393370188, 885.1944730295529, 895.9596818143199, 925.9720713267577, 9477.573509192103, 10210.387376757419, 10311.948440257751, 10324.277300588887, 10443.530041148015, 399897.0, 407568.0, 407769.0, 408135.0, 408511.0, 72482.0, 72874.27125728675, 73692.26307736961, 75818.0, 76900.23099769042, 899.7750562359541, 903.6159632156307, 916.2029034739686, 923.0815338737999, 929.9256059515222, 15375.692486150438, 15721.371145154524, 16127.67744645077, 16271.51185464438, 16479.505614831574, 34846.12884677904, 35460.46809297811, 35628.287434250655, 35924.46113308355, 36549.26901461903, 46262.0, 46529.76735116303, 46766.76616616895, 46966.76516617395, 47678.76160619175, 6565.080888675494, 6717.462602991846, 6776.390124888783, 7109.75115870942, 7242.167150777517, 10201.693949181536, 11270.154738394489, 11356.205065645656, 11437.142214333791, 11480.598179064027, 9039.3672442931, 9255.305852060988, 9465.0, 9660.661876834278, 9864.753381165241, 9505.287103467406, 9721.70834874955, 10278.537465814206, 10315.48422578853, 10567.577296908037, 5206.765695543634, 5317.25558421829, 9051.547422628973, 9399.060093990547, 9916.950415247878, 7538.133114691881, 7561.3194812467145, 7593.126790419173, 7670.884936725842, 7960.402969777174, 8642.22220000203, 10084.243681723754, 10215.642452514126, 10345.37927724339, 10440.895591043993], "value": 8177.521641935052, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1212.7271363943119, 1316.0590178022712, 1369.8835598974088, 1412.0, 1660.1533218058787, 6067.908981365277, 6859.279775623562, 7113.430925525959, 7116.3239492248285, 7142.67857946393, 6912.239653638096, 7035.859282814341, 7045.612491312976, 7223.891641625367, 7231.204567497584, 2115.4288342147647, 2151.9569608607817, 2154.762976072631, 2156.02978659603, 2158.4927542027613, 2081.739782527184, 2116.5872654832315, 2126.5746850629857, 2128.0104751290646, 2135.1139277199945, 2016.7781544030145, 2031.0, 2042.0402410866868, 2047.7645070816823, 2072.678734796109, 2084.6143463459234, 2098.6537221358535, 2098.95802083959, 2099.139352865318, 2102.432343267309, 2038.7178422416043, 2082.011044753745, 2086.8539202255847, 2087.4676957375923, 2092.1631347460984, 7058.505904586681, 7137.35763781257, 7198.5320954137815, 7222.422206223489, 7283.781486555412, 4104.979475102606, 4243.6605071594195, 4258.212230737293, 4258.48898132223, 4260.084081922401, 2794.175718163147, 2931.7801164912717, 2970.064429704636, 2972.7324540791433, 2979.8808047678067, 924.9306302027308, 925.9027802080752, 938.1978408460805, 944.9669261575881, 952.6998995316453, 9581.473018983954, 9695.51522423876, 9737.853932191023, 9750.75623109428, 9764.658236961743, 3907.1013666856215, 3928.5482169550296, 4064.0, 4068.3694027425795, 4097.89755256115, 7706.614669266514, 7833.33416659579, 7834.4124190685925, 7842.45102842807, 7856.489328193649, 4403.295472724413, 4407.845725399596, 4412.977935110304, 4413.624841888475, 4427.114577084531, 3483.547138871981, 3803.201327721154, 3921.784301863368, 4150.066235097079, 4150.06623509714, 3319.5850518685165, 3323.9501407478906, 3431.897043088711, 3452.896413107611, 3454.4472884338384, 993.513178542509, 996.0, 999.9350042247231, 1003.8996100389902, 1029.902159294866, 678.060885673346, 747.4095464582919, 779.3687113438133, 827.8261565071282, 843.1442086282428, 4803.279508073747, 4934.062528119646, 5020.995800839846, 5062.341895553554, 5125.256837758504, 394.6625635082021, 401.528204359876, 402.141428051113, 408.4812288393723, 411.6480409250108, 3337.1490269981, 3446.9314512501205, 3500.6674365935196, 3539.4867744177463, 3608.386574282326, 8750.0, 8989.775255618531, 9071.909280907239, 9081.0, 9101.0, 9030.142136497023, 9037.231835293942, 9046.457212567268, 9048.592813323416, 9053.547322633842, 6957.61733104674, 7021.122359705037, 7023.051887995055, 7024.981377700205, 7026.718931242693, 4850.1997170466475, 4904.583110435653, 4934.195806629739, 4961.1318019345745, 5154.0, 8270.048944371474, 8372.079071302274, 8406.663733450594, 8460.873086903579, 8489.74530764078, 8089.0, 8461.534615596202, 8461.830763384822, 8485.278751306209, 8666.826663466822, 5364.892702146013, 5870.119482077723, 5895.7052147393315, 5925.140854576149, 5930.881382372415, 6286.717097730704, 6318.178636777189, 6632.403083722488, 6803.455723542006, 6826.283240259701, 6232.937670623236, 6238.594491358047, 6266.185395898504, 6306.65313407767, 6361.427471527585, 6078.939210608014, 6087.026075827846, 6089.33017368098, 6170.598911070766, 6315.5894866833505, 5760.654360738369, 5907.616004959664, 5962.761489540543, 6057.57596968208, 6057.848553786191, 4074.327735923658, 4148.62662360389, 4215.810288536962, 4270.188664153801, 4397.516273209881, 4237.343211802176, 4280.636145927507, 4300.84947026865, 4309.676774242007, 4430.069685366109], "value": 3795.454487265421, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.2830009875606, 294.4523186872417, 300.24338666560254, 300.6662604508995, 302.8955010521369, 4664.790084446204, 4694.248920172773, 4711.363965864607, 4729.952700472994, 4750.603318627181, 4684.836030738925, 4696.718196908188, 4697.2954056891485, 4735.4317481902135, 4767.189577771778, 3532.9116772080647, 3585.533880595532, 3625.8912232632933, 3720.869769558059, 3727.2545490901794, 3660.469231961364, 3666.2484190740834, 3681.926361472769, 3682.8342724577324, 3684.355237833382, 3667.9266414671824, 3671.6695497405226, 3676.356637588411, 3684.5762737285154, 3703.351913415155, 3545.7340699447645, 3578.3916734155255, 3693.7045036397158, 3712.4964389422325, 3741.214344987529, 3282.7537934654756, 3286.8356582170795, 3290.555774970372, 3291.0949488890747, 3314.1880239341276, 4619.159217081505, 4621.1219868224925, 4644.396228490308, 4645.767711614406, 4648.1865673507, 4191.0, 4238.7032907696475, 4262.275413179736, 4271.864067966017, 4277.700560960734, 4888.584470320027, 4896.632752543574, 4898.314236006961, 4907.730074845882, 4908.288298196777, 3608.1520842601967, 3609.675129238381, 3610.9097272568397, 3613.765105268149, 3623.148560088377, 5184.740762961875, 5187.948120518822, 5203.609729270282, 5210.713410762407, 5211.452797456251, 3392.3045775615938, 3558.0749005258817, 3576.2311103112434, 3589.8025608591256, 3727.440883867387, 3618.656227658421, 3630.6006339302658, 3637.9991004947174, 3642.817859107034, 3646.872359467406, 4135.379693046068, 4144.849914065332, 4156.480439945007, 4168.43726096973, 4175.31107367281, 4648.0, 4931.852044438672, 4957.603391728725, 4972.0, 4994.700317980933, 1244.1850587864963, 1264.4310060472824, 1275.4069357748692, 1281.35932033983, 1285.1068507387463, 797.1028951096354, 812.8374325134997, 819.7171975668385, 821.8643923752634, 824.3158178711655, 3972.0, 4204.162190521386, 4221.78891055446, 4373.475182978063, 4390.297552391602, 5087.821926232638, 5088.211327244283, 5101.158308879068, 5115.820946266863, 5117.232415137684, 4371.78141092944, 4376.934345984812, 4379.474463064389, 4383.0, 4384.276594361894, 2616.8691565421655, 2654.5487267164244, 2675.7725593324394, 2682.3964607963444, 2699.3521554827093, 4755.2153894607, 4765.309030190603, 4767.141914455362, 4771.212749896228, 4779.641526885428, 5140.434552199255, 5190.480951904779, 5194.22086687, 5203.687778733288, 5204.271402003723, 4547.613452856478, 4714.4106986626675, 4794.400699912511, 4798.272621856198, 4803.687760295569, 5074.16276314404, 5102.0, 5107.9489205107475, 5112.667676601009, 5130.666506677054, 5021.698698078127, 5024.29659847629, 5051.4340554428245, 5075.0, 5082.136036873814, 4711.0, 4781.545753153445, 4803.663743538059, 4810.628970743322, 4814.638902082288, 3613.2412193439673, 3626.637336266352, 3637.654422829827, 3657.5976642569826, 3658.3780757270797, 5151.433342332415, 5157.381114266312, 5160.458151893997, 5163.483651634807, 5186.559142472932, 4784.856454306377, 4912.066707325596, 4969.776360063878, 4981.651284410056, 5001.149804533311, 4887.242477416006, 4887.462379138363, 4901.436334821398, 4905.23968784839, 4911.435184953633, 4874.7562621868055, 4882.0, 4883.755812209304, 4891.828785992473, 4906.748779061317, 4710.411198600175, 4750.8337208197545, 4935.62982776286, 4956.727379994135, 5011.824586139468, 4684.688287279567, 4760.833370832004, 4807.7836497357, 4807.918218400831, 4808.759562021815], "value": 3725.7137382895385, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.4916067146282, 638.7125793392971, 692.5048590257966, 709.6948312225741, 710.6233696141045, 13178.20930744156, 14377.209253491053, 14380.209088500129, 14390.784138237908, 14406.135631862082, 13183.0, 13612.795808062887, 14171.220582867938, 14240.430382784676, 14727.779083313759, 641.5348872067736, 771.7453240430673, 788.9644965976516, 789.1595450844832, 789.9210078992112, 752.1200195770933, 760.4828716472796, 761.9771406857775, 784.6900474312644, 784.8508783331177, 816.7386436340373, 819.5328662662284, 820.6799348254185, 821.1542111625006, 821.195228675898, 671.1576970901536, 808.6441965535175, 809.2554849538438, 809.9028116626013, 810.2464707821723, 585.1719816459704, 585.8476796033019, 587.5417174603816, 601.0653433910268, 604.2265899648457, 13020.86979130206, 13031.087823852335, 13070.0, 13073.934630326787, 13085.607431777062, 12351.629451116482, 12629.557965471256, 12912.612621621367, 13050.673733156653, 13380.598582042554, 11526.769464610745, 11563.479643416067, 11987.280763154151, 12193.512259509609, 12355.75288494225, 4110.588941105865, 4173.8956526087095, 4190.036291652907, 4256.87229383119, 4303.225419424502, 73136.63431682861, 73381.0, 74999.62500187519, 76274.61862690651, 76595.0, 10051.145652619609, 10240.948795256125, 10376.16990640747, 10384.42885641297, 10550.472476376304, 19053.237870485304, 19069.61860762777, 19094.71357929632, 19097.331593394163, 19116.904415477835, 6557.704903279364, 6577.909959181195, 6654.83362915931, 6662.86674266512, 7017.859642807115, 3720.6465385788306, 3776.490173826553, 3785.6971442284553, 3884.6892248620043, 3934.724569280123, 2765.820221685584, 2865.584490248902, 2865.9570106448004, 2868.512352900012, 2877.798554101235, 465.8765427161805, 472.35523510408046, 477.136383146499, 551.1897510659325, 580.3181262017113, 530.5543343591402, 544.2625242796033, 558.7681112338335, 560.1849309255024, 562.8086450606731, 7596.962015189889, 7974.362051035903, 8022.518648881086, 8113.918860811435, 8114.229148230908, 564.8277275430951, 579.5711173731493, 586.1091141464949, 591.1871177131444, 591.9674417906971, 3765.284595926765, 3779.905502362463, 3782.9218672678703, 3939.094008378032, 3956.723029387973, 61073.0, 82067.5896620525, 106476.46761766142, 107168.92831071746, 107328.46335768428, 56303.43696563064, 73991.63004185053, 89611.0, 95405.0, 97074.0, 83240.58379708063, 83247.5837620808, 83251.0, 83560.58219708984, 84325.15674843418, 7142.107236595426, 7263.6005019724425, 7269.382102521345, 7547.245275472409, 7604.961975190088, 47405.48943551209, 47873.5212647883, 48305.0, 49271.260931085344, 50053.0, 61315.38684613097, 61384.0, 70143.64928175327, 73707.63146184235, 73818.0, 9756.951215243878, 9877.0, 10312.432816195182, 10442.425666588108, 10721.142308615135, 11069.335839849635, 11262.549498019987, 11325.603603873826, 12048.879511204776, 12463.0, 10293.43386113771, 10361.948190259, 10588.788224235626, 10768.69703127317, 10832.350058996486, 9979.700608981742, 10132.138768204784, 10144.79710405773, 10361.948190259, 10474.790504190027, 8720.607572659372, 8777.0, 9957.601695932082, 10085.142762864945, 10324.0, 8262.586870656323, 8867.866981995405, 9156.862647060432, 9182.862257066015, 9319.114684105, 8346.374021948257, 8708.78228044304, 8925.062868398983, 8934.28525717928, 9099.818003639757], "value": 6519.680740630366, "unit": "score"}], "name": "dromaeo_css", "value": 6226.285888595386, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 114.0, 113.0, 113.0, 114.0, 114.0, 113.0, 113.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 126.0, 125.0, 186.0, 125.0, 152.0, 143.0, 125.0, 191.0, 130.0], "value": 145.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 161.0, 160.0, 162.0, 161.0, 162.0, 160.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 121.0, 76.0, 77.0, 76.0, 75.0, 98.0, 75.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 125.0, 86.0, 85.0, 85.0, 125.0, 84.0, 86.0, 125.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 119.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0, 148.0, 147.0, 147.0], "value": 146.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 70.0, 64.0, 64.0, 64.0, 63.0, 63.0, 63.0, 63.0, 67.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 68.0, 69.0, 64.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 79.0, 79.0, 88.0, 82.0, 85.0, 80.0, 82.0, 83.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 129.0, 145.0, 131.0, 137.0, 128.0, 125.0, 129.0, 205.0, 133.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 170.0, 168.0, 170.0, 167.0, 165.0, 169.0, 168.0, 166.0, 165.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 74.0, 67.0, 67.0, 67.0, 66.0, 66.0, 66.0, 65.0, 68.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1545.8, "alertThreshold": 2.0}]} 10:01:34 INFO - Return code: 0 10:01:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:01:34 INFO - # TBPL SUCCESS # 10:01:34 INFO - Running post-action listener: _resource_record_post_action 10:01:34 INFO - [mozharness: 2016-08-04 17:01:34.233036Z] Finished run-tests step (success) 10:01:34 INFO - Running post-run listener: _resource_record_post_run 10:01:34 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 12288; Write bytes: 532180992; Read time: 420; Write time: 480396 10:01:34 INFO - TinderboxPrint: CPU usage
13.0% 10:01:34 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 420 10:01:34 INFO - TinderboxPrint: I/O write bytes / time
532,180,992 / 480,396 10:01:34 INFO - TinderboxPrint: CPU idle
8,335.4 (87.0%) 10:01:34 INFO - TinderboxPrint: CPU user
1,197.3 (12.5%) 10:01:34 INFO - TinderboxPrint: Swap in / out
0 / 0 10:01:34 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 4096; Write bytes: 247549952; Read time: 240; Write time: 263540 10:01:34 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 8192; Write bytes: 284360704; Read time: 180; Write time: 216832 10:01:34 INFO - Running post-run listener: _upload_blobber_files 10:01:34 INFO - Blob upload gear active. 10:01:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:01:34 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 10:01:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:01:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:01:35 INFO - (blobuploader) - INFO - Open directory for files ... 10:01:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:01:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:01:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:01:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:01:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:01:40 INFO - (blobuploader) - INFO - Done attempting. 10:01:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:01:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:01:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:01:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:01:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:01:41 INFO - (blobuploader) - INFO - Done attempting. 10:01:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:01:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:01:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:01:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:01:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:01:41 INFO - (blobuploader) - INFO - Done attempting. 10:01:41 INFO - (blobuploader) - INFO - Iteration through files over. 10:01:41 INFO - Return code: 0 10:01:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:01:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:01:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1f3fb40d4bd3e6cfdf7d6e7e2813f184a8097469f6b8a78966edef745ac28ef6affacb072b428111387000a8416201d114daff3909dcad7c47b9976ae516a75f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5db64703efb079516970a4d4bed2530a47026a62253f003beb9e538004456cc9658a73469a4dc9a6e34fc66b20e3e37176a8e25977b02dd4d51bf924c84b9a8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6681870cca0fa1b5458258597434650565b941744b824a96692b964e0b413e18ee1e178c1d87b840be66acf2d1b42d3ae3b01fa3bd91c3f3d917cac5b8bfd344"} 10:01:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:01:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:01:41 INFO - Contents: 10:01:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1f3fb40d4bd3e6cfdf7d6e7e2813f184a8097469f6b8a78966edef745ac28ef6affacb072b428111387000a8416201d114daff3909dcad7c47b9976ae516a75f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5db64703efb079516970a4d4bed2530a47026a62253f003beb9e538004456cc9658a73469a4dc9a6e34fc66b20e3e37176a8e25977b02dd4d51bf924c84b9a8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6681870cca0fa1b5458258597434650565b941744b824a96692b964e0b413e18ee1e178c1d87b840be66acf2d1b42d3ae3b01fa3bd91c3f3d917cac5b8bfd344"} 10:01:41 INFO - Running post-run listener: copy_logs_to_upload_dir 10:01:41 INFO - Copying logs to upload dir... 10:01:41 INFO - mkdir: /builds/slave/test/build/upload/logs 10:01:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.539577 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-08-04 10:01:41.905788) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 10:01:41.913683) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1f3fb40d4bd3e6cfdf7d6e7e2813f184a8097469f6b8a78966edef745ac28ef6affacb072b428111387000a8416201d114daff3909dcad7c47b9976ae516a75f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5db64703efb079516970a4d4bed2530a47026a62253f003beb9e538004456cc9658a73469a4dc9a6e34fc66b20e3e37176a8e25977b02dd4d51bf924c84b9a8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6681870cca0fa1b5458258597434650565b941744b824a96692b964e0b413e18ee1e178c1d87b840be66acf2d1b42d3ae3b01fa3bd91c3f3d917cac5b8bfd344"} build_url:https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011417 build_url: 'https://queue.taskcluster.net/v1/task/NDtwf7k4RE-giXtHzvrlZQ/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/Autoland-Non-PGO/sha512/1f3fb40d4bd3e6cfdf7d6e7e2813f184a8097469f6b8a78966edef745ac28ef6affacb072b428111387000a8416201d114daff3909dcad7c47b9976ae516a75f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5db64703efb079516970a4d4bed2530a47026a62253f003beb9e538004456cc9658a73469a4dc9a6e34fc66b20e3e37176a8e25977b02dd4d51bf924c84b9a8f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6681870cca0fa1b5458258597434650565b941744b824a96692b964e0b413e18ee1e178c1d87b840be66acf2d1b42d3ae3b01fa3bd91c3f3d917cac5b8bfd344"}' ========= master_lag: 0.35 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-04 10:01:42.274421) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 10:01:42.274804) ========= 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=9e0dfe3369c33a1556e63f89000001d8-1470328784.899527-1616517566 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-04 10:01:42.324592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 10:01:42.324953) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-04 10:01:42.325296) ========= ========= Total master_lag: 0.63 =========