builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-011 starttime: 1468920611.31 results: success (0) buildid: 20160719010919 builduid: f8f74ccea64e480890c3ae059a5e4da8 revision: 0175a8da10333b8df86bf886b3490050bbabbaf9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:11.307103) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:11.307558) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:11.307831) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:11.328769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:11.329086) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-19 02:30:11-- 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% 105M=0s 2016-07-19 02:30:11 (105 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.166022 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:11.509376) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:11.509681) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021515 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:11.559977) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 02:30:11.560289) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 0175a8da10333b8df86bf886b3490050bbabbaf9 --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 0175a8da10333b8df86bf886b3490050bbabbaf9 --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=5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-19 02:30:11,763 truncating revision to first 12 chars 2016-07-19 02:30:11,763 Setting DEBUG logging. 2016-07-19 02:30:11,764 attempt 1/10 2016-07-19 02:30:11,764 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/0175a8da1033?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-19 02:30:13,134 unpacking tar archive at: autoland-0175a8da1033/testing/mozharness/ program finished with exit code 0 elapsedTime=1.678005 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-19 02:30:13.252869) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:13.253196) ========= script_repo_revision: 0175a8da10333b8df86bf886b3490050bbabbaf9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:13.253587) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:13.253876) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-19 02:30:13.264466) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-07-19 02:30:13.264807) ========= /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=5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:30:13 INFO - MultiFileLogger online at 20160719 02:30:13 in /builds/slave/test 02:30:13 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 02:30:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:30:13 INFO - {'append_to_log': False, 02:30:13 INFO - 'base_work_dir': '/builds/slave/test', 02:30:13 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 02:30:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:30:13 INFO - 'branch': 'Autoland-Non-PGO', 02:30:13 INFO - 'buildbot_json_path': 'buildprops.json', 02:30:13 INFO - 'config_files': ('talos/linux_config.py',), 02:30:13 INFO - 'default_actions': ('clobber', 02:30:13 INFO - 'read-buildbot-config', 02:30:13 INFO - 'download-and-extract', 02:30:13 INFO - 'populate-webroot', 02:30:13 INFO - 'create-virtualenv', 02:30:13 INFO - 'install', 02:30:13 INFO - 'run-tests'), 02:30:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:30:13 INFO - 'download_minidump_stackwalk': True, 02:30:13 INFO - 'download_symbols': 'ondemand', 02:30:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:30:13 INFO - 'tooltool.py': '/tools/tooltool.py', 02:30:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:30:13 INFO - '/tools/misc-python/virtualenv.py')}, 02:30:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:30:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:30:13 INFO - 'installer_path': 'installer.exe', 02:30:13 INFO - 'log_level': 'info', 02:30:13 INFO - 'log_name': 'talos', 02:30:13 INFO - 'log_to_console': True, 02:30:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:30:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:30:13 INFO - 'opt_config_files': (), 02:30:13 INFO - 'pip_index': False, 02:30:13 INFO - 'sps_profile': False, 02:30:13 INFO - 'sps_profile_interval': 0, 02:30:13 INFO - 'suite': 'dromaeojs', 02:30:13 INFO - 'system_bits': '32', 02:30:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:30:13 INFO - 'title': 'talos-linux64-ix-011', 02:30:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:30:13 INFO - 'use_talos_json': True, 02:30:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:30:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:30:13 INFO - 'work_dir': 'build'} 02:30:13 INFO - ##### 02:30:13 INFO - ##### Running clobber step. 02:30:13 INFO - ##### 02:30:13 INFO - Running pre-action listener: _resource_record_pre_action 02:30:13 INFO - Running main action method: clobber 02:30:13 INFO - rmtree: /builds/slave/test/build 02:30:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:30:14 INFO - Running post-action listener: _resource_record_post_action 02:30:14 INFO - ##### 02:30:14 INFO - ##### Finished clobber step (success) 02:30:14 INFO - ##### 02:30:14 INFO - ##### 02:30:14 INFO - ##### Running read-buildbot-config step. 02:30:14 INFO - ##### 02:30:14 INFO - Running pre-action listener: _resource_record_pre_action 02:30:14 INFO - Running main action method: read_buildbot_config 02:30:14 INFO - Using buildbot properties: 02:30:14 INFO - { 02:30:14 INFO - "project": "", 02:30:14 INFO - "product": "firefox", 02:30:14 INFO - "script_repo_revision": "production", 02:30:14 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 02:30:14 INFO - "repository": "", 02:30:14 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 02:30:14 INFO - "buildid": "20160719010919", 02:30:14 INFO - "pgo_build": "False", 02:30:14 INFO - "basedir": "/builds/slave/test", 02:30:14 INFO - "buildnumber": 164, 02:30:14 INFO - "slavename": "talos-linux64-ix-011", 02:30:14 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 02:30:14 INFO - "platform": "ubuntu64_hw", 02:30:14 INFO - "scheduler": "tests-autoland-linux64-talos", 02:30:14 INFO - "branch": "autoland", 02:30:14 INFO - "repo_path": "integration/autoland", 02:30:14 INFO - "revision": "0175a8da10333b8df86bf886b3490050bbabbaf9", 02:30:14 INFO - "stage_platform": "linux64", 02:30:14 INFO - "builduid": "f8f74ccea64e480890c3ae059a5e4da8", 02:30:14 INFO - "slavebuilddir": "test" 02:30:14 INFO - } 02:30:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 02:30:14 INFO - Running post-action listener: _resource_record_post_action 02:30:14 INFO - ##### 02:30:14 INFO - ##### Finished read-buildbot-config step (success) 02:30:14 INFO - ##### 02:30:14 INFO - ##### 02:30:14 INFO - ##### Running download-and-extract step. 02:30:14 INFO - ##### 02:30:14 INFO - Running pre-action listener: _resource_record_pre_action 02:30:14 INFO - Running main action method: download_and_extract 02:30:14 INFO - mkdir: /builds/slave/test/build/tests 02:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:14 INFO - https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:30:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:30:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:30:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:30:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:30:16 INFO - Downloaded 1361 bytes. 02:30:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 02:30:16 INFO - Using the following test package requirements: 02:30:16 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 02:30:16 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:30:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:30:16 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:30:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:30:16 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:30:16 INFO - u'jsshell-linux-x86_64.zip'], 02:30:16 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:30:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:30:16 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 02:30:16 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:30:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:30:16 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:30:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:30:16 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:30:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:30:16 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 02:30:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:30:16 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:16 INFO - https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:30:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:30:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:30:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:30:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:30:22 INFO - Downloaded 22765222 bytes. 02:30:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:30:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:30:23 INFO - Return code: 0 02:30:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:30:23 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:23 INFO - https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:30:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:30:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:30:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 02:30:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:30:24 INFO - Downloaded 22765222 bytes. 02:30:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:30:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:30:25 INFO - Return code: 0 02:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:25 INFO - https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:30:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 02:30:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 02:30:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 02:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:30:26 INFO - Downloaded 14403317 bytes. 02:30:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:30:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:30:27 INFO - Return code: 0 02:30:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:27 INFO - https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:30:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:30:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:30:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:30:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:30:29 INFO - Downloaded 57132296 bytes. 02:30:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:30:29 INFO - mkdir: /builds/slave/test/properties 02:30:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:30:29 INFO - Writing to file /builds/slave/test/properties/build_url 02:30:29 INFO - Contents: 02:30:29 INFO - build_url:https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 02:30:29 INFO - Running post-action listener: _resource_record_post_action 02:30:29 INFO - Running post-action listener: set_extra_try_arguments 02:30:29 INFO - ##### 02:30:29 INFO - ##### Finished download-and-extract step (success) 02:30:29 INFO - ##### 02:30:29 INFO - ##### 02:30:29 INFO - ##### Running populate-webroot step. 02:30:29 INFO - ##### 02:30:29 INFO - Running pre-action listener: _resource_record_pre_action 02:30:29 INFO - Running main action method: populate_webroot 02:30:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:30:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:30:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:30:29 INFO - '--tppagecycles', 02:30:29 INFO - '7'], 02:30:29 INFO - 'tests': ['tsvgm']}}, 02:30:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:30:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:30:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:30:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:30:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:29 INFO - 'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:30:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:30:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:29 INFO - 'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['damp', 'tps']}, 02:30:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:29 INFO - 'tests': ['damp', 'tps']}, 02:30:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['dromaeo_dom']}, 02:30:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:30:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['basic_compositor_video']}, 02:30:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:30:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['a11yr', 02:30:29 INFO - 'ts_paint', 02:30:29 INFO - 'tpaint', 02:30:29 INFO - 'sessionrestore', 02:30:29 INFO - 'sessionrestore_no_auto_restore', 02:30:29 INFO - 'tabpaint']}, 02:30:29 INFO - 'other-e10s': {'tests': ['a11yr', 02:30:29 INFO - 'ts_paint', 02:30:29 INFO - 'tpaint', 02:30:29 INFO - 'sessionrestore', 02:30:29 INFO - 'sessionrestore_no_auto_restore', 02:30:29 INFO - 'tabpaint']}, 02:30:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:30:29 INFO - 'ts_paint', 02:30:29 INFO - 'tpaint', 02:30:29 INFO - 'sessionrestore', 02:30:29 INFO - 'sessionrestore_no_auto_restore', 02:30:29 INFO - 'tabpaint']}, 02:30:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:30:29 INFO - 'ts_paint', 02:30:29 INFO - 'tpaint', 02:30:29 INFO - 'sessionrestore', 02:30:29 INFO - 'sessionrestore_no_auto_restore', 02:30:29 INFO - 'tabpaint']}, 02:30:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['a11yr', 02:30:29 INFO - 'ts_paint', 02:30:29 INFO - 'tpaint', 02:30:29 INFO - 'sessionrestore', 02:30:29 INFO - 'sessionrestore_no_auto_restore', 02:30:29 INFO - 'tabpaint']}, 02:30:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['a11yr', 02:30:29 INFO - 'ts_paint', 02:30:29 INFO - 'tpaint', 02:30:29 INFO - 'sessionrestore', 02:30:29 INFO - 'sessionrestore_no_auto_restore', 02:30:29 INFO - 'tabpaint']}, 02:30:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['tsvgx', 02:30:29 INFO - 'tsvgr_opacity', 02:30:29 INFO - 'tart', 02:30:29 INFO - 'tscrollx', 02:30:29 INFO - 'cart']}, 02:30:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:30:29 INFO - 'tsvgr_opacity', 02:30:29 INFO - 'tart', 02:30:29 INFO - 'tscrollx', 02:30:29 INFO - 'cart']}, 02:30:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:29 INFO - 'talos_options': ['--disable-e10s'], 02:30:29 INFO - 'tests': ['tp5o']}, 02:30:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:29 INFO - 'tests': ['tp5o']}, 02:30:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:29 INFO - 'talos_options': ['--disable-e10s', 02:30:29 INFO - '--xperf_path', 02:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:30:29 INFO - 'tests': ['tp5n']}, 02:30:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:30:29 INFO - 'talos_options': ['--xperf_path', 02:30:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:30:29 INFO - 'tests': ['tp5n']}}, 02:30:29 INFO - 'talos.zip': {'path': '', 02:30:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:30:29 INFO - Running post-action listener: _resource_record_post_action 02:30:29 INFO - ##### 02:30:29 INFO - ##### Finished populate-webroot step (success) 02:30:29 INFO - ##### 02:30:29 INFO - ##### 02:30:29 INFO - ##### Running create-virtualenv step. 02:30:29 INFO - ##### 02:30:29 INFO - Running pre-action listener: _resource_record_pre_action 02:30:29 INFO - Running main action method: create_virtualenv 02:30:29 INFO - Creating virtualenv /builds/slave/test/build/venv 02:30:29 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 02:30:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:30:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:30:29 INFO - Using real prefix '/usr' 02:30:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:30:30 INFO - Installing distribute.............................................................................................................................................................................................done. 02:30:32 INFO - Installing pip.................done. 02:30:32 INFO - Return code: 0 02:30:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:30:32 INFO - Using env: {'DISPLAY': ':0', 02:30:32 INFO - 'HOME': '/home/cltbld', 02:30:32 INFO - 'LANG': 'en_US.UTF-8', 02:30:32 INFO - 'LANGUAGE': 'en_US:en', 02:30:32 INFO - 'LOGNAME': 'cltbld', 02:30:32 INFO - 'MAIL': '/var/mail/cltbld', 02:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:32 INFO - 'MOZ_NO_REMOTE': '1', 02:30:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:32 INFO - 'NO_EM_RESTART': '1', 02:30:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:32 INFO - 'PWD': '/builds/slave/test', 02:30:32 INFO - 'SHELL': '/bin/bash', 02:30:32 INFO - 'SHLVL': '1', 02:30:32 INFO - 'TERM': 'linux', 02:30:32 INFO - 'TMOUT': '86400', 02:30:32 INFO - 'USER': 'cltbld', 02:30:32 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:32 INFO - '_': '/tools/buildbot/bin/python'} 02:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:32 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 02:30:32 INFO - Cleaning up... 02:30:32 INFO - Return code: 0 02:30:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:30:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:30:32 INFO - Using env: {'DISPLAY': ':0', 02:30:32 INFO - 'HOME': '/home/cltbld', 02:30:32 INFO - 'LANG': 'en_US.UTF-8', 02:30:32 INFO - 'LANGUAGE': 'en_US:en', 02:30:32 INFO - 'LOGNAME': 'cltbld', 02:30:32 INFO - 'MAIL': '/var/mail/cltbld', 02:30:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:32 INFO - 'MOZ_NO_REMOTE': '1', 02:30:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:32 INFO - 'NO_EM_RESTART': '1', 02:30:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:32 INFO - 'PWD': '/builds/slave/test', 02:30:32 INFO - 'SHELL': '/bin/bash', 02:30:32 INFO - 'SHLVL': '1', 02:30:32 INFO - 'TERM': 'linux', 02:30:32 INFO - 'TMOUT': '86400', 02:30:32 INFO - 'USER': 'cltbld', 02:30:32 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:32 INFO - '_': '/tools/buildbot/bin/python'} 02:30:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:32 INFO - Downloading/unpacking psutil>=3.1.1 02:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:30:34 INFO - Installing collected packages: psutil 02:30:34 INFO - Running setup.py install for psutil 02:30:34 INFO - building 'psutil._psutil_linux' extension 02:30:34 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 02:30:35 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 02:30:35 INFO - building 'psutil._psutil_posix' extension 02:30:35 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 02:30:35 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 02:30:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:30:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:30:35 INFO - Successfully installed psutil 02:30:35 INFO - Cleaning up... 02:30:35 INFO - Return code: 0 02:30:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:30:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:30:35 INFO - Using env: {'DISPLAY': ':0', 02:30:35 INFO - 'HOME': '/home/cltbld', 02:30:35 INFO - 'LANG': 'en_US.UTF-8', 02:30:35 INFO - 'LANGUAGE': 'en_US:en', 02:30:35 INFO - 'LOGNAME': 'cltbld', 02:30:35 INFO - 'MAIL': '/var/mail/cltbld', 02:30:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:35 INFO - 'MOZ_NO_REMOTE': '1', 02:30:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:35 INFO - 'NO_EM_RESTART': '1', 02:30:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:35 INFO - 'PWD': '/builds/slave/test', 02:30:35 INFO - 'SHELL': '/bin/bash', 02:30:35 INFO - 'SHLVL': '1', 02:30:35 INFO - 'TERM': 'linux', 02:30:35 INFO - 'TMOUT': '86400', 02:30:35 INFO - 'USER': 'cltbld', 02:30:35 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:35 INFO - '_': '/tools/buildbot/bin/python'} 02:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:30:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:30:37 INFO - Installing collected packages: mozsystemmonitor 02:30:37 INFO - Running setup.py install for mozsystemmonitor 02:30:37 INFO - Successfully installed mozsystemmonitor 02:30:37 INFO - Cleaning up... 02:30:37 INFO - Return code: 0 02:30:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:30:37 INFO - Using env: {'DISPLAY': ':0', 02:30:37 INFO - 'HOME': '/home/cltbld', 02:30:37 INFO - 'LANG': 'en_US.UTF-8', 02:30:37 INFO - 'LANGUAGE': 'en_US:en', 02:30:37 INFO - 'LOGNAME': 'cltbld', 02:30:37 INFO - 'MAIL': '/var/mail/cltbld', 02:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:37 INFO - 'MOZ_NO_REMOTE': '1', 02:30:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:37 INFO - 'NO_EM_RESTART': '1', 02:30:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:37 INFO - 'PWD': '/builds/slave/test', 02:30:37 INFO - 'SHELL': '/bin/bash', 02:30:37 INFO - 'SHLVL': '1', 02:30:37 INFO - 'TERM': 'linux', 02:30:37 INFO - 'TMOUT': '86400', 02:30:37 INFO - 'USER': 'cltbld', 02:30:37 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:37 INFO - '_': '/tools/buildbot/bin/python'} 02:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:37 INFO - Downloading/unpacking jsonschema==2.5.1 02:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:30:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:30:39 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 02:30:39 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 02:30:39 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 02:30:39 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 02:30:39 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 02:30:39 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 02:30:40 INFO - Downloading functools32-3.2.3-2.tar.gz 02:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:30:40 INFO - warning: no files found matching '*.txt' 02:30:40 INFO - no previously-included directories found matching 'build' 02:30:40 INFO - no previously-included directories found matching 'dist' 02:30:40 INFO - no previously-included directories found matching '.git*' 02:30:40 INFO - Installing collected packages: jsonschema, functools32 02:30:40 INFO - Running setup.py install for functools32 02:30:40 INFO - warning: no files found matching '*.txt' 02:30:40 INFO - no previously-included directories found matching 'build' 02:30:40 INFO - no previously-included directories found matching 'dist' 02:30:40 INFO - no previously-included directories found matching '.git*' 02:30:40 INFO - Successfully installed jsonschema functools32 02:30:40 INFO - Cleaning up... 02:30:40 INFO - Return code: 0 02:30:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:40 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:40 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 02:30:40 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 02:30:40 INFO - Using env: {'DISPLAY': ':0', 02:30:40 INFO - 'HOME': '/home/cltbld', 02:30:40 INFO - 'LANG': 'en_US.UTF-8', 02:30:40 INFO - 'LANGUAGE': 'en_US:en', 02:30:40 INFO - 'LOGNAME': 'cltbld', 02:30:40 INFO - 'MAIL': '/var/mail/cltbld', 02:30:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:40 INFO - 'MOZ_NO_REMOTE': '1', 02:30:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:40 INFO - 'NO_EM_RESTART': '1', 02:30:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:40 INFO - 'PWD': '/builds/slave/test', 02:30:40 INFO - 'SHELL': '/bin/bash', 02:30:40 INFO - 'SHLVL': '1', 02:30:40 INFO - 'TERM': 'linux', 02:30:40 INFO - 'TMOUT': '86400', 02:30:40 INFO - 'USER': 'cltbld', 02:30:40 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:40 INFO - '_': '/tools/buildbot/bin/python'} 02:30:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:40 INFO - Downloading/unpacking blobuploader==1.2.4 02:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:30:42 INFO - Downloading blobuploader-1.2.4.tar.gz 02:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:30:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:30:42 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 02:30:42 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 02:30:42 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 02:30:42 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 02:30:42 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 02:30:42 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 02:30:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:30:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:30:42 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 02:30:42 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 02:30:42 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 02:30:42 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 02:30:42 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 02:30:42 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 02:30:43 INFO - Downloading docopt-0.6.1.tar.gz 02:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:30:43 INFO - Installing collected packages: blobuploader, requests, docopt 02:30:43 INFO - Running setup.py install for blobuploader 02:30:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:30:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:30:43 INFO - Running setup.py install for requests 02:30:43 INFO - Running setup.py install for docopt 02:30:43 INFO - Successfully installed blobuploader requests docopt 02:30:43 INFO - Cleaning up... 02:30:43 INFO - Return code: 0 02:30:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:43 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:43 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 02:30:43 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 02:30:43 INFO - Using env: {'DISPLAY': ':0', 02:30:43 INFO - 'HOME': '/home/cltbld', 02:30:43 INFO - 'LANG': 'en_US.UTF-8', 02:30:43 INFO - 'LANGUAGE': 'en_US:en', 02:30:43 INFO - 'LOGNAME': 'cltbld', 02:30:43 INFO - 'MAIL': '/var/mail/cltbld', 02:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:43 INFO - 'MOZ_NO_REMOTE': '1', 02:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:43 INFO - 'NO_EM_RESTART': '1', 02:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:43 INFO - 'PWD': '/builds/slave/test', 02:30:43 INFO - 'SHELL': '/bin/bash', 02:30:43 INFO - 'SHLVL': '1', 02:30:43 INFO - 'TERM': 'linux', 02:30:43 INFO - 'TMOUT': '86400', 02:30:43 INFO - 'USER': 'cltbld', 02:30:43 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:43 INFO - '_': '/tools/buildbot/bin/python'} 02:30:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:30:44 INFO - Running setup.py (path:/tmp/pip-ArWcif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:30:44 INFO - Running setup.py (path:/tmp/pip-jKW8WP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:30:44 INFO - Running setup.py (path:/tmp/pip-2P3hkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:30:44 INFO - Running setup.py (path:/tmp/pip-Msjd2D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:30:44 INFO - Running setup.py (path:/tmp/pip-pxiosp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:30:44 INFO - Running setup.py (path:/tmp/pip-Kdn25D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:30:44 INFO - Running setup.py (path:/tmp/pip-HVUH4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:30:44 INFO - Running setup.py (path:/tmp/pip-tTdz_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:30:44 INFO - Running setup.py (path:/tmp/pip-1Wvj0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:30:44 INFO - Running setup.py (path:/tmp/pip-OTGz2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:30:44 INFO - Running setup.py (path:/tmp/pip-3Tp_EW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:30:44 INFO - Running setup.py (path:/tmp/pip-MSBoGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:30:44 INFO - Running setup.py (path:/tmp/pip-Urzvq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:30:44 INFO - Running setup.py (path:/tmp/pip-iIWVyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:45 INFO - Running setup.py (path:/tmp/pip-wOJqX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:30:45 INFO - Running setup.py (path:/tmp/pip-KAc1eT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:30:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:30:45 INFO - Running setup.py (path:/tmp/pip-kzLciV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:30:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:30:45 INFO - Running setup.py install for manifestparser 02:30:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:30:45 INFO - Running setup.py install for mozcrash 02:30:45 INFO - Running setup.py install for mozdebug 02:30:45 INFO - Running setup.py install for mozdevice 02:30:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:30:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:30:45 INFO - Running setup.py install for mozfile 02:30:45 INFO - Running setup.py install for mozhttpd 02:30:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:30:45 INFO - Running setup.py install for mozinfo 02:30:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:30:45 INFO - Running setup.py install for mozInstall 02:30:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Running setup.py install for mozleak 02:30:46 INFO - Running setup.py install for mozlog 02:30:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Running setup.py install for moznetwork 02:30:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Running setup.py install for mozprocess 02:30:46 INFO - Running setup.py install for mozprofile 02:30:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Running setup.py install for mozrunner 02:30:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:30:46 INFO - Running setup.py install for mozscreenshot 02:30:46 INFO - Running setup.py install for moztest 02:30:46 INFO - Running setup.py install for mozversion 02:30:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:30:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:30:47 INFO - Cleaning up... 02:30:47 INFO - Return code: 0 02:30:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:47 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:47 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 02:30:47 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 02:30:47 INFO - Using env: {'DISPLAY': ':0', 02:30:47 INFO - 'HOME': '/home/cltbld', 02:30:47 INFO - 'LANG': 'en_US.UTF-8', 02:30:47 INFO - 'LANGUAGE': 'en_US:en', 02:30:47 INFO - 'LOGNAME': 'cltbld', 02:30:47 INFO - 'MAIL': '/var/mail/cltbld', 02:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:47 INFO - 'MOZ_NO_REMOTE': '1', 02:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:47 INFO - 'NO_EM_RESTART': '1', 02:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:47 INFO - 'PWD': '/builds/slave/test', 02:30:47 INFO - 'SHELL': '/bin/bash', 02:30:47 INFO - 'SHLVL': '1', 02:30:47 INFO - 'TERM': 'linux', 02:30:47 INFO - 'TMOUT': '86400', 02:30:47 INFO - 'USER': 'cltbld', 02:30:47 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:47 INFO - '_': '/tools/buildbot/bin/python'} 02:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:30:47 INFO - Running setup.py (path:/tmp/pip-uxjePM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:30:47 INFO - Running setup.py (path:/tmp/pip-ehBGam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:30:47 INFO - Running setup.py (path:/tmp/pip-c6nUVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:30:47 INFO - Running setup.py (path:/tmp/pip-FR9AfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:30:47 INFO - Running setup.py (path:/tmp/pip-JOa0Wi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:30:47 INFO - Running setup.py (path:/tmp/pip-lN5udC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:30:47 INFO - Running setup.py (path:/tmp/pip-COxGVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:30:47 INFO - Running setup.py (path:/tmp/pip-7Tt1B_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:30:47 INFO - Running setup.py (path:/tmp/pip-geHHfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:30:47 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:30:47 INFO - Running setup.py (path:/tmp/pip-O_EPSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 02:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:30:47 INFO - Running setup.py (path:/tmp/pip-4i51vb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:30:48 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)) 02:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:30:48 INFO - Running setup.py (path:/tmp/pip-IOqZ5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:30:48 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)) 02:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:30:48 INFO - Running setup.py (path:/tmp/pip-CoK2dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:30:48 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)) 02:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:30:48 INFO - Running setup.py (path:/tmp/pip-pforly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:30:48 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)) 02:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:48 INFO - Running setup.py (path:/tmp/pip-kF1q4w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:30:48 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)) 02:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:30:48 INFO - Running setup.py (path:/tmp/pip-kzkehp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:30:48 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)) 02:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:30:48 INFO - Running setup.py (path:/tmp/pip-Hg1Zbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:30:48 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)) 02:30:48 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)) 02:30:48 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)) 02:30:48 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)) 02:30:48 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)) 02:30:48 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)) 02:30:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:30:48 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 02:30:48 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 02:30:48 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 02:30:48 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 02:30:48 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 02:30:48 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 02:30:50 INFO - Downloading blessings-1.6.tar.gz 02:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:30:50 INFO - Installing collected packages: blessings 02:30:50 INFO - Running setup.py install for blessings 02:30:50 INFO - Successfully installed blessings 02:30:50 INFO - Cleaning up... 02:30:50 INFO - Return code: 0 02:30:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:30:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:30:50 INFO - Reading from file tmpfile_stdout 02:30:50 INFO - Current package versions: 02:30:50 INFO - argparse == 1.2.1 02:30:50 INFO - blessings == 1.6 02:30:50 INFO - blobuploader == 1.2.4 02:30:50 INFO - docopt == 0.6.1 02:30:50 INFO - functools32 == 3.2.3-2 02:30:50 INFO - jsonschema == 2.5.1 02:30:50 INFO - manifestparser == 1.1 02:30:50 INFO - mozInstall == 1.12 02:30:50 INFO - mozcrash == 0.17 02:30:50 INFO - mozdebug == 0.1 02:30:50 INFO - mozdevice == 0.48 02:30:50 INFO - mozfile == 1.2 02:30:50 INFO - mozhttpd == 0.7 02:30:50 INFO - mozinfo == 0.9 02:30:50 INFO - mozleak == 0.1 02:30:50 INFO - mozlog == 3.2 02:30:50 INFO - moznetwork == 0.27 02:30:50 INFO - mozprocess == 0.23 02:30:50 INFO - mozprofile == 0.28 02:30:50 INFO - mozrunner == 6.12 02:30:50 INFO - mozscreenshot == 0.1 02:30:50 INFO - mozsystemmonitor == 0.3 02:30:50 INFO - moztest == 0.7 02:30:50 INFO - mozversion == 1.4 02:30:50 INFO - psutil == 3.1.1 02:30:50 INFO - requests == 1.2.3 02:30:50 INFO - wsgiref == 0.1.2 02:30:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:50 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:50 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 02:30:50 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 02:30:50 INFO - Using env: {'DISPLAY': ':0', 02:30:50 INFO - 'HOME': '/home/cltbld', 02:30:50 INFO - 'LANG': 'en_US.UTF-8', 02:30:50 INFO - 'LANGUAGE': 'en_US:en', 02:30:50 INFO - 'LOGNAME': 'cltbld', 02:30:50 INFO - 'MAIL': '/var/mail/cltbld', 02:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:50 INFO - 'MOZ_NO_REMOTE': '1', 02:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:50 INFO - 'NO_EM_RESTART': '1', 02:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:50 INFO - 'PWD': '/builds/slave/test', 02:30:50 INFO - 'SHELL': '/bin/bash', 02:30:50 INFO - 'SHLVL': '1', 02:30:50 INFO - 'TERM': 'linux', 02:30:50 INFO - 'TMOUT': '86400', 02:30:50 INFO - 'USER': 'cltbld', 02:30:50 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:50 INFO - '_': '/tools/buildbot/bin/python'} 02:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 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)) 02:30:51 INFO - Cleaning up... 02:30:51 INFO - Return code: 0 02:30:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:30:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:30:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:30:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:30:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:30:51 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 0x274c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28af8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a0d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, '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': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', '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 02:30:51 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 02:30:51 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 02:30:51 INFO - Using env: {'DISPLAY': ':0', 02:30:51 INFO - 'HOME': '/home/cltbld', 02:30:51 INFO - 'LANG': 'en_US.UTF-8', 02:30:51 INFO - 'LANGUAGE': 'en_US:en', 02:30:51 INFO - 'LOGNAME': 'cltbld', 02:30:51 INFO - 'MAIL': '/var/mail/cltbld', 02:30:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:30:51 INFO - 'MOZ_NO_REMOTE': '1', 02:30:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:30:51 INFO - 'NO_EM_RESTART': '1', 02:30:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:30:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:30:51 INFO - 'PWD': '/builds/slave/test', 02:30:51 INFO - 'SHELL': '/bin/bash', 02:30:51 INFO - 'SHLVL': '1', 02:30:51 INFO - 'TERM': 'linux', 02:30:51 INFO - 'TMOUT': '86400', 02:30:51 INFO - 'USER': 'cltbld', 02:30:51 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:30:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:30:51 INFO - '_': '/tools/buildbot/bin/python'} 02:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:30:51 INFO - Cleaning up... 02:30:51 INFO - Return code: 0 02:30:51 INFO - Running post-action listener: _resource_record_post_action 02:30:51 INFO - Running post-action listener: _start_resource_monitoring 02:30:51 INFO - Starting resource monitoring. 02:30:51 INFO - ##### 02:30:51 INFO - ##### Finished create-virtualenv step (success) 02:30:51 INFO - ##### 02:30:51 INFO - ##### 02:30:51 INFO - ##### Running install step. 02:30:51 INFO - ##### 02:30:51 INFO - Running pre-action listener: _resource_record_pre_action 02:30:51 INFO - Running main action method: install 02:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:30:51 INFO - Reading from file tmpfile_stdout 02:30:51 INFO - Detecting whether we're running mozinstall >=1.0... 02:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:30:51 INFO - Reading from file tmpfile_stdout 02:30:51 INFO - Output received: 02:30:51 INFO - Usage: mozinstall [options] installer 02:30:51 INFO - Options: 02:30:51 INFO - -h, --help show this help message and exit 02:30:51 INFO - -d DEST, --destination=DEST 02:30:51 INFO - Directory to install application into. [default: 02:30:51 INFO - "/builds/slave/test"] 02:30:51 INFO - --app=APP Application being installed. [default: firefox] 02:30:51 INFO - mkdir: /builds/slave/test/build/application 02:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:31:07 INFO - Reading from file tmpfile_stdout 02:31:07 INFO - Output received: 02:31:07 INFO - /builds/slave/test/build/application/firefox/firefox 02:31:07 INFO - Running post-action listener: _resource_record_post_action 02:31:07 INFO - ##### 02:31:07 INFO - ##### Finished install step (success) 02:31:07 INFO - ##### 02:31:07 INFO - ##### 02:31:07 INFO - ##### Running run-tests step. 02:31:07 INFO - ##### 02:31:07 INFO - Running pre-action listener: _resource_record_pre_action 02:31:07 INFO - Running main action method: run_tests 02:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:31:07 INFO - Python 2.7.3 02:31:07 INFO - Return code: 0 02:31:07 INFO - grabbing minidump binary from tooltool 02:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:31:07 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 0x2a0b3b0>, '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 0x2a0c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a0c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:31:07 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 02:31:07 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 02:31:07 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 02:31:08 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:31:08 INFO - Return code: 0 02:31:08 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:31:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:31:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:31:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:31:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:31:08 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/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 02:31:08 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/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 02:31:08 INFO - Using env: {'DISPLAY': ':0', 02:31:08 INFO - 'HOME': '/home/cltbld', 02:31:08 INFO - 'LANG': 'en_US.UTF-8', 02:31:08 INFO - 'LANGUAGE': 'en_US:en', 02:31:08 INFO - 'LOGNAME': 'cltbld', 02:31:08 INFO - 'MAIL': '/var/mail/cltbld', 02:31:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:31:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:31:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:31:08 INFO - 'MOZ_NO_REMOTE': '1', 02:31:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:31:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:31:08 INFO - 'NO_EM_RESTART': '1', 02:31:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:31:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:31:08 INFO - 'PWD': '/builds/slave/test', 02:31:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:31:08 INFO - 'SHELL': '/bin/bash', 02:31:08 INFO - 'SHLVL': '1', 02:31:08 INFO - 'TERM': 'linux', 02:31:08 INFO - 'TMOUT': '86400', 02:31:08 INFO - 'USER': 'cltbld', 02:31:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882', 02:31:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:31:08 INFO - '_': '/tools/buildbot/bin/python'} 02:31:08 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/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 02:31:08 INFO - mozversion application_buildid: 20160719010919 02:31:08 INFO - mozversion application_changeset: 0175a8da10333b8df86bf886b3490050bbabbaf9 02:31:08 INFO - mozversion application_display_name: Nightly 02:31:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:31:08 INFO - mozversion application_name: Firefox 02:31:08 INFO - mozversion application_remotingname: firefox 02:31:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 02:31:08 INFO - mozversion application_vendor: Mozilla 02:31:08 INFO - mozversion application_version: 50.0a1 02:31:08 INFO - mozversion platform_buildid: 20160719010919 02:31:08 INFO - mozversion platform_changeset: 0175a8da10333b8df86bf886b3490050bbabbaf9 02:31:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 02:31:08 INFO - mozversion platform_version: 50.0a1 02:31:08 INFO - using testdate: 1468920668 02:31:08 INFO - actual date: 1468920668 02:31:08 INFO - starting webserver on 'localhost:47103' 02:31:08 INFO - SUITE-START | Running 2 tests 02:31:08 INFO - TEST-START | dromaeo_css 02:31:08 INFO - operating with platform_type : linux_ 02:31:08 INFO - Initialising browser for dromaeo_css test... 02:31:08 INFO - TEST-INFO | started process 15828 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3ezmv2/profile http://localhost:47103/getInfo.html) 02:31:13 INFO - PROCESS | 15828 | __metrics Screen width/height:1600/1200 02:31:13 INFO - PROCESS | 15828 | colorDepth:24 02:31:13 INFO - PROCESS | 15828 | Browser inner width/height: 1024/697 02:31:13 INFO - PROCESS | 15828 | __metrics 02:31:14 INFO - PROCESS | 15828 | 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" 02:31:14 INFO - PROCESS | 15828 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:31:15 INFO - PROCESS | 15828 | console.error: PushService: 02:31:15 INFO - PROCESS | 15828 | stateChangeProcessEnqueue: Error transitioning state 02:31:15 INFO - PROCESS | 15828 | UnknownError 02:31:15 INFO - TEST-INFO | 15828: exit 0 02:31:15 INFO - Browser initialized. 02:31:15 INFO - Running cycle 1/1 for dromaeo_css test... 02:31:15 INFO - TEST-INFO | started process 15908 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3ezmv2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:31:27 INFO - PROCESS | 15908 | RSS: Main: 159653888 02:31:27 INFO - PROCESS | 15908 | 02:32:18 INFO - PROCESS | 15908 | 1468920738630 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] 02:34:31 INFO - PROCESS | 15908 | Cycle 1(1): loaded http://localhost:47103/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47103/tests/dromaeo/cssquery-ext.html) 02:34:32 INFO - PROCESS | 15908 | RSS: Main: 271581184 02:34:32 INFO - PROCESS | 15908 | 02:37:35 INFO - PROCESS | 15908 | Cycle 1(1): loaded http://localhost:47103/tests/dromaeo/cssquery-ext.html (next: http://localhost:47103/tests/dromaeo/cssquery-jquery.html) 02:37:36 INFO - PROCESS | 15908 | RSS: Main: 243171328 02:37:36 INFO - PROCESS | 15908 | 02:40:38 INFO - PROCESS | 15908 | Cycle 1(1): loaded http://localhost:47103/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47103/tests/dromaeo/cssquery-mootools.html) 02:40:39 INFO - PROCESS | 15908 | RSS: Main: 239886336 02:40:39 INFO - PROCESS | 15908 | 02:43:41 INFO - PROCESS | 15908 | Cycle 1(1): loaded http://localhost:47103/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47103/tests/dromaeo/cssquery-prototype.html) 02:43:42 INFO - PROCESS | 15908 | RSS: Main: 236929024 02:43:42 INFO - PROCESS | 15908 | 02:46:45 INFO - PROCESS | 15908 | Cycle 1(1): loaded http://localhost:47103/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47103/tests/dromaeo/cssquery-yui.html) 02:46:45 INFO - PROCESS | 15908 | RSS: Main: 213598208 02:46:45 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | Cycle 1(1): loaded http://localhost:47103/tests/dromaeo/cssquery-yui.html (next: http://localhost:47103/tests/dromaeo/cssquery-dojo.html) 02:49:49 INFO - PROCESS | 15908 | RSS: Main: 275296256 02:49:49 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | __start_tp_report 02:49:49 INFO - PROCESS | 15908 | _x_x_mozilla_page_load 02:49:49 INFO - PROCESS | 15908 | _x_x_mozilla_page_load_details 02:49:49 INFO - PROCESS | 15908 | |i|pagename|runs| 02:49:49 INFO - PROCESS | 15908 | |0;dojo.html;2593.2738833126723;2613.4119823039805;2638.1689767723165;2650.6819181698197;2652.694940081891;4502.594766471017;4560.270356742922;4692.366530518383;4697.131030759312;4707.505711900249;4669.649776266777;4681.976590117053;4688.85933421997;4715.764211789414;4716.811327546894;4269.658427325814;4270.935935960963;4345.435093437849;4384.254676704952;4466.285394336906;4379.627731642814;4398.230309695807;4420.668449866257;4421.270490369086;4452.866414007585;4254;4281.31498960168;4303.956960430387;4305.698601097924;4358.476982762058;4243.087736136746;4246.40550322955;4251.638610718092;4289.163613095417;4301.870943871689;4272.764997925113;4275.209086319041;4284.164587905359;4318.913621727579;4322.070754787705;4413.58070983256;4624.491305956376;4679.953200467986;4687.765611719435;4697.2484402495775;5042.747862606891;5089.3638295213095;5097;5103.413107492649;5104.515071068243;5085.720285384303;5095.464976177485;5132.717700526469;5133.563647090002;5136.794528218867;4535.909281814378;4590.517995610479;4631.536846315342;4632.953670463319;4692.882677933079;5122.795088196505;5138.820141295037;5142.280080788692;5142.665726727751;5146.30524879144;4905.435874874435;4948.455669876311;4948.727819969936;4949.059678661115;4949.975250123726;4852.053849499384;5031.094403007493;5047.772850221749;5062.063518249062;5077.822276220389;5049.596032317406;5064.442911279756;5065.544101030925;5068.771905264272;5120.539151476385;5033.874153146201;5035.1188542005;5036.84889453317;5038.017586570657;5039.84880453587;4117.814698338582;4140.834366625361;4148.605882441163;4262.531121576624;4339.153864996359;3672.3022625701033;3678.448232765053;3711.257748450321;3725.441183822394;3729.8321575529167;4256.6807489438415;4258.936115958263;4264.360345948071;4300.61294483498;4301.892452688708;4861.854144375674;4876.4879687633;4907.803687852517;4963.45402005779;4964.702117872939;4300.827966881289;4317.935230971538;4333.891652708708;4374.256376416017;4384;4553.54464553542;4555.111753208159;4567.817287308537;4568.109218702321;4598.425196850394;4655.2784318430695;4726.550977657117;4736.3842700450095;4772.832950846704;4774.40319960005;5025.040488351812;5050.74746262678;5052.040112378636;5054.620903432184;5088.491150884882;4801.783919723551;4803.207470767285;4805.3512775774825;4805.735684537384;4837.879053023703;4736.8578942631775;4934.506549345037;4957.82647607332;4987.900241995212;5040.798368065236;5054.16606259963;5061.645684802029;5070.366204224472;5078.593712503065;5124.180131178993;4929.334539837076;4939.234418665113;4940.7035577865445;4942.3327850741125;4989.925151122663;4731.905361892674;4875.87810304731;4917.778699958439;4938.333325001079;4953.876153096201;4847.224444088929;4862.124817532878;4880.438749543848;4908.705477671351;4948.406191257072;4793.520647935318;4949.876253093701;4995.275685025578;4998.6750861193905;5001.8499445016705;4778.665493415567;4813.278008298643;4826.300186473012;4849;4852.684575502581 02:49:49 INFO - PROCESS | 15908 | |1;ext.html;553.0780302494042;577.933537643171;586.0505980311896;588.1354409018741;603.1435361786263;29926;39469.605303946955;40661.796691016505;40747.796261018724;44635.77682111593;41708.79145604275;42104.789476052654;42250;42310.576894231046;42318.57681423185;7578.621068946558;7800.258975397328;7940.920590794076;10004.149647279954;10297.485125743682;9984.301098923079;10071.194304455663;10073.496325183749;10081.747956301078;10084.092431681147;10172.745681357988;10185.236107291945;10199.54102065409;10216.693499195037;10228;7673.693052277902;9956.900430995705;10241.590336386536;10242.129418999393;10572.048515633553;10116.291859569834;10134.23993200513;10205.64280250195;10212.74468138295;10214.846777298346;29846.10461686153;39284.21431571353;39632.60367396318;39715.20569588621;39727;3169.35028319194;3276.2955964467756;3339.5992480902446;3339.716124129452;3572.9464058039152;28088.719112808816;28591.28521786951;31117;31185.376292474022;31368.529472057937;3542.2738338640775;3554.147004718849;3560.9821950890077;3572.1248294167835;3629.745917785756;258051;261725;262442;262945;263172.68413658;58133.76796088372;58884.411155887894;58902;58904;59038.40961590415;57563.42436575665;59496;60550;60821;67127;13089.869101309056;15601.921990389976;15608.297626606603;15609;15757.290921908312;10755.354678719303;11185.776284474265;11196.832047519294;11203.103751700008;11778.587749428729;18413.422120903084;19308.903455482632;19371.225150993803;19377.612447750966;19461;6925.792226233221;7114.64426778659;7141.143062832493;7229.92770072303;7232.92767072333;840.5167028958373;879.5909901895649;886.9290456763443;888.9419204725223;983.4197823284296;28309.71690283112;28909.56635650467;29245.707542924298;29274.707252927197;29292.267693307836;969.3747532841278;989.8218320702342;994.229472159068;1008.1027885182194;1040.0119886108228;14878.181700006387;14885.553433397015;15348.846511534743;15364.846351536566;15623.296951637203;385441;387176;391596;391964;392704;51156;51505.742471288155;51644.48355516472;51722.48277517252;51786.70533236699;1089.7057794395469;1135.3301552084145;1159.2349049627205;1178.793711100571;1186.6618013866007;26678.733212667627;29482.705172947997;29631.555526666685;30203.244918877204;30241.848790755907;42182.156356872074;42620;42733.35899961565;42837.78581107199;42846.78576607097;297872;298558;299109;299310;299647;30946.381072377975;37720.81139594394;38193.23613527769;38890.4166437498;41870.1625967485;41836.37245441382;42490.57509424866;42527.787361063;43471;43576.78211608922;36595.81702091579;36905.26189476142;36917;37226.627733722315;37586;36619.81690091533;36765.44851827278;37164.81417592895;37292;37601.4359784598;35586.28827423489;36252;36439.81780091082;36682.44976325411;36840.44739328966;32614;32966.83516582402;32999.34001319912;33179;33446;25565.744342556336;25571.744282556938;25599.74400256048;25671.486570268862;26155 02:49:49 INFO - PROCESS | 15908 | |2;jquery.html;342.1514643683666;366.38813181986063;371.2056199732572;372.1904856936164;377.219156346363;12922;16079.35682572697;16529.25618347174;16830.57923551912;17787;16193.757093643604;16956.4065257716;16966.660666786658;17310.394136205236;17382.652346953055;4584.991301913574;5048.570871475919;5218.712970786605;5223.71269580173;5232.2674825524455;5162.664426812245;5200.817971371002;5207.843764687065;5232.189010703347;5251.501107394792;4967.304577359173;5087.389513258396;5135.486451354872;5141.588672906178;5190.896182076356;5113.181890897457;5139.049275883974;5151.793928242866;5156.458571849939;5157.7936882524655;4571.451425828888;4727.57451829337;5024.874378140539;5026.371703537058;5076.873078173076;12070.577529786415;12129.09031822608;12221.511139554495;14418.711625767426;20380.898095509427;1871.9251229950785;1905.7236700678463;1936.2255097960788;1948.4349538633778;2042.3566576528494;8661.090585488559;8755.430896991675;9209.861852072225;9297.581608827619;9345.112214339626;1574.5906064423218;1677.9832201677948;1735.9652806943916;1737.5048111288222;1749.1604030065512;66011;68606.31393686049;69329.3067069328;71051;72176.91734624037;9017.81964360709;9463.90536094644;10150.847737283946;10286.434246116385;10310.226732995055;12076.15466917325;12951.222926624432;12972.221666700028;13285.335733213495;13287.800682989762;5706.563438986586;7494.40044796415;7590.316871481593;7606.847863042708;7711.0746710395115;6289.276733175743;6546.869062618816;7313.341799238094;7343.596102214323;7453.664585093684;3989.4414781930554;4204.255234077885;4221.261279276115;4266.338717498793;4289.0992891492515;1828.4697437742905;1898.9810101898804;1903.6097599991967;1904.6381187574316;1905.1712505060343;828.349745449819;880.7269746378515;897.3449381951212;962.5043102802063;977.4770497783652;16416.83583164177;17104.91447542779;17202.913985429994;17474.82525174732;17591.38430154964;849.5030407211726;858.4763294390417;895.9686410975585;909.8225845959975;910.977225569366;9454.621815127319;9529.332946693803;9601.951990240144;9701.563429645683;9875.061869122423;357615;358645;361157;364636;365099;80477.59761201157;80722;83344;83699;84125.57937210274;846.2172490446189;855.8331125430482;856.1780690537154;856.8200677857595;868.8653258744905;14311;15328.846711533188;15582.9220853895;15616.219189040276;15957.760633590739;37327.25345493129;38046;39120.02199945024;39121.6087839118;39368.40947385734;47773.5222647783;50096.248556270955;51793.96412071813;51982.74008629932;52209.47790522046;4501.032278060102;6668.499862510234;6875.346842049998;6993.300669932966;7274.617822613686;8373.874391884005;11608.24546404481;11995.880041199476;12052.216605920587;12080.21478603888;10406.687799366031;10561.419121948367;10648.307859989076;10737.194710396596;10928.289661171999;9844.556994935101;10368.118709909742;10390.324628899098;10474.109700675528;10904.34573925567;10892.836607450736;10962.287451315959;10979.39613321275;11030.06244469197;11395.886041139482;7812.218778122142;7910.8813367798375;7931.960340198262;7939.245771651684;7997.640106195119;8868.822623547621;10480.790384192122;10723.839142413026;10766.46167691628;10775.407352595368 02:49:49 INFO - PROCESS | 15908 | |3;mootools.html;1379.7861331493618;1403.5999740074078;1481.3778213150476;1668.8247733987923;1674.9078800665966;7281;7756.340711039568;7800.7269745558915;7802.453828232026;7812.960935195323;6328.3671632836695;6558;7258;7414.998975138355;7457.216992215807;2194.9341519754394;2217.589745897006;2225.4436390902274;2228.799408053275;2230.565039817236;1846.298406605488;2117.6717608770587;2139.8930053497343;2201.0975500044983;2201.4276288165033;2130.5419334843004;2191.9452013699706;2197.3627647982066;2219.644856822909;2224.699665545155;2173.1741938063433;2180.062573093577;2190.616642087636;2209.4034610655035;2213.8893055347166;2116.968245476319;2134.6050980568643;2138.4546940530067;2139.3795799218365;2146.2809958663865;7651.5409075455655;7792.376609871197;7803.219678032208;7803.531788092732;7806.219378062206;4469.597736203757;4594.977025114887;4598.356230127785;4601.665517000066;4629.537046295377;2769.0862015534826;2837.9290517737013;2876.885489165462;2888.714522037694;2894.160693398912;948.8861336639648;964.0333719212797;967.5839389062701;970.6166064404521;973.634886917406;10322.845157322647;10369.429681367521;10397.324173928746;10422.322549034365;10429.895701042968;4194.916101677949;4281.807318670667;4286.742795432284;4359.14996575671;4373.978130109329;8884;8894.199522042918;8935.910640893508;8947.731568052968;8951.686690965915;4632.050429661911;4656.697314674535;4656.743879086615;4657.417822772153;4661.067786442657;4147.108371700099;4273.7008409411665;4275.850345237902;4279.358096285582;4280.293751530962;3702.426123950792;3703.4074548072717;3753.8123093845743;3783.2622638585212;3819.52255968004;1108.7505311304892;1175.5121624525898;1224.2776761710634;1243.7885559454915;1248.8189212564128;814.9348052155814;824.4435006370676;828.2297463359014;861.8448679237672;880.212210071982;5247.527722504993;5321.946780532223;5402.594805389533;5406.486383793534;5542.418046105182;858.8582883824224;869.3175856952262;888.0276097673037;889.8932128144535;942.1473566422399;3626.4197728363333;3784.6215378461934;3850.2492014057552;3856.5564960029956;3906.0820707133803;9244.121808428188;9438.292128090421;9455.479948602893;9487.857682134774;9535.666251681158;8093.514389136671;8389.664413423518;8394.706185283485;9234.076592340713;9399.57701903416;7122.92877071233;7309.013283206806;7324.4872858900435;7335.853282934311;7354.705811767576;5539.14143307788;5577.2191893135805;5586.888262234814;5603.411641777718;5689.2319536862;8671;8814.382993190415;8839.69061082859;8858.95570522169;8859.202671759573;8602.526861022703;8774.517401542977;9011.594478248626;9061.77345566366;9106.134917182857;6340.207474065742;6366.4588509977175;6387.201599800025;6408.935910640834;6427.903581446376;7384.48308618413;7407.333339999426;7457.105147382132;7481.738139165103;7484.513506622052;6578.868422631424;6637.535372523877;6736.292689267556;6793.93206067933;6841.110655614738;6534.771283005072;6580.341965803382;6611.305812889577;6626.370494802986;6707.061011458497;6370.362963703593;6420.1974753155855;6426.614403135827;6433.195850518685;6438.67806609677;4497.538300052562;4553.180427522945;4672.696274742131;4706.788194531186;4709.0817290628;4524.434445694288;4613.19269127908;4650.255959046536;4668.089722504079;4761.666683332133 02:49:49 INFO - PROCESS | 15908 | |4;prototype.html;292.27953095619296;298.2737035318997;300.14309147384216;307.28709394205424;310.0203357390646;4825.600575833008;4848.733319667417;4878.975605121969;4883.316335713003;4918.655694101414;4529.524399938;4657.720536767796;4703.247480403136;4833;4834.540718631733;3797.411401232813;3799.7270914243763;3831.3103641344546;3832.6742226910746;3841.4621952926473;3849.2109117630957;3849.2493963677093;3875.4380614810834;3880.165764360662;3892.2020985698005;3634.9273014539694;3718.4236443351188;3802.6577608015273;3817.790021548814;3838.865639702611;3837.8870127662944;3838.136419305662;3846.423036544513;3847.153626202217;3856.8071596420345;3417.2823707021557;3418.4017796885446;3425.383430982422;3435.8282085895853;3440.9655903440894;4436.511983681792;4771.188897887333;4796.232602783538;4800.423949126127;4821.7589120543835;4263.211305908386;4324.372965919955;4347.30443129101;4363.563643635643;4372.956270437318;5027.2907211548245;5032.572231360339;5035.194368900995;5062.898742025139;5087.465816089331;3547.5330687502246;3619.674229319374;3620.366435873738;3628.0748409155494;3638.490611314418;5166.948330516684;5262.842174721555;5276.683398996072;5291.3121294231505;5294.920576191399;3666.698322095671;3680.9263814723554;3681.9447708284397;3682.410814269704;3712.350338690718;3730.925381492355;3756.3050835595504;3765.5293088363956;3774.226283611853;3777.8866634001024;4111.568285329998;4265.87202383929;4310.396544483775;4318.3090705486975;4334.566543345703;5022.668992716933;5046.798128074909;5055.69665820052;5061.493850614909;5065.214891691794;1220.1519943639264;1258.7167887225485;1269.6635391621228;1293.531841360063;1302.9022823288292;854.1458541458542;873.4802792338548;889.8175873945955;890.7906641939139;894.1684233662626;4455.309427038814;4456.9331460028125;4459.019015816515;4476.798544065525;4480.775961201927;5056.393232812086;5084.41529224144;5117.309163262912;5144.768485418165;5149.253358263031;4453.866384008485;4462.910741785211;4467.664925130628;4474.552544745565;4480.9103817924115;2296.8047715944;2433.722295794722;2447.2902858171287;2455.226603619854;2461.1878080233478;4337.718048326912;4358.847440339573;4359.476862776487;4372.846950356722;4466.888327791831;4654.557817007379;4659.8136074557315;4683.765811709401;4685.5943217034755;4731.1720448921305;4369;4594.862154135381;4993.500649934905;4998.600111991104;5004.899902001866;5220.347456567929;5220.556252718561;5229.529342359206;5246.501582349671;5268.209768534751;5130.204818253177;5196.792128314825;5200.557952574073;5224.399194092728;5224.608154388512;4776.808927642855;4996.200607902718;5019.623528235324;5032.119379108714;5032.949670503248;3707.8146092695797;3725.6646901778972;3744.513213282256;3749.156439801132;3752.8498860045293;5006.924896126634;5289.947100528945;5302.992431438014;5304.480371823353;5314.77760115168;4693.131770622445;5011.949880501148;5074.619403544675;5096.159133742891;5102.79588816458;5065.189569668835;5089.312942752829;5103.642745007814;5106.4893510648635;5106.66806657566;5017.498250174954;5068.619853511075;5073.579397768631;5076.137056700443;5080.237964305384;4897.314375987436;5123.410807757006;5128.743562821918;5131;5133.486651334986;4988.079589358051;5000.924986125138;5010.624203184849;5019.698818070781;5082.3138876252715 02:49:49 INFO - PROCESS | 15908 | |5;yui.html;634.5241069198102;727.78894120705;733.7138515978769;751.2074761127016;756.7022556315917;12543.811842822353;13274.203547787127;13297;13356.866431335671;14289.356978935946;14573.854261457382;15207.163606001666;15208.923955380234;15209.011414258093;15214.391424343014;621.0435928669843;771.6913234706107;773.5320131320555;793.4525177627419;795.8010497375656;774.1368374262713;780.5511830697343;780.7696729464822;781.2421950707804;781.2968328504335;806.3428305930677;806.8345989072255;811.7199566149699;812.1715849833166;812.25272749071;651.9608823470606;798.1260519730886;806.2179685704857;808.8261023879865;809.1261437647356;599.1462166412845;599.8830228105521;599.9610025348338;600.051917969607;600.2586805295457;13285.468581256739;13287.86712132876;13325.600231993056;13326.133801302884;13364.465421383133;11973.700657483545;12411.875881241253;12649.747005059939;12800;12984.026198035184;11345.658274475034;11469.713257168638;11486.42567871603;11530.13523985696;11951;4144.834206631731;4163.2298024865195;4218.34615634574;4261.595148460891;4305.892352691177;58896.705516472575;59536;60006.69996649989;60635.69682151561;60704.696476517776;10147.391156530632;10304.845427318596;10357.4303413313;10399.32404393712;10699.25105242641;18171.72742408865;18200.726989095176;18208.180631871597;18217.908910455364;18268.451946441626;6840.418564421981;6903.896441553381;6906.723731050803;6913.481488888354;7059.89410158848;3667.4132138857653;3792.4311353297226;3796.7532110412735;3814.809259537012;3824.388097904322;2650.3109191609933;2747.917562473129;2753.4630747004144;2754.9724502754716;2755.2698534888273;492.2075458511793;531.4340227657532;555.5139253153491;559.027292511036;571.2516603249757;496.46133944669714;497.57457373944754;560.4675558219708;592.8547505861092;644.1851058411114;7942;8268.83462330762;8327.458715183493;8395.790105247417;8611.13888611134;495.5587647628982;557.8493806672176;594.6016169166669;604.2959951656359;604.7792555717219;3863.9227215456094;4080.81636326366;4120.711550191458;4232.873013809591;4303.462067241595;88413;92449;93968.53015734878;94967;94983.52508237415;81382.59308703418;81646.59176704078;81827.59086204531;82028;82702;71189;71778;73606.6319668409;76267.23732762602;77961;8640.308775298086;8746.91253087461;8765.211130998241;8835.469871807712;8991.14584114508;31041.534376983913;38899.29333033618;40067.59932400638;41510.92445377779;44688.77655611701;39956;40954;41529.169416610894;47551;64333.71332573416;11404;11526.82709759371;11710.824337635113;11799.882001179878;11886.702832429259;12727.363631818187;12831.743365132832;12983.221006739626;13337.066405351532;13735;12122.75754484923;12659.936700316808;12716.427760750974;12839.229646221256;12867.485300588247;11697;12190.51237950472;12230.510779568718;12230.938845306073;12829.807552886528;11932.1050921183;12029.338386388483;12154.149209555246;12420.068494863102;12538.059645526804;10070.69787906364;10112.696619101438;10143.391396516232;10499.370037797757;10742.086922611354;9780.706578802647;9874.21006319507;9892.307538472238;10016.248781341283;10124.190064794946 02:49:49 INFO - PROCESS | 15908 | __end_tp_report 02:49:49 INFO - PROCESS | 15908 | __start_cc_report 02:49:49 INFO - PROCESS | 15908 | _x_x_mozilla_cycle_collect,1038 02:49:49 INFO - PROCESS | 15908 | __end_cc_report 02:49:49 INFO - PROCESS | 15908 | __startTimestamp1468921789331__endTimestamp 02:49:49 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | ------- Summary: start ------- 02:49:49 INFO - PROCESS | 15908 | Number of tests: 6 02:49:49 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | [#0] dojo.html Cycles:165 Average:4646.45 Median:4778.67 stddev:496.57 (10.4%) stddev-sans-first:471.25 02:49:49 INFO - PROCESS | 15908 | Values: 2593.3 2613.4 2638.2 2650.7 2652.7 4502.6 4560.3 4692.4 4697.1 4707.5 4669.6 4682.0 4688.9 4715.8 4716.8 4269.7 4270.9 4345.4 4384.3 4466.3 4379.6 4398.2 4420.7 4421.3 4452.9 4254.0 4281.3 4304.0 4305.7 4358.5 4243.1 4246.4 4251.6 4289.2 4301.9 4272.8 4275.2 4284.2 4318.9 4322.1 4413.6 4624.5 4680.0 4687.8 4697.2 5042.7 5089.4 5097.0 5103.4 5104.5 5085.7 5095.5 5132.7 5133.6 5136.8 4535.9 4590.5 4631.5 4633.0 4692.9 5122.8 5138.8 5142.3 5142.7 5146.3 4905.4 4948.5 4948.7 4949.1 4950.0 4852.1 5031.1 5047.8 5062.1 5077.8 5049.6 5064.4 5065.5 5068.8 5120.5 5033.9 5035.1 5036.8 5038.0 5039.8 4117.8 4140.8 4148.6 4262.5 4339.2 3672.3 3678.4 3711.3 3725.4 3729.8 4256.7 4258.9 4264.4 4300.6 4301.9 4861.9 4876.5 4907.8 4963.5 4964.7 4300.8 4317.9 4333.9 4374.3 4384.0 4553.5 4555.1 4567.8 4568.1 4598.4 4655.3 4726.6 4736.4 4772.8 4774.4 5025.0 5050.7 5052.0 5054.6 5088.5 4801.8 4803.2 4805.4 4805.7 4837.9 4736.9 4934.5 4957.8 4987.9 5040.8 5054.2 5061.6 5070.4 5078.6 5124.2 4929.3 4939.2 4940.7 4942.3 4989.9 4731.9 4875.9 4917.8 4938.3 4953.9 4847.2 4862.1 4880.4 4908.7 4948.4 4793.5 4949.9 4995.3 4998.7 5001.8 4778.7 4813.3 4826.3 4849.0 4852.7 02:49:49 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | [#1] ext.html Cycles:180 Average:48569.69 Median:19573.64 stddev:84307.34 (430.7%) stddev-sans-first:84466.75 02:49:49 INFO - PROCESS | 15908 | Values: 553.1 577.9 586.1 588.1 603.1 29926.0 39469.6 40661.8 40747.8 44635.8 41708.8 42104.8 42250.0 42310.6 42318.6 7578.6 7800.3 7940.9 10004.1 10297.5 9984.3 10071.2 10073.5 10081.7 10084.1 10172.7 10185.2 10199.5 10216.7 10228.0 7673.7 9956.9 10241.6 10242.1 10572.0 10116.3 10134.2 10205.6 10212.7 10214.8 29846.1 39284.2 39632.6 39715.2 39727.0 3169.4 3276.3 3339.6 3339.7 3572.9 28088.7 28591.3 31117.0 31185.4 31368.5 3542.3 3554.1 3561.0 3572.1 3629.7 258051.0 261725.0 262442.0 262945.0 263172.7 58133.8 58884.4 58902.0 58904.0 59038.4 57563.4 59496.0 60550.0 60821.0 67127.0 13089.9 15601.9 15608.3 15609.0 15757.3 10755.4 11185.8 11196.8 11203.1 11778.6 18413.4 19308.9 19371.2 19377.6 19461.0 6925.8 7114.6 7141.1 7229.9 7232.9 840.5 879.6 886.9 888.9 983.4 28309.7 28909.6 29245.7 29274.7 29292.3 969.4 989.8 994.2 1008.1 1040.0 14878.2 14885.6 15348.8 15364.8 15623.3 385441.0 387176.0 391596.0 391964.0 392704.0 51156.0 51505.7 51644.5 51722.5 51786.7 1089.7 1135.3 1159.2 1178.8 1186.7 26678.7 29482.7 29631.6 30203.2 30241.8 42182.2 42620.0 42733.4 42837.8 42846.8 297872.0 298558.0 299109.0 299310.0 299647.0 30946.4 37720.8 38193.2 38890.4 41870.2 41836.4 42490.6 42527.8 43471.0 43576.8 36595.8 36905.3 36917.0 37226.6 37586.0 36619.8 36765.4 37164.8 37292.0 37601.4 35586.3 36252.0 36439.8 36682.4 36840.4 32614.0 32966.8 32999.3 33179.0 33446.0 25565.7 25571.7 25599.7 25671.5 26155.0 02:49:49 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | [#2] jquery.html Cycles:180 Average:23452.51 Median:4536.24 stddev:60078.56 (1324.4%) stddev-sans-first:60222.04 02:49:49 INFO - PROCESS | 15908 | Values: 342.2 366.4 371.2 372.2 377.2 12922.0 16079.4 16529.3 16830.6 17787.0 16193.8 16956.4 16966.7 17310.4 17382.7 4585.0 5048.6 5218.7 5223.7 5232.3 5162.7 5200.8 5207.8 5232.2 5251.5 4967.3 5087.4 5135.5 5141.6 5190.9 5113.2 5139.0 5151.8 5156.5 5157.8 4571.5 4727.6 5024.9 5026.4 5076.9 12070.6 12129.1 12221.5 14418.7 20380.9 1871.9 1905.7 1936.2 1948.4 2042.4 8661.1 8755.4 9209.9 9297.6 9345.1 1574.6 1678.0 1736.0 1737.5 1749.2 66011.0 68606.3 69329.3 71051.0 72176.9 9017.8 9463.9 10150.8 10286.4 10310.2 12076.2 12951.2 12972.2 13285.3 13287.8 5706.6 7494.4 7590.3 7606.8 7711.1 6289.3 6546.9 7313.3 7343.6 7453.7 3989.4 4204.3 4221.3 4266.3 4289.1 1828.5 1899.0 1903.6 1904.6 1905.2 828.3 880.7 897.3 962.5 977.5 16416.8 17104.9 17202.9 17474.8 17591.4 849.5 858.5 896.0 909.8 911.0 9454.6 9529.3 9602.0 9701.6 9875.1 357615.0 358645.0 361157.0 364636.0 365099.0 80477.6 80722.0 83344.0 83699.0 84125.6 846.2 855.8 856.2 856.8 868.9 14311.0 15328.8 15582.9 15616.2 15957.8 37327.3 38046.0 39120.0 39121.6 39368.4 47773.5 50096.2 51794.0 51982.7 52209.5 4501.0 6668.5 6875.3 6993.3 7274.6 8373.9 11608.2 11995.9 12052.2 12080.2 10406.7 10561.4 10648.3 10737.2 10928.3 9844.6 10368.1 10390.3 10474.1 10904.3 10892.8 10962.3 10979.4 11030.1 11395.9 7812.2 7910.9 7932.0 7939.2 7997.6 8868.8 10480.8 10723.8 10766.5 10775.4 02:49:49 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | [#3] mootools.html Cycles:180 Average:5089.03 Median:5559.82 stddev:2768.69 (49.8%) stddev-sans-first:2762.42 02:49:49 INFO - PROCESS | 15908 | Values: 1379.8 1403.6 1481.4 1668.8 1674.9 7281.0 7756.3 7800.7 7802.5 7813.0 6328.4 6558.0 7258.0 7415.0 7457.2 2194.9 2217.6 2225.4 2228.8 2230.6 1846.3 2117.7 2139.9 2201.1 2201.4 2130.5 2191.9 2197.4 2219.6 2224.7 2173.2 2180.1 2190.6 2209.4 2213.9 2117.0 2134.6 2138.5 2139.4 2146.3 7651.5 7792.4 7803.2 7803.5 7806.2 4469.6 4595.0 4598.4 4601.7 4629.5 2769.1 2837.9 2876.9 2888.7 2894.2 948.9 964.0 967.6 970.6 973.6 10322.8 10369.4 10397.3 10422.3 10429.9 4194.9 4281.8 4286.7 4359.1 4374.0 8884.0 8894.2 8935.9 8947.7 8951.7 4632.1 4656.7 4656.7 4657.4 4661.1 4147.1 4273.7 4275.9 4279.4 4280.3 3702.4 3703.4 3753.8 3783.3 3819.5 1108.8 1175.5 1224.3 1243.8 1248.8 814.9 824.4 828.2 861.8 880.2 5247.5 5321.9 5402.6 5406.5 5542.4 858.9 869.3 888.0 889.9 942.1 3626.4 3784.6 3850.2 3856.6 3906.1 9244.1 9438.3 9455.5 9487.9 9535.7 8093.5 8389.7 8394.7 9234.1 9399.6 7122.9 7309.0 7324.5 7335.9 7354.7 5539.1 5577.2 5586.9 5603.4 5689.2 8671.0 8814.4 8839.7 8859.0 8859.2 8602.5 8774.5 9011.6 9061.8 9106.1 6340.2 6366.5 6387.2 6408.9 6427.9 7384.5 7407.3 7457.1 7481.7 7484.5 6578.9 6637.5 6736.3 6793.9 6841.1 6534.8 6580.3 6611.3 6626.4 6707.1 6370.4 6420.2 6426.6 6433.2 6438.7 4497.5 4553.2 4672.7 4706.8 4709.1 4524.4 4613.2 4650.3 4668.1 4761.7 02:49:49 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | [#4] prototype.html Cycles:180 Average:4179.40 Median:4658.77 stddev:1215.46 (26.1%) stddev-sans-first:1183.34 02:49:49 INFO - PROCESS | 15908 | Values: 292.3 298.3 300.1 307.3 310.0 4825.6 4848.7 4879.0 4883.3 4918.7 4529.5 4657.7 4703.2 4833.0 4834.5 3797.4 3799.7 3831.3 3832.7 3841.5 3849.2 3849.2 3875.4 3880.2 3892.2 3634.9 3718.4 3802.7 3817.8 3838.9 3837.9 3838.1 3846.4 3847.2 3856.8 3417.3 3418.4 3425.4 3435.8 3441.0 4436.5 4771.2 4796.2 4800.4 4821.8 4263.2 4324.4 4347.3 4363.6 4373.0 5027.3 5032.6 5035.2 5062.9 5087.5 3547.5 3619.7 3620.4 3628.1 3638.5 5166.9 5262.8 5276.7 5291.3 5294.9 3666.7 3680.9 3681.9 3682.4 3712.4 3730.9 3756.3 3765.5 3774.2 3777.9 4111.6 4265.9 4310.4 4318.3 4334.6 5022.7 5046.8 5055.7 5061.5 5065.2 1220.2 1258.7 1269.7 1293.5 1302.9 854.1 873.5 889.8 890.8 894.2 4455.3 4456.9 4459.0 4476.8 4480.8 5056.4 5084.4 5117.3 5144.8 5149.3 4453.9 4462.9 4467.7 4474.6 4480.9 2296.8 2433.7 2447.3 2455.2 2461.2 4337.7 4358.8 4359.5 4372.8 4466.9 4654.6 4659.8 4683.8 4685.6 4731.2 4369.0 4594.9 4993.5 4998.6 5004.9 5220.3 5220.6 5229.5 5246.5 5268.2 5130.2 5196.8 5200.6 5224.4 5224.6 4776.8 4996.2 5019.6 5032.1 5032.9 3707.8 3725.7 3744.5 3749.2 3752.8 5006.9 5289.9 5303.0 5304.5 5314.8 4693.1 5011.9 5074.6 5096.2 5102.8 5065.2 5089.3 5103.6 5106.5 5106.7 5017.5 5068.6 5073.6 5076.1 5080.2 4897.3 5123.4 5128.7 5131.0 5133.5 4988.1 5000.9 5010.6 5019.7 5082.3 02:49:49 INFO - PROCESS | 15908 | 02:49:49 INFO - PROCESS | 15908 | [#5] yui.html Cycles:180 Average:17130.11 Median:4247.23 stddev:23799.66 (560.4%) stddev-sans-first:23834.19 02:49:49 INFO - PROCESS | 15908 | Values: 634.5 727.8 733.7 751.2 756.7 12543.8 13274.2 13297.0 13356.9 14289.4 14573.9 15207.2 15208.9 15209.0 15214.4 621.0 771.7 773.5 793.5 795.8 774.1 780.6 780.8 781.2 781.3 806.3 806.8 811.7 812.2 812.3 652.0 798.1 806.2 808.8 809.1 599.1 599.9 600.0 600.1 600.3 13285.5 13287.9 13325.6 13326.1 13364.5 11973.7 12411.9 12649.7 12800.0 12984.0 11345.7 11469.7 11486.4 11530.1 11951.0 4144.8 4163.2 4218.3 4261.6 4305.9 58896.7 59536.0 60006.7 60635.7 60704.7 10147.4 10304.8 10357.4 10399.3 10699.3 18171.7 18200.7 18208.2 18217.9 18268.5 6840.4 6903.9 6906.7 6913.5 7059.9 3667.4 3792.4 3796.8 3814.8 3824.4 2650.3 2747.9 2753.5 2755.0 2755.3 492.2 531.4 555.5 559.0 571.3 496.5 497.6 560.5 592.9 644.2 7942.0 8268.8 8327.5 8395.8 8611.1 495.6 557.8 594.6 604.3 604.8 3863.9 4080.8 4120.7 4232.9 4303.5 88413.0 92449.0 93968.5 94967.0 94983.5 81382.6 81646.6 81827.6 82028.0 82702.0 71189.0 71778.0 73606.6 76267.2 77961.0 8640.3 8746.9 8765.2 8835.5 8991.1 31041.5 38899.3 40067.6 41510.9 44688.8 39956.0 40954.0 41529.2 47551.0 64333.7 11404.0 11526.8 11710.8 11799.9 11886.7 12727.4 12831.7 12983.2 13337.1 13735.0 12122.8 12659.9 12716.4 12839.2 12867.5 11697.0 12190.5 12230.5 12230.9 12829.8 11932.1 12029.3 12154.1 12420.1 12538.1 10070.7 10112.7 10143.4 10499.4 10742.1 9780.7 9874.2 9892.3 10016.2 10124.2 02:49:49 INFO - PROCESS | 15908 | -------- Summary: end -------- 02:49:49 INFO - PROCESS | 15908 | 02:49:50 INFO - TEST-INFO | 15908: exit 0 02:49:50 INFO - TEST-OK | dromaeo_css | took 1121925ms 02:49:50 INFO - TEST-START | kraken 02:49:50 INFO - operating with platform_type : linux_ 02:49:50 INFO - Initialising browser for kraken test... 02:49:50 INFO - TEST-INFO | started process 16097 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEZOM06/profile http://localhost:47103/getInfo.html) 02:49:54 INFO - PROCESS | 16097 | __metrics Screen width/height:1600/1200 02:49:54 INFO - PROCESS | 16097 | colorDepth:24 02:49:54 INFO - PROCESS | 16097 | Browser inner width/height: 1024/697 02:49:54 INFO - PROCESS | 16097 | __metrics 02:49:55 INFO - PROCESS | 16097 | 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" 02:49:55 INFO - PROCESS | 16097 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 58: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:49:56 INFO - TEST-INFO | 16097: exit 0 02:49:56 INFO - Browser initialized. 02:49:56 INFO - Running cycle 1/1 for kraken test... 02:49:56 INFO - TEST-INFO | started process 16177 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEZOM06/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:50:08 INFO - PROCESS | 16177 | RSS: Main: 159166464 02:50:08 INFO - PROCESS | 16177 | 02:50:11 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47103/tests/kraken/driver.html?testName=audio-beat-detection) 02:50:12 INFO - PROCESS | 16177 | RSS: Main: 218140672 02:50:12 INFO - PROCESS | 16177 | 02:50:15 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47103/tests/kraken/driver.html?testName=audio-dft) 02:50:15 INFO - PROCESS | 16177 | RSS: Main: 206987264 02:50:15 INFO - PROCESS | 16177 | 02:50:19 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47103/tests/kraken/driver.html?testName=audio-fft) 02:50:19 INFO - PROCESS | 16177 | RSS: Main: 271585280 02:50:19 INFO - PROCESS | 16177 | 02:50:21 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47103/tests/kraken/driver.html?testName=audio-oscillator) 02:50:22 INFO - PROCESS | 16177 | RSS: Main: 182521856 02:50:22 INFO - PROCESS | 16177 | 02:50:24 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47103/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:50:25 INFO - PROCESS | 16177 | RSS: Main: 185753600 02:50:25 INFO - PROCESS | 16177 | 02:50:28 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47103/tests/kraken/driver.html?testName=imaging-darkroom) 02:50:28 INFO - PROCESS | 16177 | RSS: Main: 221876224 02:50:28 INFO - PROCESS | 16177 | 02:50:32 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47103/tests/kraken/driver.html?testName=imaging-desaturate) 02:50:33 INFO - PROCESS | 16177 | RSS: Main: 223080448 02:50:33 INFO - PROCESS | 16177 | 02:50:35 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47103/tests/kraken/driver.html?testName=json-parse-financial) 02:50:36 INFO - PROCESS | 16177 | RSS: Main: 223014912 02:50:36 INFO - PROCESS | 16177 | 02:50:38 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47103/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:50:38 INFO - PROCESS | 16177 | RSS: Main: 212402176 02:50:38 INFO - PROCESS | 16177 | 02:50:40 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47103/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:50:41 INFO - PROCESS | 16177 | RSS: Main: 216854528 02:50:41 INFO - PROCESS | 16177 | 02:50:43 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47103/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:50:44 INFO - PROCESS | 16177 | RSS: Main: 212811776 02:50:44 INFO - PROCESS | 16177 | 02:50:46 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47103/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:50:47 INFO - PROCESS | 16177 | RSS: Main: 222769152 02:50:47 INFO - PROCESS | 16177 | 02:50:50 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47103/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:50:50 INFO - PROCESS | 16177 | RSS: Main: 224559104 02:50:50 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | Cycle 1(1): loaded http://localhost:47103/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47103/tests/kraken/driver.html?testName=ai-astar) 02:50:52 INFO - PROCESS | 16177 | RSS: Main: 266702848 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | __start_tp_report 02:50:52 INFO - PROCESS | 16177 | _x_x_mozilla_page_load 02:50:52 INFO - PROCESS | 16177 | _x_x_mozilla_page_load_details 02:50:52 INFO - PROCESS | 16177 | |i|pagename|runs| 02:50:52 INFO - PROCESS | 16177 | |0;ai-astar;105;116;113;114;114;113;113;114;115;115 02:50:52 INFO - PROCESS | 16177 | |1;audio-beat-detection;152;125;124;179;123;124;144;123;153;121 02:50:52 INFO - PROCESS | 16177 | |2;audio-dft;161;162;162;161;160;162;162;162;161;161 02:50:52 INFO - PROCESS | 16177 | |3;audio-fft;76;75;76;118;78;78;76;76;100;75 02:50:52 INFO - PROCESS | 16177 | |4;audio-oscillator;85;84;120;84;85;84;113;84;85;132 02:50:52 INFO - PROCESS | 16177 | |5;imaging-gaussian-blur;117;116;117;117;117;117;116;116;116;118 02:50:52 INFO - PROCESS | 16177 | |6;imaging-darkroom;147;146;147;147;146;146;146;146;147;147 02:50:52 INFO - PROCESS | 16177 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;94 02:50:52 INFO - PROCESS | 16177 | |8;json-parse-financial;64;63;64;62;63;64;63;63;64;64 02:50:52 INFO - PROCESS | 16177 | |9;json-stringify-tinderbox;65;65;65;65;66;65;65;66;66;67 02:50:52 INFO - PROCESS | 16177 | |10;stanford-crypto-aes;78;79;78;78;78;78;80;81;78;81 02:50:52 INFO - PROCESS | 16177 | |11;stanford-crypto-ccm;133;128;141;128;129;138;139;128;178;128 02:50:52 INFO - PROCESS | 16177 | |12;stanford-crypto-pbkdf2;160;163;162;165;164;161;162;164;161;162 02:50:52 INFO - PROCESS | 16177 | |13;stanford-crypto-sha256-iterative;67;68;68;66;65;65;66;67;66;65 02:50:52 INFO - PROCESS | 16177 | __end_tp_report 02:50:52 INFO - PROCESS | 16177 | __start_cc_report 02:50:52 INFO - PROCESS | 16177 | _x_x_mozilla_cycle_collect,1907 02:50:52 INFO - PROCESS | 16177 | __end_cc_report 02:50:52 INFO - PROCESS | 16177 | __startTimestamp1468921852890__endTimestamp 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | ------- Summary: start ------- 02:50:52 INFO - PROCESS | 16177 | Number of tests: 14 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:3.05 (2.7%) stddev-sans-first:1.05 02:50:52 INFO - PROCESS | 16177 | Values: 105.0 116.0 113.0 114.0 114.0 113.0 113.0 114.0 115.0 115.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:134.50 stddev:19.50 (14.5%) stddev-sans-first:19.90 02:50:52 INFO - PROCESS | 16177 | Values: 152.0 125.0 124.0 179.0 123.0 124.0 144.0 123.0 153.0 121.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#2] audio-dft Cycles:10 Average:161.40 Median:162.00 stddev:0.70 (0.4%) stddev-sans-first:0.73 02:50:52 INFO - PROCESS | 16177 | Values: 161.0 162.0 162.0 161.0 160.0 162.0 162.0 162.0 161.0 161.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#3] audio-fft Cycles:10 Average:82.80 Median:76.00 stddev:14.48 (19.1%) stddev-sans-first:15.15 02:50:52 INFO - PROCESS | 16177 | Values: 76.0 75.0 76.0 118.0 78.0 78.0 76.0 76.0 100.0 75.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#4] audio-oscillator Cycles:10 Average:95.60 Median:84.00 stddev:18.55 (22.1%) stddev-sans-first:19.28 02:50:52 INFO - PROCESS | 16177 | Values: 85.0 84.0 120.0 84.0 85.0 84.0 113.0 84.0 85.0 132.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 02:50:52 INFO - PROCESS | 16177 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 118.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 02:50:52 INFO - PROCESS | 16177 | Values: 147.0 146.0 147.0 147.0 146.0 146.0 146.0 146.0 147.0 147.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 02:50:52 INFO - PROCESS | 16177 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:64.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 02:50:52 INFO - PROCESS | 16177 | Values: 64.0 63.0 64.0 62.0 63.0 64.0 63.0 63.0 64.0 64.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#9] json-stringify-tinderbox Cycles:10 Average:65.50 Median:65.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 02:50:52 INFO - PROCESS | 16177 | Values: 65.0 65.0 65.0 65.0 66.0 65.0 65.0 66.0 66.0 67.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:78.50 stddev:1.29 (1.6%) stddev-sans-first:1.32 02:50:52 INFO - PROCESS | 16177 | Values: 78.0 79.0 78.0 78.0 78.0 78.0 80.0 81.0 78.0 81.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#11] stanford-crypto-ccm Cycles:10 Average:137.00 Median:135.50 stddev:15.30 (11.3%) stddev-sans-first:16.16 02:50:52 INFO - PROCESS | 16177 | Values: 133.0 128.0 141.0 128.0 129.0 138.0 139.0 128.0 178.0 128.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.40 Median:162.50 stddev:1.58 (1.0%) stddev-sans-first:1.41 02:50:52 INFO - PROCESS | 16177 | Values: 160.0 163.0 162.0 165.0 164.0 161.0 162.0 164.0 161.0 162.0 02:50:52 INFO - PROCESS | 16177 | 02:50:52 INFO - PROCESS | 16177 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:1.16 (1.7%) stddev-sans-first:1.20 02:50:52 INFO - PROCESS | 16177 | Values: 67.0 68.0 68.0 66.0 65.0 65.0 66.0 67.0 66.0 65.0 02:50:52 INFO - PROCESS | 16177 | -------- Summary: end -------- 02:50:52 INFO - PROCESS | 16177 | 02:50:54 INFO - TEST-INFO | 16177: exit 0 02:50:54 INFO - TEST-OK | kraken | took 63883ms 02:50:54 INFO - SUITE-END | took 1185s 02:50:54 INFO - Completed test suite (00:19:46) 02:50:54 INFO - javascript benchmark 02:50:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2593.2738833126723, 2613.4119823039805, 2638.1689767723165, 2650.6819181698197, 2652.694940081891, 4502.594766471017, 4560.270356742922, 4692.366530518383, 4697.131030759312, 4707.505711900249, 4669.649776266777, 4681.976590117053, 4688.85933421997, 4715.764211789414, 4716.811327546894, 4269.658427325814, 4270.935935960963, 4345.435093437849, 4384.254676704952, 4466.285394336906, 4379.627731642814, 4398.230309695807, 4420.668449866257, 4421.270490369086, 4452.866414007585, 4254.0, 4281.31498960168, 4303.956960430387, 4305.698601097924, 4358.476982762058, 4243.087736136746, 4246.40550322955, 4251.638610718092, 4289.163613095417, 4301.870943871689, 4272.764997925113, 4275.209086319041, 4284.164587905359, 4318.913621727579, 4322.070754787705, 4413.58070983256, 4624.491305956376, 4679.953200467986, 4687.765611719435, 4697.2484402495775, 5042.747862606891, 5089.3638295213095, 5097.0, 5103.413107492649, 5104.515071068243, 5085.720285384303, 5095.464976177485, 5132.717700526469, 5133.563647090002, 5136.794528218867, 4535.909281814378, 4590.517995610479, 4631.536846315342, 4632.953670463319, 4692.882677933079, 5122.795088196505, 5138.820141295037, 5142.280080788692, 5142.665726727751, 5146.30524879144, 4905.435874874435, 4948.455669876311, 4948.727819969936, 4949.059678661115, 4949.975250123726, 4852.053849499384, 5031.094403007493, 5047.772850221749, 5062.063518249062, 5077.822276220389, 5049.596032317406, 5064.442911279756, 5065.544101030925, 5068.771905264272, 5120.539151476385, 5033.874153146201, 5035.1188542005, 5036.84889453317, 5038.017586570657, 5039.84880453587, 4117.814698338582, 4140.834366625361, 4148.605882441163, 4262.531121576624, 4339.153864996359, 3672.3022625701033, 3678.448232765053, 3711.257748450321, 3725.441183822394, 3729.8321575529167, 4256.6807489438415, 4258.936115958263, 4264.360345948071, 4300.61294483498, 4301.892452688708, 4861.854144375674, 4876.4879687633, 4907.803687852517, 4963.45402005779, 4964.702117872939, 4300.827966881289, 4317.935230971538, 4333.891652708708, 4374.256376416017, 4384.0, 4553.54464553542, 4555.111753208159, 4567.817287308537, 4568.109218702321, 4598.425196850394, 4655.2784318430695, 4726.550977657117, 4736.3842700450095, 4772.832950846704, 4774.40319960005, 5025.040488351812, 5050.74746262678, 5052.040112378636, 5054.620903432184, 5088.491150884882, 4801.783919723551, 4803.207470767285, 4805.3512775774825, 4805.735684537384, 4837.879053023703, 4736.8578942631775, 4934.506549345037, 4957.82647607332, 4987.900241995212, 5040.798368065236, 5054.16606259963, 5061.645684802029, 5070.366204224472, 5078.593712503065, 5124.180131178993, 4929.334539837076, 4939.234418665113, 4940.7035577865445, 4942.3327850741125, 4989.925151122663, 4731.905361892674, 4875.87810304731, 4917.778699958439, 4938.333325001079, 4953.876153096201, 4847.224444088929, 4862.124817532878, 4880.438749543848, 4908.705477671351, 4948.406191257072, 4793.520647935318, 4949.876253093701, 4995.275685025578, 4998.6750861193905, 5001.8499445016705, 4778.665493415567, 4813.278008298643, 4826.300186473012, 4849.0, 4852.684575502581], "value": 4614.402881746514, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.0780302494042, 577.933537643171, 586.0505980311896, 588.1354409018741, 603.1435361786263, 29926.0, 39469.605303946955, 40661.796691016505, 40747.796261018724, 44635.77682111593, 41708.79145604275, 42104.789476052654, 42250.0, 42310.576894231046, 42318.57681423185, 7578.621068946558, 7800.258975397328, 7940.920590794076, 10004.149647279954, 10297.485125743682, 9984.301098923079, 10071.194304455663, 10073.496325183749, 10081.747956301078, 10084.092431681147, 10172.745681357988, 10185.236107291945, 10199.54102065409, 10216.693499195037, 10228.0, 7673.693052277902, 9956.900430995705, 10241.590336386536, 10242.129418999393, 10572.048515633553, 10116.291859569834, 10134.23993200513, 10205.64280250195, 10212.74468138295, 10214.846777298346, 29846.10461686153, 39284.21431571353, 39632.60367396318, 39715.20569588621, 39727.0, 3169.35028319194, 3276.2955964467756, 3339.5992480902446, 3339.716124129452, 3572.9464058039152, 28088.719112808816, 28591.28521786951, 31117.0, 31185.376292474022, 31368.529472057937, 3542.2738338640775, 3554.147004718849, 3560.9821950890077, 3572.1248294167835, 3629.745917785756, 258051.0, 261725.0, 262442.0, 262945.0, 263172.68413658, 58133.76796088372, 58884.411155887894, 58902.0, 58904.0, 59038.40961590415, 57563.42436575665, 59496.0, 60550.0, 60821.0, 67127.0, 13089.869101309056, 15601.921990389976, 15608.297626606603, 15609.0, 15757.290921908312, 10755.354678719303, 11185.776284474265, 11196.832047519294, 11203.103751700008, 11778.587749428729, 18413.422120903084, 19308.903455482632, 19371.225150993803, 19377.612447750966, 19461.0, 6925.792226233221, 7114.64426778659, 7141.143062832493, 7229.92770072303, 7232.92767072333, 840.5167028958373, 879.5909901895649, 886.9290456763443, 888.9419204725223, 983.4197823284296, 28309.71690283112, 28909.56635650467, 29245.707542924298, 29274.707252927197, 29292.267693307836, 969.3747532841278, 989.8218320702342, 994.229472159068, 1008.1027885182194, 1040.0119886108228, 14878.181700006387, 14885.553433397015, 15348.846511534743, 15364.846351536566, 15623.296951637203, 385441.0, 387176.0, 391596.0, 391964.0, 392704.0, 51156.0, 51505.742471288155, 51644.48355516472, 51722.48277517252, 51786.70533236699, 1089.7057794395469, 1135.3301552084145, 1159.2349049627205, 1178.793711100571, 1186.6618013866007, 26678.733212667627, 29482.705172947997, 29631.555526666685, 30203.244918877204, 30241.848790755907, 42182.156356872074, 42620.0, 42733.35899961565, 42837.78581107199, 42846.78576607097, 297872.0, 298558.0, 299109.0, 299310.0, 299647.0, 30946.381072377975, 37720.81139594394, 38193.23613527769, 38890.4166437498, 41870.1625967485, 41836.37245441382, 42490.57509424866, 42527.787361063, 43471.0, 43576.78211608922, 36595.81702091579, 36905.26189476142, 36917.0, 37226.627733722315, 37586.0, 36619.81690091533, 36765.44851827278, 37164.81417592895, 37292.0, 37601.4359784598, 35586.28827423489, 36252.0, 36439.81780091082, 36682.44976325411, 36840.44739328966, 32614.0, 32966.83516582402, 32999.34001319912, 33179.0, 33446.0, 25565.744342556336, 25571.744282556938, 25599.74400256048, 25671.486570268862, 26155.0], "value": 18676.818621564384, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.1514643683666, 366.38813181986063, 371.2056199732572, 372.1904856936164, 377.219156346363, 12922.0, 16079.35682572697, 16529.25618347174, 16830.57923551912, 17787.0, 16193.757093643604, 16956.4065257716, 16966.660666786658, 17310.394136205236, 17382.652346953055, 4584.991301913574, 5048.570871475919, 5218.712970786605, 5223.71269580173, 5232.2674825524455, 5162.664426812245, 5200.817971371002, 5207.843764687065, 5232.189010703347, 5251.501107394792, 4967.304577359173, 5087.389513258396, 5135.486451354872, 5141.588672906178, 5190.896182076356, 5113.181890897457, 5139.049275883974, 5151.793928242866, 5156.458571849939, 5157.7936882524655, 4571.451425828888, 4727.57451829337, 5024.874378140539, 5026.371703537058, 5076.873078173076, 12070.577529786415, 12129.09031822608, 12221.511139554495, 14418.711625767426, 20380.898095509427, 1871.9251229950785, 1905.7236700678463, 1936.2255097960788, 1948.4349538633778, 2042.3566576528494, 8661.090585488559, 8755.430896991675, 9209.861852072225, 9297.581608827619, 9345.112214339626, 1574.5906064423218, 1677.9832201677948, 1735.9652806943916, 1737.5048111288222, 1749.1604030065512, 66011.0, 68606.31393686049, 69329.3067069328, 71051.0, 72176.91734624037, 9017.81964360709, 9463.90536094644, 10150.847737283946, 10286.434246116385, 10310.226732995055, 12076.15466917325, 12951.222926624432, 12972.221666700028, 13285.335733213495, 13287.800682989762, 5706.563438986586, 7494.40044796415, 7590.316871481593, 7606.847863042708, 7711.0746710395115, 6289.276733175743, 6546.869062618816, 7313.341799238094, 7343.596102214323, 7453.664585093684, 3989.4414781930554, 4204.255234077885, 4221.261279276115, 4266.338717498793, 4289.0992891492515, 1828.4697437742905, 1898.9810101898804, 1903.6097599991967, 1904.6381187574316, 1905.1712505060343, 828.349745449819, 880.7269746378515, 897.3449381951212, 962.5043102802063, 977.4770497783652, 16416.83583164177, 17104.91447542779, 17202.913985429994, 17474.82525174732, 17591.38430154964, 849.5030407211726, 858.4763294390417, 895.9686410975585, 909.8225845959975, 910.977225569366, 9454.621815127319, 9529.332946693803, 9601.951990240144, 9701.563429645683, 9875.061869122423, 357615.0, 358645.0, 361157.0, 364636.0, 365099.0, 80477.59761201157, 80722.0, 83344.0, 83699.0, 84125.57937210274, 846.2172490446189, 855.8331125430482, 856.1780690537154, 856.8200677857595, 868.8653258744905, 14311.0, 15328.846711533188, 15582.9220853895, 15616.219189040276, 15957.760633590739, 37327.25345493129, 38046.0, 39120.02199945024, 39121.6087839118, 39368.40947385734, 47773.5222647783, 50096.248556270955, 51793.96412071813, 51982.74008629932, 52209.47790522046, 4501.032278060102, 6668.499862510234, 6875.346842049998, 6993.300669932966, 7274.617822613686, 8373.874391884005, 11608.24546404481, 11995.880041199476, 12052.216605920587, 12080.21478603888, 10406.687799366031, 10561.419121948367, 10648.307859989076, 10737.194710396596, 10928.289661171999, 9844.556994935101, 10368.118709909742, 10390.324628899098, 10474.109700675528, 10904.34573925567, 10892.836607450736, 10962.287451315959, 10979.39613321275, 11030.06244469197, 11395.886041139482, 7812.218778122142, 7910.8813367798375, 7931.960340198262, 7939.245771651684, 7997.640106195119, 8868.822623547621, 10480.790384192122, 10723.839142413026, 10766.46167691628, 10775.407352595368], "value": 8032.965515756624, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1379.7861331493618, 1403.5999740074078, 1481.3778213150476, 1668.8247733987923, 1674.9078800665966, 7281.0, 7756.340711039568, 7800.7269745558915, 7802.453828232026, 7812.960935195323, 6328.3671632836695, 6558.0, 7258.0, 7414.998975138355, 7457.216992215807, 2194.9341519754394, 2217.589745897006, 2225.4436390902274, 2228.799408053275, 2230.565039817236, 1846.298406605488, 2117.6717608770587, 2139.8930053497343, 2201.0975500044983, 2201.4276288165033, 2130.5419334843004, 2191.9452013699706, 2197.3627647982066, 2219.644856822909, 2224.699665545155, 2173.1741938063433, 2180.062573093577, 2190.616642087636, 2209.4034610655035, 2213.8893055347166, 2116.968245476319, 2134.6050980568643, 2138.4546940530067, 2139.3795799218365, 2146.2809958663865, 7651.5409075455655, 7792.376609871197, 7803.219678032208, 7803.531788092732, 7806.219378062206, 4469.597736203757, 4594.977025114887, 4598.356230127785, 4601.665517000066, 4629.537046295377, 2769.0862015534826, 2837.9290517737013, 2876.885489165462, 2888.714522037694, 2894.160693398912, 948.8861336639648, 964.0333719212797, 967.5839389062701, 970.6166064404521, 973.634886917406, 10322.845157322647, 10369.429681367521, 10397.324173928746, 10422.322549034365, 10429.895701042968, 4194.916101677949, 4281.807318670667, 4286.742795432284, 4359.14996575671, 4373.978130109329, 8884.0, 8894.199522042918, 8935.910640893508, 8947.731568052968, 8951.686690965915, 4632.050429661911, 4656.697314674535, 4656.743879086615, 4657.417822772153, 4661.067786442657, 4147.108371700099, 4273.7008409411665, 4275.850345237902, 4279.358096285582, 4280.293751530962, 3702.426123950792, 3703.4074548072717, 3753.8123093845743, 3783.2622638585212, 3819.52255968004, 1108.7505311304892, 1175.5121624525898, 1224.2776761710634, 1243.7885559454915, 1248.8189212564128, 814.9348052155814, 824.4435006370676, 828.2297463359014, 861.8448679237672, 880.212210071982, 5247.527722504993, 5321.946780532223, 5402.594805389533, 5406.486383793534, 5542.418046105182, 858.8582883824224, 869.3175856952262, 888.0276097673037, 889.8932128144535, 942.1473566422399, 3626.4197728363333, 3784.6215378461934, 3850.2492014057552, 3856.5564960029956, 3906.0820707133803, 9244.121808428188, 9438.292128090421, 9455.479948602893, 9487.857682134774, 9535.666251681158, 8093.514389136671, 8389.664413423518, 8394.706185283485, 9234.076592340713, 9399.57701903416, 7122.92877071233, 7309.013283206806, 7324.4872858900435, 7335.853282934311, 7354.705811767576, 5539.14143307788, 5577.2191893135805, 5586.888262234814, 5603.411641777718, 5689.2319536862, 8671.0, 8814.382993190415, 8839.69061082859, 8858.95570522169, 8859.202671759573, 8602.526861022703, 8774.517401542977, 9011.594478248626, 9061.77345566366, 9106.134917182857, 6340.207474065742, 6366.4588509977175, 6387.201599800025, 6408.935910640834, 6427.903581446376, 7384.48308618413, 7407.333339999426, 7457.105147382132, 7481.738139165103, 7484.513506622052, 6578.868422631424, 6637.535372523877, 6736.292689267556, 6793.93206067933, 6841.110655614738, 6534.771283005072, 6580.341965803382, 6611.305812889577, 6626.370494802986, 6707.061011458497, 6370.362963703593, 6420.1974753155855, 6426.614403135827, 6433.195850518685, 6438.67806609677, 4497.538300052562, 4553.180427522945, 4672.696274742131, 4706.788194531186, 4709.0817290628, 4524.434445694288, 4613.19269127908, 4650.255959046536, 4668.089722504079, 4761.666683332133], "value": 4138.555106530491, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.27953095619296, 298.2737035318997, 300.14309147384216, 307.28709394205424, 310.0203357390646, 4825.600575833008, 4848.733319667417, 4878.975605121969, 4883.316335713003, 4918.655694101414, 4529.524399938, 4657.720536767796, 4703.247480403136, 4833.0, 4834.540718631733, 3797.411401232813, 3799.7270914243763, 3831.3103641344546, 3832.6742226910746, 3841.4621952926473, 3849.2109117630957, 3849.2493963677093, 3875.4380614810834, 3880.165764360662, 3892.2020985698005, 3634.9273014539694, 3718.4236443351188, 3802.6577608015273, 3817.790021548814, 3838.865639702611, 3837.8870127662944, 3838.136419305662, 3846.423036544513, 3847.153626202217, 3856.8071596420345, 3417.2823707021557, 3418.4017796885446, 3425.383430982422, 3435.8282085895853, 3440.9655903440894, 4436.511983681792, 4771.188897887333, 4796.232602783538, 4800.423949126127, 4821.7589120543835, 4263.211305908386, 4324.372965919955, 4347.30443129101, 4363.563643635643, 4372.956270437318, 5027.2907211548245, 5032.572231360339, 5035.194368900995, 5062.898742025139, 5087.465816089331, 3547.5330687502246, 3619.674229319374, 3620.366435873738, 3628.0748409155494, 3638.490611314418, 5166.948330516684, 5262.842174721555, 5276.683398996072, 5291.3121294231505, 5294.920576191399, 3666.698322095671, 3680.9263814723554, 3681.9447708284397, 3682.410814269704, 3712.350338690718, 3730.925381492355, 3756.3050835595504, 3765.5293088363956, 3774.226283611853, 3777.8866634001024, 4111.568285329998, 4265.87202383929, 4310.396544483775, 4318.3090705486975, 4334.566543345703, 5022.668992716933, 5046.798128074909, 5055.69665820052, 5061.493850614909, 5065.214891691794, 1220.1519943639264, 1258.7167887225485, 1269.6635391621228, 1293.531841360063, 1302.9022823288292, 854.1458541458542, 873.4802792338548, 889.8175873945955, 890.7906641939139, 894.1684233662626, 4455.309427038814, 4456.9331460028125, 4459.019015816515, 4476.798544065525, 4480.775961201927, 5056.393232812086, 5084.41529224144, 5117.309163262912, 5144.768485418165, 5149.253358263031, 4453.866384008485, 4462.910741785211, 4467.664925130628, 4474.552544745565, 4480.9103817924115, 2296.8047715944, 2433.722295794722, 2447.2902858171287, 2455.226603619854, 2461.1878080233478, 4337.718048326912, 4358.847440339573, 4359.476862776487, 4372.846950356722, 4466.888327791831, 4654.557817007379, 4659.8136074557315, 4683.765811709401, 4685.5943217034755, 4731.1720448921305, 4369.0, 4594.862154135381, 4993.500649934905, 4998.600111991104, 5004.899902001866, 5220.347456567929, 5220.556252718561, 5229.529342359206, 5246.501582349671, 5268.209768534751, 5130.204818253177, 5196.792128314825, 5200.557952574073, 5224.399194092728, 5224.608154388512, 4776.808927642855, 4996.200607902718, 5019.623528235324, 5032.119379108714, 5032.949670503248, 3707.8146092695797, 3725.6646901778972, 3744.513213282256, 3749.156439801132, 3752.8498860045293, 5006.924896126634, 5289.947100528945, 5302.992431438014, 5304.480371823353, 5314.77760115168, 4693.131770622445, 5011.949880501148, 5074.619403544675, 5096.159133742891, 5102.79588816458, 5065.189569668835, 5089.312942752829, 5103.642745007814, 5106.4893510648635, 5106.66806657566, 5017.498250174954, 5068.619853511075, 5073.579397768631, 5076.137056700443, 5080.237964305384, 4897.314375987436, 5123.410807757006, 5128.743562821918, 5131.0, 5133.486651334986, 4988.079589358051, 5000.924986125138, 5010.624203184849, 5019.698818070781, 5082.3138876252715], "value": 3794.1202533497067, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.5241069198102, 727.78894120705, 733.7138515978769, 751.2074761127016, 756.7022556315917, 12543.811842822353, 13274.203547787127, 13297.0, 13356.866431335671, 14289.356978935946, 14573.854261457382, 15207.163606001666, 15208.923955380234, 15209.011414258093, 15214.391424343014, 621.0435928669843, 771.6913234706107, 773.5320131320555, 793.4525177627419, 795.8010497375656, 774.1368374262713, 780.5511830697343, 780.7696729464822, 781.2421950707804, 781.2968328504335, 806.3428305930677, 806.8345989072255, 811.7199566149699, 812.1715849833166, 812.25272749071, 651.9608823470606, 798.1260519730886, 806.2179685704857, 808.8261023879865, 809.1261437647356, 599.1462166412845, 599.8830228105521, 599.9610025348338, 600.051917969607, 600.2586805295457, 13285.468581256739, 13287.86712132876, 13325.600231993056, 13326.133801302884, 13364.465421383133, 11973.700657483545, 12411.875881241253, 12649.747005059939, 12800.0, 12984.026198035184, 11345.658274475034, 11469.713257168638, 11486.42567871603, 11530.13523985696, 11951.0, 4144.834206631731, 4163.2298024865195, 4218.34615634574, 4261.595148460891, 4305.892352691177, 58896.705516472575, 59536.0, 60006.69996649989, 60635.69682151561, 60704.696476517776, 10147.391156530632, 10304.845427318596, 10357.4303413313, 10399.32404393712, 10699.25105242641, 18171.72742408865, 18200.726989095176, 18208.180631871597, 18217.908910455364, 18268.451946441626, 6840.418564421981, 6903.896441553381, 6906.723731050803, 6913.481488888354, 7059.89410158848, 3667.4132138857653, 3792.4311353297226, 3796.7532110412735, 3814.809259537012, 3824.388097904322, 2650.3109191609933, 2747.917562473129, 2753.4630747004144, 2754.9724502754716, 2755.2698534888273, 492.2075458511793, 531.4340227657532, 555.5139253153491, 559.027292511036, 571.2516603249757, 496.46133944669714, 497.57457373944754, 560.4675558219708, 592.8547505861092, 644.1851058411114, 7942.0, 8268.83462330762, 8327.458715183493, 8395.790105247417, 8611.13888611134, 495.5587647628982, 557.8493806672176, 594.6016169166669, 604.2959951656359, 604.7792555717219, 3863.9227215456094, 4080.81636326366, 4120.711550191458, 4232.873013809591, 4303.462067241595, 88413.0, 92449.0, 93968.53015734878, 94967.0, 94983.52508237415, 81382.59308703418, 81646.59176704078, 81827.59086204531, 82028.0, 82702.0, 71189.0, 71778.0, 73606.6319668409, 76267.23732762602, 77961.0, 8640.308775298086, 8746.91253087461, 8765.211130998241, 8835.469871807712, 8991.14584114508, 31041.534376983913, 38899.29333033618, 40067.59932400638, 41510.92445377779, 44688.77655611701, 39956.0, 40954.0, 41529.169416610894, 47551.0, 64333.71332573416, 11404.0, 11526.82709759371, 11710.824337635113, 11799.882001179878, 11886.702832429259, 12727.363631818187, 12831.743365132832, 12983.221006739626, 13337.066405351532, 13735.0, 12122.75754484923, 12659.936700316808, 12716.427760750974, 12839.229646221256, 12867.485300588247, 11697.0, 12190.51237950472, 12230.510779568718, 12230.938845306073, 12829.807552886528, 11932.1050921183, 12029.338386388483, 12154.149209555246, 12420.068494863102, 12538.059645526804, 10070.69787906364, 10112.696619101438, 10143.391396516232, 10499.370037797757, 10742.086922611354, 9780.706578802647, 9874.21006319507, 9892.307538472238, 10016.248781341283, 10124.190064794946], "value": 6613.305374584218, "unit": "score"}], "name": "dromaeo_css", "value": 6448.4329334619515, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 113.0, 114.0, 114.0, 113.0, 113.0, 114.0, 115.0, 115.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 125.0, 124.0, 179.0, 123.0, 124.0, 144.0, 123.0, 153.0, 121.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 162.0, 161.0, 160.0, 162.0, 162.0, 162.0, 161.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 118.0, 78.0, 78.0, 76.0, 76.0, 100.0, 75.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 120.0, 84.0, 85.0, 84.0, 113.0, 84.0, 85.0, 132.0], "value": 95.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 118.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 64.0, 62.0, 63.0, 64.0, 63.0, 63.0, 64.0, 64.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0, 66.0, 67.0], "value": 65.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 78.0, 78.0, 78.0, 78.0, 80.0, 81.0, 78.0, 81.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 128.0, 141.0, 128.0, 129.0, 138.0, 139.0, 128.0, 178.0, 128.0], "value": 137.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 162.0, 165.0, 164.0, 161.0, 162.0, 164.0, 161.0, 162.0], "value": 162.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 66.0, 65.0, 65.0, 66.0, 67.0, 66.0, 65.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1521.3, "alertThreshold": 2.0}]} 02:50:54 INFO - Return code: 0 02:50:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:50:54 INFO - # TBPL SUCCESS # 02:50:54 INFO - Running post-action listener: _resource_record_post_action 02:50:54 INFO - ##### 02:50:54 INFO - ##### Finished run-tests step (success) 02:50:54 INFO - ##### 02:50:54 INFO - Running post-run listener: _resource_record_post_run 02:50:54 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 8192; Write bytes: 548139008; Read time: 20; Write time: 884740 02:50:54 INFO - TinderboxPrint: CPU usage
13.0% 02:50:54 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 20 02:50:54 INFO - TinderboxPrint: I/O write bytes / time
548,139,008 / 884,740 02:50:54 INFO - TinderboxPrint: CPU idle
8,352.0 (87.0%) 02:50:54 INFO - TinderboxPrint: CPU user
1,202.5 (12.5%) 02:50:54 INFO - TinderboxPrint: Swap in / out
0 / 0 02:50:54 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 246738944; Read time: 0; Write time: 405708 02:50:54 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288800768; Read time: 8; Write time: 476700 02:50:55 INFO - Running post-run listener: _upload_blobber_files 02:50:55 INFO - Blob upload gear active. 02:50:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:50:55 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 02:50:55 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'] 02:50:55 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 02:50:55 INFO - (blobuploader) - INFO - Open directory for files ... 02:50:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:50:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:50:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:56 INFO - (blobuploader) - INFO - Done attempting. 02:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:50:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:50:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:50:58 INFO - (blobuploader) - INFO - Done attempting. 02:50:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:50:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:50:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:51:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:51:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:51:01 INFO - (blobuploader) - INFO - Done attempting. 02:51:01 INFO - (blobuploader) - INFO - Iteration through files over. 02:51:01 INFO - Return code: 0 02:51:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:51:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:51:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/161c7e066b525c7b486c35559bd886e81d4ea9717ebcfdf6585b03afc8fac9e6162a385c556ee97d13446a63e8533f30955f2377707bc5fd5cdc8f27094c60ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3f4fd9fb6f1ed47ec60d8a9c00bf14a332cac00c8574b44f5d25dbf6ce4818e7c79b1039669f2e68b7d526a8c8994ae3561299c37c7cde4661279b6d8665d3d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb0d0f4ded3d0d7e514fb5cbc8e165b4ee65dc2ab8c2e4bfdb2a064b559244c32cfa018551e4f9e545001ecd611656c56180f5ea450d495fd54ad97fc0719215"} 02:51:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:51:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:51:01 INFO - Contents: 02:51:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/161c7e066b525c7b486c35559bd886e81d4ea9717ebcfdf6585b03afc8fac9e6162a385c556ee97d13446a63e8533f30955f2377707bc5fd5cdc8f27094c60ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3f4fd9fb6f1ed47ec60d8a9c00bf14a332cac00c8574b44f5d25dbf6ce4818e7c79b1039669f2e68b7d526a8c8994ae3561299c37c7cde4661279b6d8665d3d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb0d0f4ded3d0d7e514fb5cbc8e165b4ee65dc2ab8c2e4bfdb2a064b559244c32cfa018551e4f9e545001ecd611656c56180f5ea450d495fd54ad97fc0719215"} 02:51:01 INFO - Running post-run listener: copy_logs_to_upload_dir 02:51:01 INFO - Copying logs to upload dir... 02:51:01 INFO - mkdir: /builds/slave/test/build/upload/logs 02:51:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1248.547836 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 48 secs) (at 2016-07-19 02:51:01.843497) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 02:51:01.848328) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882 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/161c7e066b525c7b486c35559bd886e81d4ea9717ebcfdf6585b03afc8fac9e6162a385c556ee97d13446a63e8533f30955f2377707bc5fd5cdc8f27094c60ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3f4fd9fb6f1ed47ec60d8a9c00bf14a332cac00c8574b44f5d25dbf6ce4818e7c79b1039669f2e68b7d526a8c8994ae3561299c37c7cde4661279b6d8665d3d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb0d0f4ded3d0d7e514fb5cbc8e165b4ee65dc2ab8c2e4bfdb2a064b559244c32cfa018551e4f9e545001ecd611656c56180f5ea450d495fd54ad97fc0719215"} build_url:https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/XoZOhrgQS8eQOaNBdYH9Hw/artifacts/public/build/firefox-50.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/161c7e066b525c7b486c35559bd886e81d4ea9717ebcfdf6585b03afc8fac9e6162a385c556ee97d13446a63e8533f30955f2377707bc5fd5cdc8f27094c60ab", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/3f4fd9fb6f1ed47ec60d8a9c00bf14a332cac00c8574b44f5d25dbf6ce4818e7c79b1039669f2e68b7d526a8c8994ae3561299c37c7cde4661279b6d8665d3d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/eb0d0f4ded3d0d7e514fb5cbc8e165b4ee65dc2ab8c2e4bfdb2a064b559244c32cfa018551e4f9e545001ecd611656c56180f5ea450d495fd54ad97fc0719215"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-19 02:51:01.876113) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 02:51:01.876436) ========= 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=5cf47e208febbcd81da23b2e0000026f-1468920412.430759-150921882 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004495 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-19 02:51:01.956178) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 02:51:01.956517) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-19 02:51:01.956831) ========= ========= Total master_lag: 0.18 =========