builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-032 starttime: 1464390168.95 results: success (0) buildid: 20160527144251 builduid: d440f4d99614438c8efc54b121ae006a revision: ea15028498ed95677844fb7f30be5efcaf8b2621 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:48.953299) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:48.955570) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:48.955931) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:48.971978) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:48.972585) ========= 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=745a4373a2a16df560f216e000000264-1464389792.607349-1469284873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-27 16:02:49-- 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% 47.1M=0s 2016-05-27 16:02:49 (47.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116758 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.109390) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.110006) ========= 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=745a4373a2a16df560f216e000000264-1464389792.607349-1469284873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017436 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.169030) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.169725) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ea15028498ed95677844fb7f30be5efcaf8b2621 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ea15028498ed95677844fb7f30be5efcaf8b2621 --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=745a4373a2a16df560f216e000000264-1464389792.607349-1469284873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-27 16:02:49,370 truncating revision to first 12 chars 2016-05-27 16:02:49,370 Setting DEBUG logging. 2016-05-27 16:02:49,370 attempt 1/10 2016-05-27 16:02:49,370 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ea15028498ed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 16:02:49,684 unpacking tar archive at: mozilla-central-ea15028498ed/testing/mozharness/ program finished with exit code 0 elapsedTime=0.684841 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.869585) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.870085) ========= script_repo_revision: ea15028498ed95677844fb7f30be5efcaf8b2621 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.870715) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.871124) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 16:02:49.881847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-05-27 16:02:49.882227) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=745a4373a2a16df560f216e000000264-1464389792.607349-1469284873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:02:50 INFO - MultiFileLogger online at 20160527 16:02:50 in /builds/slave/test 16:02:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 16:02:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:02:50 INFO - {'append_to_log': False, 16:02:50 INFO - 'base_work_dir': '/builds/slave/test', 16:02:50 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:02:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:02:50 INFO - 'branch': 'Firefox-Non-PGO', 16:02:50 INFO - 'buildbot_json_path': 'buildprops.json', 16:02:50 INFO - 'config_files': ('talos/linux_config.py',), 16:02:50 INFO - 'default_actions': ('clobber', 16:02:50 INFO - 'read-buildbot-config', 16:02:50 INFO - 'download-and-extract', 16:02:50 INFO - 'populate-webroot', 16:02:50 INFO - 'create-virtualenv', 16:02:50 INFO - 'install', 16:02:50 INFO - 'run-tests'), 16:02:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:02:50 INFO - 'download_minidump_stackwalk': True, 16:02:50 INFO - 'download_symbols': 'ondemand', 16:02:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:02:50 INFO - 'tooltool.py': '/tools/tooltool.py', 16:02:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:02:50 INFO - '/tools/misc-python/virtualenv.py')}, 16:02:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:02:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:02:50 INFO - 'installer_path': 'installer.exe', 16:02:50 INFO - 'log_level': 'info', 16:02:50 INFO - 'log_name': 'talos', 16:02:50 INFO - 'log_to_console': True, 16:02:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:02:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:02:50 INFO - 'opt_config_files': (), 16:02:50 INFO - 'pip_index': False, 16:02:50 INFO - 'sps_profile': False, 16:02:50 INFO - 'sps_profile_interval': 0, 16:02:50 INFO - 'suite': 'dromaeojs', 16:02:50 INFO - 'system_bits': '32', 16:02:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:02:50 INFO - 'title': 'talos-linux64-ix-032', 16:02:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:02:50 INFO - 'use_talos_json': True, 16:02:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:02:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:02:50 INFO - 'work_dir': 'build'} 16:02:50 INFO - ##### 16:02:50 INFO - ##### Running clobber step. 16:02:50 INFO - ##### 16:02:50 INFO - Running pre-action listener: _resource_record_pre_action 16:02:50 INFO - Running main action method: clobber 16:02:50 INFO - rmtree: /builds/slave/test/build 16:02:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:02:51 INFO - Running post-action listener: _resource_record_post_action 16:02:51 INFO - ##### 16:02:51 INFO - ##### Finished clobber step (success) 16:02:51 INFO - ##### 16:02:51 INFO - ##### 16:02:51 INFO - ##### Running read-buildbot-config step. 16:02:51 INFO - ##### 16:02:51 INFO - Running pre-action listener: _resource_record_pre_action 16:02:51 INFO - Running main action method: read_buildbot_config 16:02:51 INFO - Using buildbot properties: 16:02:51 INFO - { 16:02:51 INFO - "project": "", 16:02:51 INFO - "product": "firefox", 16:02:51 INFO - "script_repo_revision": "production", 16:02:51 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:02:51 INFO - "repository": "", 16:02:51 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:02:51 INFO - "buildid": "20160527144251", 16:02:51 INFO - "pgo_build": "False", 16:02:51 INFO - "basedir": "/builds/slave/test", 16:02:51 INFO - "buildnumber": 599, 16:02:51 INFO - "slavename": "talos-linux64-ix-032", 16:02:51 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:02:51 INFO - "platform": "ubuntu64_hw", 16:02:51 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:02:51 INFO - "branch": "mozilla-central", 16:02:51 INFO - "repo_path": "mozilla-central", 16:02:51 INFO - "revision": "ea15028498ed95677844fb7f30be5efcaf8b2621", 16:02:51 INFO - "stage_platform": "linux64", 16:02:51 INFO - "builduid": "d440f4d99614438c8efc54b121ae006a", 16:02:51 INFO - "slavebuilddir": "test" 16:02:51 INFO - } 16:02:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 16:02:51 INFO - Running post-action listener: _resource_record_post_action 16:02:51 INFO - ##### 16:02:51 INFO - ##### Finished read-buildbot-config step (success) 16:02:51 INFO - ##### 16:02:51 INFO - ##### 16:02:51 INFO - ##### Running download-and-extract step. 16:02:51 INFO - ##### 16:02:51 INFO - Running pre-action listener: _resource_record_pre_action 16:02:51 INFO - Running main action method: download_and_extract 16:02:51 INFO - mkdir: /builds/slave/test/build/tests 16:02: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')]} 16:02:51 INFO - https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:02:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:02:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:02:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:02:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:02:53 INFO - Downloaded 1361 bytes. 16:02:53 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 16:02:53 INFO - Using the following test package requirements: 16:02:53 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:02:53 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:02:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:02:53 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:02:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:02:53 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:02:53 INFO - u'jsshell-linux-x86_64.zip'], 16:02:53 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:02:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:02:53 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 16:02:53 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:02:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:02:53 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:02:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:02:53 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:02:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:02:53 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 16:02:53 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:02:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:02:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:02:53 INFO - https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:02:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:02:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:02:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:02:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:02:55 INFO - Downloaded 22837929 bytes. 16:02:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:02:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:02:56 INFO - Return code: 0 16:02:56 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:02:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:02:56 INFO - https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:02:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:02:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:02:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 16:02:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:02:57 INFO - Downloaded 22837929 bytes. 16:02:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:02:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:02:58 INFO - Return code: 0 16:02:58 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')]} 16:02:58 INFO - https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:02:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:02:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:02:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 16:02:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:02:59 INFO - Downloaded 10834034 bytes. 16:02:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:02:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:03:00 INFO - Return code: 0 16:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:00 INFO - https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:03:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:03:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:03:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:03:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:03:02 INFO - Downloaded 54831234 bytes. 16:03:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:03:02 INFO - mkdir: /builds/slave/test/properties 16:03:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:03:02 INFO - Writing to file /builds/slave/test/properties/build_url 16:03:02 INFO - Contents: 16:03:02 INFO - build_url:https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 16:03:03 INFO - Running post-action listener: _resource_record_post_action 16:03:03 INFO - Running post-action listener: set_extra_try_arguments 16:03:03 INFO - ##### 16:03:03 INFO - ##### Finished download-and-extract step (success) 16:03:03 INFO - ##### 16:03:03 INFO - ##### 16:03:03 INFO - ##### Running populate-webroot step. 16:03:03 INFO - ##### 16:03:03 INFO - Running pre-action listener: _resource_record_pre_action 16:03:03 INFO - Running main action method: populate_webroot 16:03:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:03:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:03:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:03:03 INFO - '--tppagecycles', 16:03:03 INFO - '7'], 16:03:03 INFO - 'tests': ['tsvgm']}}, 16:03:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:03:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:03:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:03:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:03:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:03:03 INFO - 'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:03:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:03:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:03:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:03:03 INFO - 'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['damp', 'tps']}, 16:03:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:03:03 INFO - 'tests': ['damp', 'tps']}, 16:03:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['dromaeo_dom']}, 16:03:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:03:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['a11yr', 16:03:03 INFO - 'ts_paint', 16:03:03 INFO - 'tpaint', 16:03:03 INFO - 'sessionrestore', 16:03:03 INFO - 'sessionrestore_no_auto_restore', 16:03:03 INFO - 'tabpaint']}, 16:03:03 INFO - 'other-e10s': {'tests': ['a11yr', 16:03:03 INFO - 'ts_paint', 16:03:03 INFO - 'tpaint', 16:03:03 INFO - 'sessionrestore', 16:03:03 INFO - 'sessionrestore_no_auto_restore', 16:03:03 INFO - 'tabpaint']}, 16:03:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:03:03 INFO - 'ts_paint', 16:03:03 INFO - 'tpaint', 16:03:03 INFO - 'sessionrestore', 16:03:03 INFO - 'sessionrestore_no_auto_restore', 16:03:03 INFO - 'tabpaint']}, 16:03:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:03:03 INFO - 'ts_paint', 16:03:03 INFO - 'tpaint', 16:03:03 INFO - 'sessionrestore', 16:03:03 INFO - 'sessionrestore_no_auto_restore', 16:03:03 INFO - 'tabpaint']}, 16:03:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['a11yr', 16:03:03 INFO - 'ts_paint', 16:03:03 INFO - 'tpaint', 16:03:03 INFO - 'sessionrestore', 16:03:03 INFO - 'sessionrestore_no_auto_restore', 16:03:03 INFO - 'tabpaint']}, 16:03:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['a11yr', 16:03:03 INFO - 'ts_paint', 16:03:03 INFO - 'tpaint', 16:03:03 INFO - 'sessionrestore', 16:03:03 INFO - 'sessionrestore_no_auto_restore', 16:03:03 INFO - 'tabpaint']}, 16:03:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['tsvgx', 16:03:03 INFO - 'tsvgr_opacity', 16:03:03 INFO - 'tart', 16:03:03 INFO - 'tscrollx', 16:03:03 INFO - 'cart']}, 16:03:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:03:03 INFO - 'tsvgr_opacity', 16:03:03 INFO - 'tart', 16:03:03 INFO - 'tscrollx', 16:03:03 INFO - 'cart']}, 16:03:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:03:03 INFO - 'talos_options': ['--disable-e10s'], 16:03:03 INFO - 'tests': ['tp5o']}, 16:03:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:03:03 INFO - 'tests': ['tp5o']}, 16:03:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:03:03 INFO - 'talos_options': ['--disable-e10s', 16:03:03 INFO - '--xperf_path', 16:03:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:03:03 INFO - 'tests': ['tp5n']}, 16:03:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:03:03 INFO - 'talos_options': ['--xperf_path', 16:03:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:03:03 INFO - 'tests': ['tp5n']}}, 16:03:03 INFO - 'talos.zip': {'path': '', 16:03:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:03:03 INFO - Running post-action listener: _resource_record_post_action 16:03:03 INFO - ##### 16:03:03 INFO - ##### Finished populate-webroot step (success) 16:03:03 INFO - ##### 16:03:03 INFO - ##### 16:03:03 INFO - ##### Running create-virtualenv step. 16:03:03 INFO - ##### 16:03:03 INFO - Running pre-action listener: _resource_record_pre_action 16:03:03 INFO - Running main action method: create_virtualenv 16:03:03 INFO - Creating virtualenv /builds/slave/test/build/venv 16:03:03 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 16:03:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:03:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:03:03 INFO - Using real prefix '/usr' 16:03:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:03:03 INFO - Installing distribute.............................................................................................................................................................................................done. 16:03:05 INFO - Installing pip.................done. 16:03:05 INFO - Return code: 0 16:03:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:03:05 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')]} 16:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:05 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')]} 16:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:05 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 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:05 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 16:03:05 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 16:03:05 INFO - Using env: {'DISPLAY': ':0', 16:03:05 INFO - 'HOME': '/home/cltbld', 16:03:05 INFO - 'LANG': 'en_US.UTF-8', 16:03:05 INFO - 'LANGUAGE': 'en_US:en', 16:03:05 INFO - 'LOGNAME': 'cltbld', 16:03:05 INFO - 'MAIL': '/var/mail/cltbld', 16:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:05 INFO - 'MOZ_NO_REMOTE': '1', 16:03:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:05 INFO - 'NO_EM_RESTART': '1', 16:03:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:05 INFO - 'PWD': '/builds/slave/test', 16:03:05 INFO - 'SHELL': '/bin/bash', 16:03:05 INFO - 'SHLVL': '1', 16:03:05 INFO - 'TERM': 'linux', 16:03:05 INFO - 'TMOUT': '86400', 16:03:05 INFO - 'USER': 'cltbld', 16:03:05 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:05 INFO - '_': '/tools/buildbot/bin/python'} 16:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:06 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 16:03:06 INFO - Cleaning up... 16:03:06 INFO - Return code: 0 16:03:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:06 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 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:06 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 16:03:06 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 16:03:06 INFO - Using env: {'DISPLAY': ':0', 16:03:06 INFO - 'HOME': '/home/cltbld', 16:03:06 INFO - 'LANG': 'en_US.UTF-8', 16:03:06 INFO - 'LANGUAGE': 'en_US:en', 16:03:06 INFO - 'LOGNAME': 'cltbld', 16:03:06 INFO - 'MAIL': '/var/mail/cltbld', 16:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:06 INFO - 'MOZ_NO_REMOTE': '1', 16:03:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:06 INFO - 'NO_EM_RESTART': '1', 16:03:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:06 INFO - 'PWD': '/builds/slave/test', 16:03:06 INFO - 'SHELL': '/bin/bash', 16:03:06 INFO - 'SHLVL': '1', 16:03:06 INFO - 'TERM': 'linux', 16:03:06 INFO - 'TMOUT': '86400', 16:03:06 INFO - 'USER': 'cltbld', 16:03:06 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:06 INFO - '_': '/tools/buildbot/bin/python'} 16:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:06 INFO - Downloading/unpacking psutil>=3.1.1 16:03:06 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 16:03:06 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 16:03:06 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 16:03:06 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 16:03:06 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 16:03:06 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 16:03:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:03:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:03:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:03:08 INFO - Installing collected packages: psutil 16:03:08 INFO - Running setup.py install for psutil 16:03:08 INFO - building 'psutil._psutil_linux' extension 16:03:08 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 16:03:08 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 16:03:08 INFO - building 'psutil._psutil_posix' extension 16:03:08 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 16:03:08 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 16:03:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:03:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:03:08 INFO - Successfully installed psutil 16:03:08 INFO - Cleaning up... 16:03:08 INFO - Return code: 0 16:03:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:03:08 INFO - Using env: {'DISPLAY': ':0', 16:03:08 INFO - 'HOME': '/home/cltbld', 16:03:08 INFO - 'LANG': 'en_US.UTF-8', 16:03:08 INFO - 'LANGUAGE': 'en_US:en', 16:03:08 INFO - 'LOGNAME': 'cltbld', 16:03:08 INFO - 'MAIL': '/var/mail/cltbld', 16:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:08 INFO - 'MOZ_NO_REMOTE': '1', 16:03:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:08 INFO - 'NO_EM_RESTART': '1', 16:03:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:08 INFO - 'PWD': '/builds/slave/test', 16:03:08 INFO - 'SHELL': '/bin/bash', 16:03:08 INFO - 'SHLVL': '1', 16:03:08 INFO - 'TERM': 'linux', 16:03:08 INFO - 'TMOUT': '86400', 16:03:08 INFO - 'USER': 'cltbld', 16:03:08 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:08 INFO - '_': '/tools/buildbot/bin/python'} 16:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:03:09 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 16:03:09 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 16:03:09 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 16:03:09 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 16:03:09 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 16:03:09 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 16:03:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:03:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:03:10 INFO - Installing collected packages: mozsystemmonitor 16:03:10 INFO - Running setup.py install for mozsystemmonitor 16:03:11 INFO - Successfully installed mozsystemmonitor 16:03:11 INFO - Cleaning up... 16:03:11 INFO - Return code: 0 16:03:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:03:11 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')]} 16:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:11 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')]} 16:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:11 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 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:11 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 16:03:11 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 16:03:11 INFO - Using env: {'DISPLAY': ':0', 16:03:11 INFO - 'HOME': '/home/cltbld', 16:03:11 INFO - 'LANG': 'en_US.UTF-8', 16:03:11 INFO - 'LANGUAGE': 'en_US:en', 16:03:11 INFO - 'LOGNAME': 'cltbld', 16:03:11 INFO - 'MAIL': '/var/mail/cltbld', 16:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:11 INFO - 'MOZ_NO_REMOTE': '1', 16:03:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:11 INFO - 'NO_EM_RESTART': '1', 16:03:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:11 INFO - 'PWD': '/builds/slave/test', 16:03:11 INFO - 'SHELL': '/bin/bash', 16:03:11 INFO - 'SHLVL': '1', 16:03:11 INFO - 'TERM': 'linux', 16:03:11 INFO - 'TMOUT': '86400', 16:03:11 INFO - 'USER': 'cltbld', 16:03:11 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:11 INFO - '_': '/tools/buildbot/bin/python'} 16:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:11 INFO - Downloading/unpacking blobuploader==1.2.4 16:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:03:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:13 INFO - Downloading blobuploader-1.2.4.tar.gz 16:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:03:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:03:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:13 INFO - Downloading docopt-0.6.1.tar.gz 16:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:03:13 INFO - Installing collected packages: blobuploader, requests, docopt 16:03:13 INFO - Running setup.py install for blobuploader 16:03:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:03:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:03:14 INFO - Running setup.py install for requests 16:03:14 INFO - Running setup.py install for docopt 16:03:14 INFO - Successfully installed blobuploader requests docopt 16:03:14 INFO - Cleaning up... 16:03:14 INFO - Return code: 0 16:03:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03: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')]} 16:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03: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')]} 16:03:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:14 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 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:14 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 16:03:14 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 16:03:14 INFO - Using env: {'DISPLAY': ':0', 16:03:14 INFO - 'HOME': '/home/cltbld', 16:03:14 INFO - 'LANG': 'en_US.UTF-8', 16:03:14 INFO - 'LANGUAGE': 'en_US:en', 16:03:14 INFO - 'LOGNAME': 'cltbld', 16:03:14 INFO - 'MAIL': '/var/mail/cltbld', 16:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:14 INFO - 'MOZ_NO_REMOTE': '1', 16:03:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:14 INFO - 'NO_EM_RESTART': '1', 16:03:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:14 INFO - 'PWD': '/builds/slave/test', 16:03:14 INFO - 'SHELL': '/bin/bash', 16:03:14 INFO - 'SHLVL': '1', 16:03:14 INFO - 'TERM': 'linux', 16:03:14 INFO - 'TMOUT': '86400', 16:03:14 INFO - 'USER': 'cltbld', 16:03:14 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:14 INFO - '_': '/tools/buildbot/bin/python'} 16:03:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:03:14 INFO - Running setup.py (path:/tmp/pip-dPYz5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:03:14 INFO - Running setup.py (path:/tmp/pip-EBtgAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:03:14 INFO - Running setup.py (path:/tmp/pip-oTZV5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:03:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:03:14 INFO - Running setup.py (path:/tmp/pip-foU2F7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:03:15 INFO - Running setup.py (path:/tmp/pip-tbqp_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:03:15 INFO - Running setup.py (path:/tmp/pip-R2_6Ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:03:15 INFO - Running setup.py (path:/tmp/pip-9nplhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:03:15 INFO - Running setup.py (path:/tmp/pip-xzVpXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:03:15 INFO - Running setup.py (path:/tmp/pip-uAu_W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:03:15 INFO - Running setup.py (path:/tmp/pip-KhtuHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:03:15 INFO - Running setup.py (path:/tmp/pip-FfdiVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:03:15 INFO - Running setup.py (path:/tmp/pip-L47sMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:03:15 INFO - Running setup.py (path:/tmp/pip-xL3bUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:03:15 INFO - Running setup.py (path:/tmp/pip-qiVPqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:15 INFO - Running setup.py (path:/tmp/pip-D8aZoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:03:15 INFO - Running setup.py (path:/tmp/pip-GWQ8Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:03:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:03:15 INFO - Running setup.py (path:/tmp/pip-zutxLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:03:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:03:15 INFO - Running setup.py install for manifestparser 16:03:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Running setup.py install for mozcrash 16:03:16 INFO - Running setup.py install for mozdebug 16:03:16 INFO - Running setup.py install for mozdevice 16:03:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Running setup.py install for mozfile 16:03:16 INFO - Running setup.py install for mozhttpd 16:03:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Running setup.py install for mozinfo 16:03:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Running setup.py install for mozInstall 16:03:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:03:16 INFO - Running setup.py install for mozleak 16:03:16 INFO - Running setup.py install for mozlog 16:03:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:03:17 INFO - Running setup.py install for moznetwork 16:03:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:03:17 INFO - Running setup.py install for mozprocess 16:03:17 INFO - Running setup.py install for mozprofile 16:03:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:03:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:03:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:03:17 INFO - Running setup.py install for mozrunner 16:03:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:03:17 INFO - Running setup.py install for mozscreenshot 16:03:17 INFO - Running setup.py install for moztest 16:03:17 INFO - Running setup.py install for mozversion 16:03:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:03:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:03:17 INFO - Cleaning up... 16:03:17 INFO - Return code: 0 16:03:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:17 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 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:17 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 16:03:17 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 16:03:17 INFO - Using env: {'DISPLAY': ':0', 16:03:17 INFO - 'HOME': '/home/cltbld', 16:03:17 INFO - 'LANG': 'en_US.UTF-8', 16:03:17 INFO - 'LANGUAGE': 'en_US:en', 16:03:17 INFO - 'LOGNAME': 'cltbld', 16:03:17 INFO - 'MAIL': '/var/mail/cltbld', 16:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:17 INFO - 'MOZ_NO_REMOTE': '1', 16:03:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:17 INFO - 'NO_EM_RESTART': '1', 16:03:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:17 INFO - 'PWD': '/builds/slave/test', 16:03:17 INFO - 'SHELL': '/bin/bash', 16:03:17 INFO - 'SHLVL': '1', 16:03:17 INFO - 'TERM': 'linux', 16:03:17 INFO - 'TMOUT': '86400', 16:03:17 INFO - 'USER': 'cltbld', 16:03:17 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:17 INFO - '_': '/tools/buildbot/bin/python'} 16:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:03:17 INFO - Running setup.py (path:/tmp/pip-1mJTjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:03:18 INFO - Running setup.py (path:/tmp/pip-bIHToU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:03:18 INFO - Running setup.py (path:/tmp/pip-kSXH4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:03:18 INFO - Running setup.py (path:/tmp/pip-bZosug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:03:18 INFO - Running setup.py (path:/tmp/pip-FHSF3Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:03:18 INFO - Running setup.py (path:/tmp/pip-70ADy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:03:18 INFO - Running setup.py (path:/tmp/pip-ky8g5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:03:18 INFO - Running setup.py (path:/tmp/pip-lW8_79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:03:18 INFO - Running setup.py (path:/tmp/pip-7U4lR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:03:18 INFO - Running setup.py (path:/tmp/pip-Mkrlxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:03:18 INFO - Running setup.py (path:/tmp/pip-2m8YPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:03:18 INFO - Running setup.py (path:/tmp/pip-BBPfvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:03:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:03:18 INFO - Running setup.py (path:/tmp/pip-xnGxfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:03:18 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)) 16:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:03:18 INFO - Running setup.py (path:/tmp/pip-dZBVWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:03:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 16:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:19 INFO - Running setup.py (path:/tmp/pip-8aEjp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:03:19 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)) 16:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:03:19 INFO - Running setup.py (path:/tmp/pip-zhATFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:03:19 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)) 16:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:03:19 INFO - Running setup.py (path:/tmp/pip-L2oMAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:03:19 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)) 16:03:19 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)) 16:03:19 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)) 16:03:19 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)) 16:03:19 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)) 16:03:19 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)) 16:03:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:03:19 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 16:03:19 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 16:03:19 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 16:03:19 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 16:03:19 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 16:03:19 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 16:03:21 INFO - Downloading blessings-1.6.tar.gz 16:03:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:03:21 INFO - Installing collected packages: blessings 16:03:21 INFO - Running setup.py install for blessings 16:03:21 INFO - Successfully installed blessings 16:03:21 INFO - Cleaning up... 16:03:21 INFO - Return code: 0 16:03:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:03:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:03:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:03:21 INFO - Reading from file tmpfile_stdout 16:03:21 INFO - Current package versions: 16:03:21 INFO - argparse == 1.2.1 16:03:21 INFO - blessings == 1.6 16:03:21 INFO - blobuploader == 1.2.4 16:03:21 INFO - docopt == 0.6.1 16:03:21 INFO - manifestparser == 1.1 16:03:21 INFO - mozInstall == 1.12 16:03:21 INFO - mozcrash == 0.17 16:03:21 INFO - mozdebug == 0.1 16:03:21 INFO - mozdevice == 0.48 16:03:21 INFO - mozfile == 1.2 16:03:21 INFO - mozhttpd == 0.7 16:03:21 INFO - mozinfo == 0.9 16:03:21 INFO - mozleak == 0.1 16:03:21 INFO - mozlog == 3.2 16:03:21 INFO - moznetwork == 0.27 16:03:21 INFO - mozprocess == 0.22 16:03:21 INFO - mozprofile == 0.28 16:03:21 INFO - mozrunner == 6.11 16:03:21 INFO - mozscreenshot == 0.1 16:03:21 INFO - mozsystemmonitor == 0.3 16:03:21 INFO - moztest == 0.7 16:03:21 INFO - mozversion == 1.4 16:03:21 INFO - psutil == 3.1.1 16:03:21 INFO - requests == 1.2.3 16:03:21 INFO - wsgiref == 0.1.2 16:03:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:21 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 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:21 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 16:03:21 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 16:03:21 INFO - Using env: {'DISPLAY': ':0', 16:03:21 INFO - 'HOME': '/home/cltbld', 16:03:21 INFO - 'LANG': 'en_US.UTF-8', 16:03:21 INFO - 'LANGUAGE': 'en_US:en', 16:03:21 INFO - 'LOGNAME': 'cltbld', 16:03:21 INFO - 'MAIL': '/var/mail/cltbld', 16:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:21 INFO - 'MOZ_NO_REMOTE': '1', 16:03:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:21 INFO - 'NO_EM_RESTART': '1', 16:03:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:21 INFO - 'PWD': '/builds/slave/test', 16:03:21 INFO - 'SHELL': '/bin/bash', 16:03:21 INFO - 'SHLVL': '1', 16:03:21 INFO - 'TERM': 'linux', 16:03:21 INFO - 'TMOUT': '86400', 16:03:21 INFO - 'USER': 'cltbld', 16:03:21 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:21 INFO - '_': '/tools/buildbot/bin/python'} 16:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 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)) 16:03:21 INFO - Cleaning up... 16:03:21 INFO - Return code: 0 16:03:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:21 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 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:21 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 16:03:21 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 16:03:21 INFO - Using env: {'DISPLAY': ':0', 16:03:21 INFO - 'HOME': '/home/cltbld', 16:03:21 INFO - 'LANG': 'en_US.UTF-8', 16:03:21 INFO - 'LANGUAGE': 'en_US:en', 16:03:21 INFO - 'LOGNAME': 'cltbld', 16:03:21 INFO - 'MAIL': '/var/mail/cltbld', 16:03:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:21 INFO - 'MOZ_NO_REMOTE': '1', 16:03:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:21 INFO - 'NO_EM_RESTART': '1', 16:03:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:21 INFO - 'PWD': '/builds/slave/test', 16:03:21 INFO - 'SHELL': '/bin/bash', 16:03:21 INFO - 'SHLVL': '1', 16:03:21 INFO - 'TERM': 'linux', 16:03:21 INFO - 'TMOUT': '86400', 16:03:21 INFO - 'USER': 'cltbld', 16:03:21 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:21 INFO - '_': '/tools/buildbot/bin/python'} 16:03:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:21 INFO - Downloading/unpacking jsonschema 16:03:21 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 16:03:21 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 16:03:21 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 16:03:21 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 16:03:21 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 16:03:21 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 16:03:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:03:23 INFO - Downloading/unpacking functools32 (from jsonschema) 16:03:23 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 16:03:23 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 16:03:23 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 16:03:23 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 16:03:23 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 16:03:23 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 16:03:24 INFO - Downloading functools32-3.2.3-2.tar.gz 16:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:03:24 INFO - warning: no files found matching '*.txt' 16:03:24 INFO - no previously-included directories found matching 'build' 16:03:24 INFO - no previously-included directories found matching 'dist' 16:03:24 INFO - no previously-included directories found matching '.git*' 16:03:24 INFO - Installing collected packages: jsonschema, functools32 16:03:24 INFO - Running setup.py install for functools32 16:03:24 INFO - warning: no files found matching '*.txt' 16:03:24 INFO - no previously-included directories found matching 'build' 16:03:24 INFO - no previously-included directories found matching 'dist' 16:03:24 INFO - no previously-included directories found matching '.git*' 16:03:24 INFO - Successfully installed jsonschema functools32 16:03:24 INFO - Cleaning up... 16:03:24 INFO - Return code: 0 16:03:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:03:24 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')]} 16:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:03:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:24 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')]} 16:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:03:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:03:24 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe31a95af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x20878a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2152770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, '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': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', '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 16:03:24 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', 'flake8'] in /builds/slave/test/build 16:03:24 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 flake8 16:03:24 INFO - Using env: {'DISPLAY': ':0', 16:03:24 INFO - 'HOME': '/home/cltbld', 16:03:24 INFO - 'LANG': 'en_US.UTF-8', 16:03:24 INFO - 'LANGUAGE': 'en_US:en', 16:03:24 INFO - 'LOGNAME': 'cltbld', 16:03:24 INFO - 'MAIL': '/var/mail/cltbld', 16:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:24 INFO - 'MOZ_NO_REMOTE': '1', 16:03:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:24 INFO - 'NO_EM_RESTART': '1', 16:03:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:24 INFO - 'PWD': '/builds/slave/test', 16:03:24 INFO - 'SHELL': '/bin/bash', 16:03:24 INFO - 'SHLVL': '1', 16:03:24 INFO - 'TERM': 'linux', 16:03:24 INFO - 'TMOUT': '86400', 16:03:24 INFO - 'USER': 'cltbld', 16:03:24 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:24 INFO - '_': '/tools/buildbot/bin/python'} 16:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:03:24 INFO - Downloading/unpacking flake8 16:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:03:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:03:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:03:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:03:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:26 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 16:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:03:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:03:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:03:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:03:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:03:27 INFO - no previously-included directories found matching 'docs/_build' 16:03:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:03:27 INFO - Running setup.py install for pep8 16:03:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:03:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:03:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:03:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:03:27 INFO - no previously-included directories found matching 'docs/_build' 16:03:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:03:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:03:27 INFO - Cleaning up... 16:03:27 INFO - Return code: 0 16:03:27 INFO - Running post-action listener: _resource_record_post_action 16:03:27 INFO - Running post-action listener: _start_resource_monitoring 16:03:27 INFO - Starting resource monitoring. 16:03:27 INFO - ##### 16:03:27 INFO - ##### Finished create-virtualenv step (success) 16:03:27 INFO - ##### 16:03:27 INFO - ##### 16:03:27 INFO - ##### Running install step. 16:03:27 INFO - ##### 16:03:27 INFO - Running pre-action listener: _resource_record_pre_action 16:03:27 INFO - Running main action method: install 16:03:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:03:27 INFO - Reading from file tmpfile_stdout 16:03:27 INFO - Detecting whether we're running mozinstall >=1.0... 16:03:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:03:27 INFO - Reading from file tmpfile_stdout 16:03:27 INFO - Output received: 16:03:27 INFO - Usage: mozinstall [options] installer 16:03:27 INFO - Options: 16:03:27 INFO - -h, --help show this help message and exit 16:03:27 INFO - -d DEST, --destination=DEST 16:03:27 INFO - Directory to install application into. [default: 16:03:27 INFO - "/builds/slave/test"] 16:03:27 INFO - --app=APP Application being installed. [default: firefox] 16:03:27 INFO - mkdir: /builds/slave/test/build/application 16:03:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:03:44 INFO - Reading from file tmpfile_stdout 16:03:44 INFO - Output received: 16:03:44 INFO - /builds/slave/test/build/application/firefox/firefox 16:03:44 INFO - Running post-action listener: _resource_record_post_action 16:03:44 INFO - ##### 16:03:44 INFO - ##### Finished install step (success) 16:03:44 INFO - ##### 16:03:44 INFO - ##### 16:03:44 INFO - ##### Running run-tests step. 16:03:44 INFO - ##### 16:03:44 INFO - Running pre-action listener: _resource_record_pre_action 16:03:44 INFO - Running main action method: run_tests 16:03:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:03:44 INFO - Python 2.7.3 16:03:44 INFO - Return code: 0 16:03:44 INFO - grabbing minidump binary from tooltool 16:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:03:44 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 0x21e19c0>, '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 0x21e28c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e2d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:03:44 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 16:03:44 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 16:03:44 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 16:03:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:03:44 INFO - Return code: 0 16:03:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:03:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:03:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:03:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:03:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:03:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:03:45 INFO - Return code: 0 16:03:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 16:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 16:03:45 INFO - Using env: {'DISPLAY': ':0', 16:03:45 INFO - 'HOME': '/home/cltbld', 16:03:45 INFO - 'LANG': 'en_US.UTF-8', 16:03:45 INFO - 'LANGUAGE': 'en_US:en', 16:03:45 INFO - 'LOGNAME': 'cltbld', 16:03:45 INFO - 'MAIL': '/var/mail/cltbld', 16:03:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:03:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:03:45 INFO - 'MOZ_NO_REMOTE': '1', 16:03:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:03:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:03:45 INFO - 'NO_EM_RESTART': '1', 16:03:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:03:45 INFO - 'PWD': '/builds/slave/test', 16:03:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:03:45 INFO - 'SHELL': '/bin/bash', 16:03:45 INFO - 'SHLVL': '1', 16:03:45 INFO - 'TERM': 'linux', 16:03:45 INFO - 'TMOUT': '86400', 16:03:45 INFO - 'USER': 'cltbld', 16:03:45 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1464389792.607349-1469284873', 16:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:03:45 INFO - '_': '/tools/buildbot/bin/python'} 16:03:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 16:03:46 INFO - mozversion application_buildid: 20160527144251 16:03:46 INFO - mozversion application_changeset: ea15028498ed95677844fb7f30be5efcaf8b2621 16:03:46 INFO - mozversion application_display_name: Nightly 16:03:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:03:46 INFO - mozversion application_name: Firefox 16:03:46 INFO - mozversion application_remotingname: firefox 16:03:46 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:03:46 INFO - mozversion application_vendor: Mozilla 16:03:46 INFO - mozversion application_version: 49.0a1 16:03:46 INFO - mozversion platform_buildid: 20160527144251 16:03:46 INFO - mozversion platform_changeset: ea15028498ed95677844fb7f30be5efcaf8b2621 16:03:46 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:03:46 INFO - mozversion platform_version: 49.0a1 16:03:46 INFO - using testdate: 1464390226 16:03:46 INFO - actual date: 1464390226 16:03:46 INFO - starting webserver on 'localhost:58854' 16:03:46 INFO - SUITE-START | Running 2 tests 16:03:46 INFO - TEST-START | dromaeo_css 16:03:46 INFO - operating with platform_type : linux_ 16:03:46 INFO - Initialising browser for dromaeo_css test... 16:03:46 INFO - TEST-INFO | started process 12577 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnN2IZA/profile http://localhost:58854/getInfo.html) 16:03:51 INFO - PROCESS | 12577 | __metrics Screen width/height:1600/1200 16:03:51 INFO - PROCESS | 12577 | colorDepth:24 16:03:51 INFO - PROCESS | 12577 | Browser inner width/height: 1024/697 16:03:51 INFO - PROCESS | 12577 | __metrics 16:03:52 ERROR - PROCESS | 12577 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:03:53 INFO - TEST-INFO | 12577: exit 0 16:03:53 INFO - Browser initialized. 16:03:53 INFO - Running cycle 1/1 for dromaeo_css test... 16:03:53 INFO - TEST-INFO | started process 12658 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnN2IZA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:04:05 INFO - PROCESS | 12658 | RSS: Main: 157130752 16:04:05 INFO - PROCESS | 12658 | 16:04:56 INFO - PROCESS | 12658 | 1464390296908 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] 16:07:09 INFO - PROCESS | 12658 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58854/tests/dromaeo/cssquery-ext.html) 16:07:10 INFO - PROCESS | 12658 | RSS: Main: 252772352 16:07:10 INFO - PROCESS | 12658 | 16:10:13 INFO - PROCESS | 12658 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-ext.html (next: http://localhost:58854/tests/dromaeo/cssquery-jquery.html) 16:10:14 INFO - PROCESS | 12658 | RSS: Main: 239136768 16:10:14 INFO - PROCESS | 12658 | 16:13:16 INFO - PROCESS | 12658 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58854/tests/dromaeo/cssquery-mootools.html) 16:13:17 INFO - PROCESS | 12658 | RSS: Main: 239861760 16:13:17 INFO - PROCESS | 12658 | 16:16:19 INFO - PROCESS | 12658 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58854/tests/dromaeo/cssquery-prototype.html) 16:16:19 INFO - PROCESS | 12658 | RSS: Main: 240959488 16:16:19 INFO - PROCESS | 12658 | 16:19:22 INFO - PROCESS | 12658 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58854/tests/dromaeo/cssquery-yui.html) 16:19:23 INFO - PROCESS | 12658 | RSS: Main: 208855040 16:19:23 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | Cycle 1(1): loaded http://localhost:58854/tests/dromaeo/cssquery-yui.html (next: http://localhost:58854/tests/dromaeo/cssquery-dojo.html) 16:22:26 INFO - PROCESS | 12658 | RSS: Main: 291520512 16:22:26 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | __start_tp_report 16:22:26 INFO - PROCESS | 12658 | _x_x_mozilla_page_load 16:22:26 INFO - PROCESS | 12658 | _x_x_mozilla_page_load_details 16:22:26 INFO - PROCESS | 12658 | |i|pagename|runs| 16:22:26 INFO - PROCESS | 12658 | |0;dojo.html;2684.677838659361;2696.2180967519394;2721.1972468121908;2734.945301093979;2755.6417665703457;4648.930266046003;4740.549647783464;4771.379720636317;4822.975885120574;4843.951560484394;4742.241241401376;4763.309320148585;4805.135075686375;4808.3268342432;4867.123917694814;4343.131373725252;4345.130973805236;4392.077663690613;4449.243628583133;4492;4406.603405693487;4457.465104187502;4491.281394976804;4505.301678239878;4508.075844451896;4324.610785029331;4363.214621368153;4372.344148377769;4382.189294980407;4387.363832244323;4419.359192917042;4430.069685366045;4430.867073987753;4433.490148632916;4436.489803672586;4316.136772645484;4329.047609525931;4337.392765012901;4373.890652733675;4402.449693788277;4686.742229177394;4726.338312636233;4759.428868535763;4762.547557981986;4776.355192049064;4995.500449955011;5193.480651934814;5194.532492075731;5201.739913004372;5202.895942081175;5080.974595127038;5114.590832733373;5157.767900444489;5178.2750414941565;5236.319278493771;4816.807327706852;4861.1006963711825;4864.270359446112;4869.70781753096;4872.4153101628035;5044.167712327425;5143.12566863634;5329.893402132013;5335.439778823245;5350.117230656976;4969.602431805447;4994.625403094782;5056.089903817275;5068.695878247243;5068.695878247317;5073.670211436245;5078.771455284521;5096.949030509648;5121.692698438105;5130.4356520782685;4984.053029924303;5045.798168073309;5063.0380227756605;5111.284420181178;5115.053715062725;5074.720890350993;5075.619328550373;5092.490750924878;5123.7438128093445;5149.0474262261605;4326.848560300375;4338.869833904988;4340;4384.846530371486;4385.627221686129;3739.476473293741;3758.530183727034;3786.6970642348547;3792.051987003249;3832.3101841668768;4362.781860906942;4405.140997505456;4407.471103467605;4414.448193975753;4434.889127771767;5135.768890399866;5138.177891537411;5146.742662866842;5160.509751573594;5209.0623687735815;4302.44068271129;4392.560743925582;4402.559744025636;4406.889827754268;4444.8444304449185;4556.225441674923;4623.953760462419;4633.721976681342;4640.257558790578;4648.140094082605;4880.414350277918;4892.021595680949;4896;4900.92648610264;4943.950560494421;5030.773615187252;5144.279800827812;5192.091384007708;5210.036143313499;5260.526552610283;4867.050925069578;4891.730954797378;4909.852704418873;4917.582005529427;4962.305277261258;5126.974365128151;5129;5131.717755523482;5133.281340612242;5188.558972487381;5042.873928151679;5048.59611231108;5096.031753966735;5198.818041368534;5214.765335559985;4945.208766597327;4968.22992436173;4973.701577905337;4975.825846095369;5011.047900898818;4966.602671786176;4971.6022718183185;4971.701697898137;4972.9254061188385;5008.048470790538;4854.514548545259;4925.9014819704125;4948.604111670986;4978.8755281118265;4984.925226121539;5076.796928122833;5092.694438333712;5100.69395836251;5106.540411362995;5124.077666020152;4919.7540122993;4935.827246046371;4940.11078005948;4943.901121977612;4990.101781679333 16:22:26 INFO - PROCESS | 12658 | |1;ext.html;575.8502789274787;591.6509259536872;603.3785201242719;604.3291945940009;604.60700544646;27083.322916927085;40977.79511102444;41905.16189676208;41944.790276048654;42010.36984445228;41937.161256774845;42598.14803703924;42621.573784262146;42636;42654;7401.518901271427;7799.688012479508;7969.322607578363;7969.322607578363;9998.45008524531;9765.707028789113;10107.747306317364;10110.342827716211;10111.191104711605;10143.898561014406;10122.139618132467;10215.642452514163;10227.079562839343;10241.539130739098;10248.487575621226;7441.218672039412;9950;10131.13885319745;10213.489325533694;10226.028527289896;9991.850122248172;10069.194464442828;10079.143272821819;10086.193104551618;10090.596376144946;38503.22993540142;38895;38916.80541597302;38952.41571376432;38984.80507597472;2968.154076088326;3032.5602787595894;3127.8566034736227;3303.355845610107;3344.2141096842224;26512.337191570172;26633.73366266332;26972.865135674198;27066.594001089998;28292.575611365846;3343.2310568569364;3418.7948723076447;3436.7250619950346;3457.222125021875;3490.616032236452;243840;245795;246238;246656;247218.9999999982;32212.35575288481;54904.176437353475;55947;56023.719881400335;56041.439585604436;32790.8360458201;34823.30353392918;34949;34976;34980;12655.87344126547;12759.42582583786;12781.808272875915;12782.105252632413;12786.360681965865;8742.519161446055;10256.435896025641;10428.270021098602;10461.790764184674;10631.255812093234;7885.408594355446;7925.682972681144;7929.881051784228;8002.079760827463;8014.599270036475;3076.523138913472;3090.7836451448634;3091.6908309168903;3115.3613509230554;3155.5897733294523;834.8038211020405;975.658519518163;1002.9749256268651;1022.5172406940036;1024.0067134879155;27314.45371092567;27350.863245683646;27459.17622471329;27858;27915.16254512368;970.3256236915418;970.5438443931341;981.489625394805;992.2211064314479;1004.7237009822358;14732.263386830615;14792.556223313319;14994.125734283214;15045.548633541011;15048.849511504965;285314;285975;286607;289026;290353;46701.29948050782;46766.29850552244;46968.76515617468;47067.293990590166;47073.29390059152;1075.9139268858473;1177.1700950829472;1177.5643012085584;1190.7678002789546;1192.1237890150733;25840.483190336465;29762.95829645952;29808.701912980592;29891.402171956874;30169;42117.57882421136;42432.78783606062;42536;42602;42634;273888;281918;282150;282739;283213;20378.286759963332;24831.62752558677;25137.692557361934;25202.11792587324;25780;43021;43059.138817222854;43127.568724312354;43181.56818431901;43496.34755478734;35900.281994359444;36324.63675363213;36574.634253657125;36609.58496848661;37021.44467833039;36333.81833090818;36499;36511.08722281964;36515;36621.26757464783;35814.820925896245;35926.461103083995;35930.4610430849;36343.454848177826;36766.080847979014;32126.35747285088;32217.838910805294;32508.18729531686;32591.511127333582;32649;25123.24630261095;25250.873745631154;25273.87363063173;25337.619935701347;25344.11295604645 16:22:26 INFO - PROCESS | 12658 | |2;jquery.html;370.76271186440675;373.387644263408;377.65821931152317;379.39297124600625;393.22338381696153;16068;17265.91367043163;17422.303107875687;17805.910970445148;17807.198676059576;14397.78403323951;15110.244487775595;15166.090034597932;15629.687406251867;15661.765073523906;4704.31787390829;4795.688280261789;4883.853484395456;4889.779959901813;4897.216445368742;3526.982365088171;4817.975910120462;5362.570994320445;5656.349519805213;5684.54523638108;5720.427957204267;5728.198052272685;5745.712714364286;5755.913661295063;5758.107493338539;5538.806141785019;5675.801346952858;5703;5742.4831765141125;5754.510866576346;3381.2899291148888;5143.099957507458;5480.095784195603;5485.753141108659;5485.862853428656;12729.745405091846;12734.363281835964;12789.744205115938;14952.32714527838;20139.697904531444;1782.3761683410833;1903.2862676496313;2154.1060459909118;2364.798992085675;2388.6775285336603;9132.296867113442;9338.299627527962;9423.528823558794;10631.627893023708;12320.137590368679;1730.645217730375;1775.944030854779;1802.7386029025718;1830.2861883865303;1838.7885393179688;66868.66565667141;69851.65074174597;69947;71469;73341.6332918332;10105.494725263707;10279.743006424898;10299.588016479256;10345.94827025875;10517.474126293655;14372.209528475825;14578.41686332556;14584.270786460635;14589.270536473134;14629.122252664874;6993.673714631869;7157.534760240567;7282.453815963824;7298.671559779823;7343.48595598303;6609.636469994197;6693.230278518033;6717.731290748413;6808.5914845109455;6851.725930962705;4465.642748580106;4491.101779644019;4516.503184649686;4520.570545798144;4601.309803529497;1577.834327395607;1700.6088599622155;1828.8994105324334;1845.4221640497449;1881.4637828218893;860.5697151424288;863.9654413823376;881.9989312130649;944.3059351376733;961.0197598449543;16232.594185145466;16305.266263018193;16744.330226791037;16900.577485562713;17008.48974530766;836.640244694787;878.9033206347297;894.6242578117076;895.8074014087002;900.8963969143501;9349.953250233706;9418;9449.251888400668;9687.612495500241;9911.256655750847;363080;364444;364484;367817;386646;87878.56060719784;88117;88934.55532722294;89613;90022;914.5610107148606;929.1823195587839;943.9433633981984;951.9623610264845;973.7273563402117;15646.653002049783;15908.920455397649;15959.281832317341;15979.840201597835;16359.865565185559;34470.65529344775;35442.291154177285;35495.64504354924;35625;36107.81946090253;47458;50563.24155137602;51612;51893;52787.20819187639;6612.8346791330605;6896.586204827727;7617.5048621839405;7713.267239612453;7940.960295198487;10684.946575267384;11820.527178913091;11873.762524749629;12011.699707507381;12076.698082548006;6225.066240063846;9144.954275228582;9352.298577606844;9648.75878103025;9831.655892043744;9924.900750992687;9957.402555846673;10041.04610062043;10073.949630251802;10262.692119236435;9668.70993870185;9671.613135474783;9742.75643108928;9904.900950990397;9934.503274836374;7130.42956563484;7155.391791697764;7209.423246140401;7219.45854060937;7372.59450730215;8578.442401244149;9258.72223833286;9527.857082143635;9673.21045606565;9711.174550163316 16:22:26 INFO - PROCESS | 12658 | |3;mootools.html;1188.0436248819697;1402.6142810727047;1453.8982271241011;1540.3992442947253;1781.1539518728614;6696.732130714771;7406.629668516573;7617.390608751293;7627.7330293439745;7833;7562.09254889414;7706.961465192666;7714.190010048934;7725.072991241045;7727.2659097385795;1890.102201454305;2108.188347486221;2189.5183059726874;2194.3746032380773;2221.3891179925567;2233.4528040630066;2254.549090181962;2255.210676263311;2256.548690261954;2259.2318611672026;2179.869207847534;2192.0355043780764;2200.933971980843;2204.239537359608;2208.911643534265;1823.9908800455912;2102.9474263143393;2107.2729908181727;2175.3473957812594;2179.1501314487205;2176.7823217678265;2186;2217.2018073493527;2221.8777967211795;2221.911123555056;7743.148253692089;7793.415493837985;7806.180351063113;7815.10126335473;7816.531008139473;4620.630349572026;4734.976325118352;4740.194166991585;4746.5490778376;4754.9286760698615;2850.5011622966103;2880.495913215179;2940.1326608650497;2941.647002359709;2946.454905842426;896.3635818568824;905.9592318345624;920.0431551186758;935.4854829843558;952.4333021851987;10398.324108932895;10465.895341046567;10482.318649287849;10490.475476226158;10507.474626268733;4149.647279981175;4279.572042795696;4336.609705126553;4338.913221735547;4362.847300344472;8699.391042627081;8806.383553151347;8842.823143537093;8869.68956086534;8873.689420870238;4654.86035418938;4678.602318802872;4696.201645720235;4702.3416721659;4705.811767529261;3801.6008319126645;4193.6645068394455;4373.781310934441;4373.978130109329;4404.207242696345;3589.6589823966683;3651.4887915691825;3709.5548534176073;3731.0858839583934;3762.247550489913;1202.2365797718512;1203.7291609387992;1212.7453234820787;1219.3598360860442;1225.871283515236;878.0780180810124;884.8539990901429;886.7206829848577;891.5987805487556;894.789724414762;5366.248725178478;5447.509724124848;5481.835544933579;5577.163425486128;5645.520130788929;413.2128295596893;423.8283495184455;428.6870584473352;434.4829652713261;435.904101097758;3869.7484663496784;3876.2247550490015;3889.9805500972316;3937.645611894943;3945.2504024235304;9108.772280693036;9538.426159683713;9569.473678947725;9587.616495340248;9593;8885.111488851193;9391.295652826066;9391.812163756687;9488.857667134998;9633.807323853624;7171.1394632644415;7183.676734547063;7188.388986936065;7239.674214660353;7264.091988501437;5451.155070964007;5484.232207490874;5523.475269849358;5549.139883317931;5591.245181900392;8820.603072861608;9022.729318120468;9045.819083618422;9046.140616641409;9053.547322633975;8762.123787621187;9037.548122593975;9248.260139188715;9254.444733316022;9262.16640502358;5597.748101335368;5792.681402522901;6616;6680.298568650222;6775.119234499483;7197.964010179916;7225.169105552718;7263.0921134858145;7298.087739032621;7309;6006.249218847644;6012.488938440281;6073.969630151821;6095.146679464967;6129.467633091727;6308.179936608211;6489.5781774184525;6506.3818937200895;6584.967075164594;6628.668566571556;6240.5319601031015;6253.499720022296;6320.241571011324;6322.778702745381;6329.272133704683;4498.685092043525;4519.367288579536;4521.412216411845;4610.585047345755;4687.789049492712;4505.684602077823;4653.255479123324;4657.347971283955;4692.929606055844;4745.6203503718925 16:22:26 INFO - PROCESS | 12658 | |4;prototype.html;280.9803313768037;288.1600135604714;288.59308375190983;302.9560713696514;310.70638246856714;4744.833930812422;4761.595264402526;4772.665913386064;4796.400449943757;4823.985009369144;4723.102610504002;4728.219843725781;4742.004179122388;4743.952560474394;4769.332293478907;3579.0873327301565;3670.1742108025614;3672.4307732301536;3681.4109742441215;3710.202306504101;3600.4959305697225;3740.28934502445;3763.266163098197;3772.8868133956025;3786.110264087951;3638.5451818522683;3685.4656074869126;3719.6094410086957;3719.902628724519;3724.9627503725023;3626.4016437287814;3702.241040586673;3721.62783721627;3725.18046029873;3743.5025989604105;3291.127851119455;3293.0943990402593;3303.0586282909494;3307.4046671599103;3325.8503367348526;4561.840335588238;4564.740453475551;4594.310853371988;4594.563516465931;4603.710960930945;4259.318509038539;4270.103278311558;4270.679699022586;4278.764667943262;4297.119090586422;4736.59738922192;4950.282209079699;4957.3059771632;4958.578520825734;5005.599552035865;3575.66031227033;3596.6583174598372;3598.6221446748236;3604.387254166798;3612.934184415604;5164.431912489623;5184.326037615086;5184.948150518484;5198.532132108128;5206.817761378372;3461.549998500179;3656.634336566322;3667.74325797197;3682.5949145593963;3694.6674799267657;3747.3324370654796;3776.6412190842375;3783.1651648950237;3785.280796648628;3814.8855534334016;4267.189234045583;4294.355846622969;4303.763293018851;4307.547707490731;4323.762193079411;5003.424606170264;5043.672161309503;5048.646594738333;5052.621053421009;5065.797368105234;1261.6025951825145;1327.8804907558367;1328.8206092177431;1331.8867896228928;1332.0941759603563;847.3687103108101;850.6002178975872;862.5169904853302;865.4374656473343;870.825834833036;4254.808533615995;4315.3742707307265;4392.582704643054;4419.712718673276;4442.222611043119;5126.948730512721;5154.613403994716;5157.974210128926;5167.147420675622;5198.402183748842;4433.623142032963;4439.113376814841;4455.1757924784015;4462.754548499799;4475.395821564112;2655.1503518873774;2754.5730411786203;2756.6691996960094;2764.9447011059665;2768.820026698258;4862.635302352267;4869.2939523768855;4877.731724755173;4890.731009794425;4917.28699338601;5128.256392826457;5273.894522109613;5292;5297.125974214211;5313;4867.221244600989;4882.36529251195;4886.657933944661;4910.263460480956;4914.262860570943;5113.693178409308;5135.614828887923;5158.174692049254;5166.870828229325;5186.144286192887;4939.827106051271;5065.138926382598;5088.821891233789;5101.642884998015;5111.948880511147;4862.927056094232;4867.858142417325;4908.533689299511;4919.606431485401;4922.1632322505975;3755;3819.1979684266616;3822.6941844652915;3838.865639702597;3852.8266228020366;4921.458639549575;5130.204818253177;5147.742612869416;5160;5202.921956170736;4999.625028122832;5009.974950125226;5012.674176178537;5014.222795466709;5019.958933058922;4993.2510123480315;4994.827250146198;4999.425066117297;5008.499150084962;5023.57299629521;4653.953460465352;4875.317455556154;4939.481354457874;4955.950440495549;4956.430010548833;4884.902301954012;4993.80024799004;5066.44269130388;5100.464451232567;5107.412647545431;4944.579710724484;4945.4807245238735;4948.703077815343;4959.553640172402;4971.552560269593 16:22:26 INFO - PROCESS | 12658 | |5;yui.html;609.1076572820818;690.7637717294473;753.5591678867862;763.8777795552711;769.2422963381068;12237.510499580017;12352.567660131897;12362.752744945095;12363.938180309096;12555.246685198894;11478.483468243929;11668.41657917105;11994.880051199485;12149.27104373736;12155.939220303908;789.3803364358965;796.0646240667222;800.967961281548;802.618756090858;803.4536515169682;625.684029565069;768.2049079203026;797.366093955304;798.0901771979948;802.2899733735628;847.7117779954814;851.0765819086299;851.259404318244;851.2976794144824;852.0925214646413;657.5298661457033;842.8440738463404;843.4559708987699;843.7721815109948;844.1474111147754;596.8955432799243;618.3970628637043;619.4053708439903;620.0606081786098;620.5066971757464;12258.325792081432;12262.693432664166;12310.384480775925;12310.630681079581;12398.81401778974;11729.472173752116;12230.266184028987;12285.877141228562;12426.192297500633;12789.040821938392;11025.503852326665;11068.114550835995;11128.554857805679;11173.944130279378;11194.832077518844;4047.8380864765372;4064.9187016259807;4103.712740108193;4158.480189976253;4195.559466256061;45229.77385113086;45929.311060333785;46982.765086174695;47297.763511182566;47424.05151896943;9915.553800079013;10012.098911098035;10049.296549241482;10116.494175291205;10369.533370998171;16150.59623509398;16694.332226711038;17937;17962.91018544899;17968.730469042974;6365.2679941806355;6575.638339891256;6594.07682924391;6702.430293425016;6769.289224631441;3493.9650603494147;3587.215487660491;3593.4070878304788;3622.166901612591;3684.115812205105;2940.1914473519955;3024.516077427601;3030.9090727278217;3034.544818277276;3035.8785648573808;521.3405042621088;526.628726747642;545.7953267524679;549.3764577204901;553.6927005511899;467.02392000718663;491.8893249019014;559.9668611411938;579.1804596495911;594.6016169166669;8186.83626327482;8422.369002188403;8461.746147615582;8478.660853565912;8671.913280867237;573.8135106090538;576.8413686236319;593.8456001439657;595.3778301674761;597.614538622586;3616.2044350242904;4115.259253334369;4165.187788381297;4238.936415953764;4345.34819777036;58871;59850.40149598535;61233.38766612366;61271.38728612746;61328.69335653293;54687.17969230465;55161.72419137879;55695.44304556902;56613;56841.43158568444;48139.518604814206;48431.515684843405;49180.508194917595;49746.75126624344;51562.74218628883;7923.405744569065;8118.392814561556;8152.14402487754;8227.259546640831;8355.331573474228;33013.339733205685;33105.66894331026;33217.335653287286;33367.66632333645;33510.32979340351;39873.80063099782;41011.179776404904;43318.783406082766;43469.56530434655;43915;10829.241953063525;11032.944835275772;11059.72350691239;11082.113430925345;11410.372429516456;11517.308961462339;11879.643610691694;11979.341136237243;12315.815262771244;12334.259944403364;11543.538258469904;11873.406329683654;11898.22661526998;12107.75784484323;12288.385580721108;10569.154467642416;10700.357978521313;11235.55057797679;11438.542458301908;11551.595694151001;10822.188335874998;10922.453877306261;11039.448027598428;11798.469068892093;12009.63971080869;8511.334562979602;8702.390832641655;8753.299736020976;8861.291096712119;8995.280377569648;9534.666286679932;9678.612855485702;9979.351342162736;9988.700338989842;10008.09927106564 16:22:26 INFO - PROCESS | 12658 | __end_tp_report 16:22:26 INFO - PROCESS | 12658 | __start_cc_report 16:22:26 INFO - PROCESS | 12658 | _x_x_mozilla_cycle_collect,966 16:22:26 INFO - PROCESS | 12658 | __end_cc_report 16:22:26 INFO - PROCESS | 12658 | __startTimestamp1464391346686__endTimestamp 16:22:26 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | ------- Summary: start ------- 16:22:26 INFO - PROCESS | 12658 | Number of tests: 6 16:22:26 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | [#0] dojo.html Cycles:165 Average:4746.88 Median:4909.85 stddev:498.37 (10.2%) stddev-sans-first:472.91 16:22:26 INFO - PROCESS | 12658 | Values: 2684.7 2696.2 2721.2 2734.9 2755.6 4648.9 4740.5 4771.4 4823.0 4844.0 4742.2 4763.3 4805.1 4808.3 4867.1 4343.1 4345.1 4392.1 4449.2 4492.0 4406.6 4457.5 4491.3 4505.3 4508.1 4324.6 4363.2 4372.3 4382.2 4387.4 4419.4 4430.1 4430.9 4433.5 4436.5 4316.1 4329.0 4337.4 4373.9 4402.4 4686.7 4726.3 4759.4 4762.5 4776.4 4995.5 5193.5 5194.5 5201.7 5202.9 5081.0 5114.6 5157.8 5178.3 5236.3 4816.8 4861.1 4864.3 4869.7 4872.4 5044.2 5143.1 5329.9 5335.4 5350.1 4969.6 4994.6 5056.1 5068.7 5068.7 5073.7 5078.8 5096.9 5121.7 5130.4 4984.1 5045.8 5063.0 5111.3 5115.1 5074.7 5075.6 5092.5 5123.7 5149.0 4326.8 4338.9 4340.0 4384.8 4385.6 3739.5 3758.5 3786.7 3792.1 3832.3 4362.8 4405.1 4407.5 4414.4 4434.9 5135.8 5138.2 5146.7 5160.5 5209.1 4302.4 4392.6 4402.6 4406.9 4444.8 4556.2 4624.0 4633.7 4640.3 4648.1 4880.4 4892.0 4896.0 4900.9 4944.0 5030.8 5144.3 5192.1 5210.0 5260.5 4867.1 4891.7 4909.9 4917.6 4962.3 5127.0 5129.0 5131.7 5133.3 5188.6 5042.9 5048.6 5096.0 5198.8 5214.8 4945.2 4968.2 4973.7 4975.8 5011.0 4966.6 4971.6 4971.7 4972.9 5008.0 4854.5 4925.9 4948.6 4978.9 4984.9 5076.8 5092.7 5100.7 5106.5 5124.1 4919.8 4935.8 4940.1 4943.9 4990.1 16:22:26 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | [#1] ext.html Cycles:180 Average:42625.95 Median:32363.01 stddev:71069.89 (219.6%) stddev-sans-first:71199.12 16:22:26 INFO - PROCESS | 12658 | Values: 575.9 591.7 603.4 604.3 604.6 27083.3 40977.8 41905.2 41944.8 42010.4 41937.2 42598.1 42621.6 42636.0 42654.0 7401.5 7799.7 7969.3 7969.3 9998.5 9765.7 10107.7 10110.3 10111.2 10143.9 10122.1 10215.6 10227.1 10241.5 10248.5 7441.2 9950.0 10131.1 10213.5 10226.0 9991.9 10069.2 10079.1 10086.2 10090.6 38503.2 38895.0 38916.8 38952.4 38984.8 2968.2 3032.6 3127.9 3303.4 3344.2 26512.3 26633.7 26972.9 27066.6 28292.6 3343.2 3418.8 3436.7 3457.2 3490.6 243840.0 245795.0 246238.0 246656.0 247219.0 32212.4 54904.2 55947.0 56023.7 56041.4 32790.8 34823.3 34949.0 34976.0 34980.0 12655.9 12759.4 12781.8 12782.1 12786.4 8742.5 10256.4 10428.3 10461.8 10631.3 7885.4 7925.7 7929.9 8002.1 8014.6 3076.5 3090.8 3091.7 3115.4 3155.6 834.8 975.7 1003.0 1022.5 1024.0 27314.5 27350.9 27459.2 27858.0 27915.2 970.3 970.5 981.5 992.2 1004.7 14732.3 14792.6 14994.1 15045.5 15048.8 285314.0 285975.0 286607.0 289026.0 290353.0 46701.3 46766.3 46968.8 47067.3 47073.3 1075.9 1177.2 1177.6 1190.8 1192.1 25840.5 29763.0 29808.7 29891.4 30169.0 42117.6 42432.8 42536.0 42602.0 42634.0 273888.0 281918.0 282150.0 282739.0 283213.0 20378.3 24831.6 25137.7 25202.1 25780.0 43021.0 43059.1 43127.6 43181.6 43496.3 35900.3 36324.6 36574.6 36609.6 37021.4 36333.8 36499.0 36511.1 36515.0 36621.3 35814.8 35926.5 35930.5 36343.5 36766.1 32126.4 32217.8 32508.2 32591.5 32649.0 25123.2 25250.9 25273.9 25337.6 25344.1 16:22:26 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | [#2] jquery.html Cycles:180 Average:23804.07 Median:28377.55 stddev:61506.70 (216.7%) stddev-sans-first:61654.08 16:22:26 INFO - PROCESS | 12658 | Values: 370.8 373.4 377.7 379.4 393.2 16068.0 17265.9 17422.3 17805.9 17807.2 14397.8 15110.2 15166.1 15629.7 15661.8 4704.3 4795.7 4883.9 4889.8 4897.2 3527.0 4818.0 5362.6 5656.3 5684.5 5720.4 5728.2 5745.7 5755.9 5758.1 5538.8 5675.8 5703.0 5742.5 5754.5 3381.3 5143.1 5480.1 5485.8 5485.9 12729.7 12734.4 12789.7 14952.3 20139.7 1782.4 1903.3 2154.1 2364.8 2388.7 9132.3 9338.3 9423.5 10631.6 12320.1 1730.6 1775.9 1802.7 1830.3 1838.8 66868.7 69851.7 69947.0 71469.0 73341.6 10105.5 10279.7 10299.6 10345.9 10517.5 14372.2 14578.4 14584.3 14589.3 14629.1 6993.7 7157.5 7282.5 7298.7 7343.5 6609.6 6693.2 6717.7 6808.6 6851.7 4465.6 4491.1 4516.5 4520.6 4601.3 1577.8 1700.6 1828.9 1845.4 1881.5 860.6 864.0 882.0 944.3 961.0 16232.6 16305.3 16744.3 16900.6 17008.5 836.6 878.9 894.6 895.8 900.9 9350.0 9418.0 9449.3 9687.6 9911.3 363080.0 364444.0 364484.0 367817.0 386646.0 87878.6 88117.0 88934.6 89613.0 90022.0 914.6 929.2 943.9 952.0 973.7 15646.7 15908.9 15959.3 15979.8 16359.9 34470.7 35442.3 35495.6 35625.0 36107.8 47458.0 50563.2 51612.0 51893.0 52787.2 6612.8 6896.6 7617.5 7713.3 7941.0 10684.9 11820.5 11873.8 12011.7 12076.7 6225.1 9145.0 9352.3 9648.8 9831.7 9924.9 9957.4 10041.0 10073.9 10262.7 9668.7 9671.6 9742.8 9904.9 9934.5 7130.4 7155.4 7209.4 7219.5 7372.6 8578.4 9258.7 9527.9 9673.2 9711.2 16:22:26 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | [#3] mootools.html Cycles:180 Average:5089.60 Median:5406.88 stddev:2822.72 (52.2%) stddev-sans-first:2815.41 16:22:26 INFO - PROCESS | 12658 | Values: 1188.0 1402.6 1453.9 1540.4 1781.2 6696.7 7406.6 7617.4 7627.7 7833.0 7562.1 7707.0 7714.2 7725.1 7727.3 1890.1 2108.2 2189.5 2194.4 2221.4 2233.5 2254.5 2255.2 2256.5 2259.2 2179.9 2192.0 2200.9 2204.2 2208.9 1824.0 2102.9 2107.3 2175.3 2179.2 2176.8 2186.0 2217.2 2221.9 2221.9 7743.1 7793.4 7806.2 7815.1 7816.5 4620.6 4735.0 4740.2 4746.5 4754.9 2850.5 2880.5 2940.1 2941.6 2946.5 896.4 906.0 920.0 935.5 952.4 10398.3 10465.9 10482.3 10490.5 10507.5 4149.6 4279.6 4336.6 4338.9 4362.8 8699.4 8806.4 8842.8 8869.7 8873.7 4654.9 4678.6 4696.2 4702.3 4705.8 3801.6 4193.7 4373.8 4374.0 4404.2 3589.7 3651.5 3709.6 3731.1 3762.2 1202.2 1203.7 1212.7 1219.4 1225.9 878.1 884.9 886.7 891.6 894.8 5366.2 5447.5 5481.8 5577.2 5645.5 413.2 423.8 428.7 434.5 435.9 3869.7 3876.2 3890.0 3937.6 3945.3 9108.8 9538.4 9569.5 9587.6 9593.0 8885.1 9391.3 9391.8 9488.9 9633.8 7171.1 7183.7 7188.4 7239.7 7264.1 5451.2 5484.2 5523.5 5549.1 5591.2 8820.6 9022.7 9045.8 9046.1 9053.5 8762.1 9037.5 9248.3 9254.4 9262.2 5597.7 5792.7 6616.0 6680.3 6775.1 7198.0 7225.2 7263.1 7298.1 7309.0 6006.2 6012.5 6074.0 6095.1 6129.5 6308.2 6489.6 6506.4 6585.0 6628.7 6240.5 6253.5 6320.2 6322.8 6329.3 4498.7 4519.4 4521.4 4610.6 4687.8 4505.7 4653.3 4657.3 4692.9 4745.6 16:22:26 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | [#4] prototype.html Cycles:180 Average:4169.06 Median:4744.39 stddev:1202.79 (25.4%) stddev-sans-first:1170.22 16:22:26 INFO - PROCESS | 12658 | Values: 281.0 288.2 288.6 303.0 310.7 4744.8 4761.6 4772.7 4796.4 4824.0 4723.1 4728.2 4742.0 4744.0 4769.3 3579.1 3670.2 3672.4 3681.4 3710.2 3600.5 3740.3 3763.3 3772.9 3786.1 3638.5 3685.5 3719.6 3719.9 3725.0 3626.4 3702.2 3721.6 3725.2 3743.5 3291.1 3293.1 3303.1 3307.4 3325.9 4561.8 4564.7 4594.3 4594.6 4603.7 4259.3 4270.1 4270.7 4278.8 4297.1 4736.6 4950.3 4957.3 4958.6 5005.6 3575.7 3596.7 3598.6 3604.4 3612.9 5164.4 5184.3 5184.9 5198.5 5206.8 3461.5 3656.6 3667.7 3682.6 3694.7 3747.3 3776.6 3783.2 3785.3 3814.9 4267.2 4294.4 4303.8 4307.5 4323.8 5003.4 5043.7 5048.6 5052.6 5065.8 1261.6 1327.9 1328.8 1331.9 1332.1 847.4 850.6 862.5 865.4 870.8 4254.8 4315.4 4392.6 4419.7 4442.2 5126.9 5154.6 5158.0 5167.1 5198.4 4433.6 4439.1 4455.2 4462.8 4475.4 2655.2 2754.6 2756.7 2764.9 2768.8 4862.6 4869.3 4877.7 4890.7 4917.3 5128.3 5273.9 5292.0 5297.1 5313.0 4867.2 4882.4 4886.7 4910.3 4914.3 5113.7 5135.6 5158.2 5166.9 5186.1 4939.8 5065.1 5088.8 5101.6 5111.9 4862.9 4867.9 4908.5 4919.6 4922.2 3755.0 3819.2 3822.7 3838.9 3852.8 4921.5 5130.2 5147.7 5160.0 5202.9 4999.6 5010.0 5012.7 5014.2 5020.0 4993.3 4994.8 4999.4 5008.5 5023.6 4654.0 4875.3 4939.5 4956.0 4956.4 4884.9 4993.8 5066.4 5100.5 5107.4 4944.6 4945.5 4948.7 4959.6 4971.6 16:22:26 INFO - PROCESS | 12658 | 16:22:26 INFO - PROCESS | 12658 | [#5] yui.html Cycles:180 Average:13765.46 Median:45579.54 stddev:16432.88 (36.1%) stddev-sans-first:16449.28 16:22:26 INFO - PROCESS | 12658 | Values: 609.1 690.8 753.6 763.9 769.2 12237.5 12352.6 12362.8 12363.9 12555.2 11478.5 11668.4 11994.9 12149.3 12155.9 789.4 796.1 801.0 802.6 803.5 625.7 768.2 797.4 798.1 802.3 847.7 851.1 851.3 851.3 852.1 657.5 842.8 843.5 843.8 844.1 596.9 618.4 619.4 620.1 620.5 12258.3 12262.7 12310.4 12310.6 12398.8 11729.5 12230.3 12285.9 12426.2 12789.0 11025.5 11068.1 11128.6 11173.9 11194.8 4047.8 4064.9 4103.7 4158.5 4195.6 45229.8 45929.3 46982.8 47297.8 47424.1 9915.6 10012.1 10049.3 10116.5 10369.5 16150.6 16694.3 17937.0 17962.9 17968.7 6365.3 6575.6 6594.1 6702.4 6769.3 3494.0 3587.2 3593.4 3622.2 3684.1 2940.2 3024.5 3030.9 3034.5 3035.9 521.3 526.6 545.8 549.4 553.7 467.0 491.9 560.0 579.2 594.6 8186.8 8422.4 8461.7 8478.7 8671.9 573.8 576.8 593.8 595.4 597.6 3616.2 4115.3 4165.2 4238.9 4345.3 58871.0 59850.4 61233.4 61271.4 61328.7 54687.2 55161.7 55695.4 56613.0 56841.4 48139.5 48431.5 49180.5 49746.8 51562.7 7923.4 8118.4 8152.1 8227.3 8355.3 33013.3 33105.7 33217.3 33367.7 33510.3 39873.8 41011.2 43318.8 43469.6 43915.0 10829.2 11032.9 11059.7 11082.1 11410.4 11517.3 11879.6 11979.3 12315.8 12334.3 11543.5 11873.4 11898.2 12107.8 12288.4 10569.2 10700.4 11235.6 11438.5 11551.6 10822.2 10922.5 11039.4 11798.5 12009.6 8511.3 8702.4 8753.3 8861.3 8995.3 9534.7 9678.6 9979.4 9988.7 10008.1 16:22:26 INFO - PROCESS | 12658 | -------- Summary: end -------- 16:22:26 INFO - PROCESS | 12658 | 16:22:27 INFO - TEST-INFO | 12658: exit 0 16:22:27 INFO - TEST-OK | dromaeo_css | took 1121518ms 16:22:27 INFO - TEST-START | kraken 16:22:27 INFO - operating with platform_type : linux_ 16:22:27 INFO - Initialising browser for kraken test... 16:22:27 INFO - TEST-INFO | started process 12836 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZnHeH1/profile http://localhost:58854/getInfo.html) 16:22:32 INFO - PROCESS | 12836 | __metrics Screen width/height:1600/1200 16:22:32 INFO - PROCESS | 12836 | colorDepth:24 16:22:32 INFO - PROCESS | 12836 | Browser inner width/height: 1024/697 16:22:32 INFO - PROCESS | 12836 | __metrics 16:22:33 ERROR - PROCESS | 12836 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:22:34 INFO - TEST-INFO | 12836: exit 0 16:22:34 INFO - Browser initialized. 16:22:34 INFO - Running cycle 1/1 for kraken test... 16:22:34 INFO - TEST-INFO | started process 12917 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZnHeH1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:46 INFO - PROCESS | 12917 | RSS: Main: 156823552 16:22:46 INFO - PROCESS | 12917 | 16:22:49 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58854/tests/kraken/driver.html?testName=audio-beat-detection) 16:22:50 INFO - PROCESS | 12917 | RSS: Main: 214822912 16:22:50 INFO - PROCESS | 12917 | 16:22:53 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58854/tests/kraken/driver.html?testName=audio-dft) 16:22:53 INFO - PROCESS | 12917 | RSS: Main: 202801152 16:22:53 INFO - PROCESS | 12917 | 16:22:57 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58854/tests/kraken/driver.html?testName=audio-fft) 16:22:57 INFO - PROCESS | 12917 | RSS: Main: 269787136 16:22:57 INFO - PROCESS | 12917 | 16:22:59 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58854/tests/kraken/driver.html?testName=audio-oscillator) 16:23:00 INFO - PROCESS | 12917 | RSS: Main: 180391936 16:23:00 INFO - PROCESS | 12917 | 16:23:02 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58854/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:23:03 INFO - PROCESS | 12917 | RSS: Main: 184446976 16:23:03 INFO - PROCESS | 12917 | 16:23:06 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58854/tests/kraken/driver.html?testName=imaging-darkroom) 16:23:07 INFO - PROCESS | 12917 | RSS: Main: 220504064 16:23:07 INFO - PROCESS | 12917 | 16:23:10 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58854/tests/kraken/driver.html?testName=imaging-desaturate) 16:23:11 INFO - PROCESS | 12917 | RSS: Main: 216358912 16:23:11 INFO - PROCESS | 12917 | 16:23:14 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58854/tests/kraken/driver.html?testName=json-parse-financial) 16:23:14 INFO - PROCESS | 12917 | RSS: Main: 219381760 16:23:14 INFO - PROCESS | 12917 | 16:23:16 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58854/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:23:16 INFO - PROCESS | 12917 | RSS: Main: 208764928 16:23:16 INFO - PROCESS | 12917 | 16:23:18 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:23:19 INFO - PROCESS | 12917 | RSS: Main: 210141184 16:23:19 INFO - PROCESS | 12917 | 16:23:21 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:23:22 INFO - PROCESS | 12917 | RSS: Main: 217591808 16:23:22 INFO - PROCESS | 12917 | 16:23:25 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:23:25 INFO - PROCESS | 12917 | RSS: Main: 223150080 16:23:25 INFO - PROCESS | 12917 | 16:23:28 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:23:29 INFO - PROCESS | 12917 | RSS: Main: 221593600 16:23:29 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | Cycle 1(1): loaded http://localhost:58854/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58854/tests/kraken/driver.html?testName=ai-astar) 16:23:31 INFO - PROCESS | 12917 | RSS: Main: 263741440 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | __start_tp_report 16:23:31 INFO - PROCESS | 12917 | _x_x_mozilla_page_load 16:23:31 INFO - PROCESS | 12917 | _x_x_mozilla_page_load_details 16:23:31 INFO - PROCESS | 12917 | |i|pagename|runs| 16:23:31 INFO - PROCESS | 12917 | |0;ai-astar;114;109;115;115;113;113;113;114;115;113 16:23:31 INFO - PROCESS | 12917 | |1;audio-beat-detection;157;137;130;166;137;159;158;131;182;129 16:23:31 INFO - PROCESS | 12917 | |2;audio-dft;160;163;160;161;160;159;161;162;161;163 16:23:31 INFO - PROCESS | 12917 | |3;audio-fft;77;75;77;123;76;78;75;75;98;75 16:23:31 INFO - PROCESS | 12917 | |4;audio-oscillator;85;84;108;85;86;84;120;84;85;122 16:23:31 INFO - PROCESS | 12917 | |5;imaging-gaussian-blur;116;117;117;117;117;117;117;117;117;117 16:23:31 INFO - PROCESS | 12917 | |6;imaging-darkroom;146;146;146;147;147;146;145;146;147;145 16:23:31 INFO - PROCESS | 12917 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 16:23:31 INFO - PROCESS | 12917 | |8;json-parse-financial;64;64;63;65;64;65;65;65;65;66 16:23:31 INFO - PROCESS | 12917 | |9;json-stringify-tinderbox;65;65;65;65;65;65;64;65;65;64 16:23:31 INFO - PROCESS | 12917 | |10;stanford-crypto-aes;88;89;83;82;84;91;84;87;141;82 16:23:31 INFO - PROCESS | 12917 | |11;stanford-crypto-ccm;136;141;128;128;138;127;129;130;138;142 16:23:31 INFO - PROCESS | 12917 | |12;stanford-crypto-pbkdf2;170;172;171;173;173;174;172;171;170;170 16:23:31 INFO - PROCESS | 12917 | |13;stanford-crypto-sha256-iterative;70;68;69;68;66;66;68;68;68;66 16:23:31 INFO - PROCESS | 12917 | __end_tp_report 16:23:31 INFO - PROCESS | 12917 | __start_cc_report 16:23:31 INFO - PROCESS | 12917 | _x_x_mozilla_cycle_collect,1889 16:23:31 INFO - PROCESS | 12917 | __end_cc_report 16:23:31 INFO - PROCESS | 12917 | __startTimestamp1464391411254__endTimestamp 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | ------- Summary: start ------- 16:23:31 INFO - PROCESS | 12917 | Number of tests: 14 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:1.78 (1.6%) stddev-sans-first:1.87 16:23:31 INFO - PROCESS | 12917 | Values: 114.0 109.0 115.0 115.0 113.0 113.0 113.0 114.0 115.0 113.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#1] audio-beat-detection Cycles:10 Average:148.60 Median:157.50 stddev:18.24 (11.6%) stddev-sans-first:19.09 16:23:31 INFO - PROCESS | 12917 | Values: 157.0 137.0 130.0 166.0 137.0 159.0 158.0 131.0 182.0 129.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.33 (0.8%) stddev-sans-first:1.36 16:23:31 INFO - PROCESS | 12917 | Values: 160.0 163.0 160.0 161.0 160.0 159.0 161.0 162.0 161.0 163.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#3] audio-fft Cycles:10 Average:82.90 Median:76.50 stddev:15.73 (20.6%) stddev-sans-first:16.54 16:23:31 INFO - PROCESS | 12917 | Values: 77.0 75.0 77.0 123.0 76.0 78.0 75.0 75.0 98.0 75.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:84.50 stddev:15.85 (18.8%) stddev-sans-first:16.45 16:23:31 INFO - PROCESS | 12917 | Values: 85.0 84.0 108.0 85.0 86.0 84.0 120.0 84.0 85.0 122.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 16:23:31 INFO - PROCESS | 12917 | Values: 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.74 (0.5%) stddev-sans-first:0.78 16:23:31 INFO - PROCESS | 12917 | Values: 146.0 146.0 146.0 147.0 147.0 146.0 145.0 146.0 147.0 145.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:23:31 INFO - PROCESS | 12917 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:0.84 (1.3%) stddev-sans-first:0.87 16:23:31 INFO - PROCESS | 12917 | Values: 64.0 64.0 63.0 65.0 64.0 65.0 65.0 65.0 65.0 66.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.42 (0.6%) stddev-sans-first:0.44 16:23:31 INFO - PROCESS | 12917 | Values: 65.0 65.0 65.0 65.0 65.0 65.0 64.0 65.0 65.0 64.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:85.50 stddev:17.80 (20.8%) stddev-sans-first:18.85 16:23:31 INFO - PROCESS | 12917 | Values: 88.0 89.0 83.0 82.0 84.0 91.0 84.0 87.0 141.0 82.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#11] stanford-crypto-ccm Cycles:10 Average:133.70 Median:137.00 stddev:5.87 (4.3%) stddev-sans-first:6.17 16:23:31 INFO - PROCESS | 12917 | Values: 136.0 141.0 128.0 128.0 138.0 127.0 129.0 130.0 138.0 142.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:172.00 stddev:1.43 (0.8%) stddev-sans-first:1.39 16:23:31 INFO - PROCESS | 12917 | Values: 170.0 172.0 171.0 173.0 173.0 174.0 172.0 171.0 170.0 170.0 16:23:31 INFO - PROCESS | 12917 | 16:23:31 INFO - PROCESS | 12917 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.34 (2.0%) stddev-sans-first:1.13 16:23:31 INFO - PROCESS | 12917 | Values: 70.0 68.0 69.0 68.0 66.0 66.0 68.0 68.0 68.0 66.0 16:23:31 INFO - PROCESS | 12917 | -------- Summary: end -------- 16:23:31 INFO - PROCESS | 12917 | 16:23:32 INFO - TEST-INFO | 12917: exit 0 16:23:32 INFO - TEST-OK | kraken | took 65113ms 16:23:32 INFO - SUITE-END | took 1186s 16:23:32 INFO - Completed test suite (00:19:46) 16:23:32 INFO - javascript benchmark 16:23:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2684.677838659361, 2696.2180967519394, 2721.1972468121908, 2734.945301093979, 2755.6417665703457, 4648.930266046003, 4740.549647783464, 4771.379720636317, 4822.975885120574, 4843.951560484394, 4742.241241401376, 4763.309320148585, 4805.135075686375, 4808.3268342432, 4867.123917694814, 4343.131373725252, 4345.130973805236, 4392.077663690613, 4449.243628583133, 4492.0, 4406.603405693487, 4457.465104187502, 4491.281394976804, 4505.301678239878, 4508.075844451896, 4324.610785029331, 4363.214621368153, 4372.344148377769, 4382.189294980407, 4387.363832244323, 4419.359192917042, 4430.069685366045, 4430.867073987753, 4433.490148632916, 4436.489803672586, 4316.136772645484, 4329.047609525931, 4337.392765012901, 4373.890652733675, 4402.449693788277, 4686.742229177394, 4726.338312636233, 4759.428868535763, 4762.547557981986, 4776.355192049064, 4995.500449955011, 5193.480651934814, 5194.532492075731, 5201.739913004372, 5202.895942081175, 5080.974595127038, 5114.590832733373, 5157.767900444489, 5178.2750414941565, 5236.319278493771, 4816.807327706852, 4861.1006963711825, 4864.270359446112, 4869.70781753096, 4872.4153101628035, 5044.167712327425, 5143.12566863634, 5329.893402132013, 5335.439778823245, 5350.117230656976, 4969.602431805447, 4994.625403094782, 5056.089903817275, 5068.695878247243, 5068.695878247317, 5073.670211436245, 5078.771455284521, 5096.949030509648, 5121.692698438105, 5130.4356520782685, 4984.053029924303, 5045.798168073309, 5063.0380227756605, 5111.284420181178, 5115.053715062725, 5074.720890350993, 5075.619328550373, 5092.490750924878, 5123.7438128093445, 5149.0474262261605, 4326.848560300375, 4338.869833904988, 4340.0, 4384.846530371486, 4385.627221686129, 3739.476473293741, 3758.530183727034, 3786.6970642348547, 3792.051987003249, 3832.3101841668768, 4362.781860906942, 4405.140997505456, 4407.471103467605, 4414.448193975753, 4434.889127771767, 5135.768890399866, 5138.177891537411, 5146.742662866842, 5160.509751573594, 5209.0623687735815, 4302.44068271129, 4392.560743925582, 4402.559744025636, 4406.889827754268, 4444.8444304449185, 4556.225441674923, 4623.953760462419, 4633.721976681342, 4640.257558790578, 4648.140094082605, 4880.414350277918, 4892.021595680949, 4896.0, 4900.92648610264, 4943.950560494421, 5030.773615187252, 5144.279800827812, 5192.091384007708, 5210.036143313499, 5260.526552610283, 4867.050925069578, 4891.730954797378, 4909.852704418873, 4917.582005529427, 4962.305277261258, 5126.974365128151, 5129.0, 5131.717755523482, 5133.281340612242, 5188.558972487381, 5042.873928151679, 5048.59611231108, 5096.031753966735, 5198.818041368534, 5214.765335559985, 4945.208766597327, 4968.22992436173, 4973.701577905337, 4975.825846095369, 5011.047900898818, 4966.602671786176, 4971.6022718183185, 4971.701697898137, 4972.9254061188385, 5008.048470790538, 4854.514548545259, 4925.9014819704125, 4948.604111670986, 4978.8755281118265, 4984.925226121539, 5076.796928122833, 5092.694438333712, 5100.69395836251, 5106.540411362995, 5124.077666020152, 4919.7540122993, 4935.827246046371, 4940.11078005948, 4943.901121977612, 4990.101781679333], "value": 4715.297388494502, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.8502789274787, 591.6509259536872, 603.3785201242719, 604.3291945940009, 604.60700544646, 27083.322916927085, 40977.79511102444, 41905.16189676208, 41944.790276048654, 42010.36984445228, 41937.161256774845, 42598.14803703924, 42621.573784262146, 42636.0, 42654.0, 7401.518901271427, 7799.688012479508, 7969.322607578363, 7969.322607578363, 9998.45008524531, 9765.707028789113, 10107.747306317364, 10110.342827716211, 10111.191104711605, 10143.898561014406, 10122.139618132467, 10215.642452514163, 10227.079562839343, 10241.539130739098, 10248.487575621226, 7441.218672039412, 9950.0, 10131.13885319745, 10213.489325533694, 10226.028527289896, 9991.850122248172, 10069.194464442828, 10079.143272821819, 10086.193104551618, 10090.596376144946, 38503.22993540142, 38895.0, 38916.80541597302, 38952.41571376432, 38984.80507597472, 2968.154076088326, 3032.5602787595894, 3127.8566034736227, 3303.355845610107, 3344.2141096842224, 26512.337191570172, 26633.73366266332, 26972.865135674198, 27066.594001089998, 28292.575611365846, 3343.2310568569364, 3418.7948723076447, 3436.7250619950346, 3457.222125021875, 3490.616032236452, 243840.0, 245795.0, 246238.0, 246656.0, 247218.9999999982, 32212.35575288481, 54904.176437353475, 55947.0, 56023.719881400335, 56041.439585604436, 32790.8360458201, 34823.30353392918, 34949.0, 34976.0, 34980.0, 12655.87344126547, 12759.42582583786, 12781.808272875915, 12782.105252632413, 12786.360681965865, 8742.519161446055, 10256.435896025641, 10428.270021098602, 10461.790764184674, 10631.255812093234, 7885.408594355446, 7925.682972681144, 7929.881051784228, 8002.079760827463, 8014.599270036475, 3076.523138913472, 3090.7836451448634, 3091.6908309168903, 3115.3613509230554, 3155.5897733294523, 834.8038211020405, 975.658519518163, 1002.9749256268651, 1022.5172406940036, 1024.0067134879155, 27314.45371092567, 27350.863245683646, 27459.17622471329, 27858.0, 27915.16254512368, 970.3256236915418, 970.5438443931341, 981.489625394805, 992.2211064314479, 1004.7237009822358, 14732.263386830615, 14792.556223313319, 14994.125734283214, 15045.548633541011, 15048.849511504965, 285314.0, 285975.0, 286607.0, 289026.0, 290353.0, 46701.29948050782, 46766.29850552244, 46968.76515617468, 47067.293990590166, 47073.29390059152, 1075.9139268858473, 1177.1700950829472, 1177.5643012085584, 1190.7678002789546, 1192.1237890150733, 25840.483190336465, 29762.95829645952, 29808.701912980592, 29891.402171956874, 30169.0, 42117.57882421136, 42432.78783606062, 42536.0, 42602.0, 42634.0, 273888.0, 281918.0, 282150.0, 282739.0, 283213.0, 20378.286759963332, 24831.62752558677, 25137.692557361934, 25202.11792587324, 25780.0, 43021.0, 43059.138817222854, 43127.568724312354, 43181.56818431901, 43496.34755478734, 35900.281994359444, 36324.63675363213, 36574.634253657125, 36609.58496848661, 37021.44467833039, 36333.81833090818, 36499.0, 36511.08722281964, 36515.0, 36621.26757464783, 35814.820925896245, 35926.461103083995, 35930.4610430849, 36343.454848177826, 36766.080847979014, 32126.35747285088, 32217.838910805294, 32508.18729531686, 32591.511127333582, 32649.0, 25123.24630261095, 25250.873745631154, 25273.87363063173, 25337.619935701347, 25344.11295604645], "value": 16732.117987426656, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.76271186440675, 373.387644263408, 377.65821931152317, 379.39297124600625, 393.22338381696153, 16068.0, 17265.91367043163, 17422.303107875687, 17805.910970445148, 17807.198676059576, 14397.78403323951, 15110.244487775595, 15166.090034597932, 15629.687406251867, 15661.765073523906, 4704.31787390829, 4795.688280261789, 4883.853484395456, 4889.779959901813, 4897.216445368742, 3526.982365088171, 4817.975910120462, 5362.570994320445, 5656.349519805213, 5684.54523638108, 5720.427957204267, 5728.198052272685, 5745.712714364286, 5755.913661295063, 5758.107493338539, 5538.806141785019, 5675.801346952858, 5703.0, 5742.4831765141125, 5754.510866576346, 3381.2899291148888, 5143.099957507458, 5480.095784195603, 5485.753141108659, 5485.862853428656, 12729.745405091846, 12734.363281835964, 12789.744205115938, 14952.32714527838, 20139.697904531444, 1782.3761683410833, 1903.2862676496313, 2154.1060459909118, 2364.798992085675, 2388.6775285336603, 9132.296867113442, 9338.299627527962, 9423.528823558794, 10631.627893023708, 12320.137590368679, 1730.645217730375, 1775.944030854779, 1802.7386029025718, 1830.2861883865303, 1838.7885393179688, 66868.66565667141, 69851.65074174597, 69947.0, 71469.0, 73341.6332918332, 10105.494725263707, 10279.743006424898, 10299.588016479256, 10345.94827025875, 10517.474126293655, 14372.209528475825, 14578.41686332556, 14584.270786460635, 14589.270536473134, 14629.122252664874, 6993.673714631869, 7157.534760240567, 7282.453815963824, 7298.671559779823, 7343.48595598303, 6609.636469994197, 6693.230278518033, 6717.731290748413, 6808.5914845109455, 6851.725930962705, 4465.642748580106, 4491.101779644019, 4516.503184649686, 4520.570545798144, 4601.309803529497, 1577.834327395607, 1700.6088599622155, 1828.8994105324334, 1845.4221640497449, 1881.4637828218893, 860.5697151424288, 863.9654413823376, 881.9989312130649, 944.3059351376733, 961.0197598449543, 16232.594185145466, 16305.266263018193, 16744.330226791037, 16900.577485562713, 17008.48974530766, 836.640244694787, 878.9033206347297, 894.6242578117076, 895.8074014087002, 900.8963969143501, 9349.953250233706, 9418.0, 9449.251888400668, 9687.612495500241, 9911.256655750847, 363080.0, 364444.0, 364484.0, 367817.0, 386646.0, 87878.56060719784, 88117.0, 88934.55532722294, 89613.0, 90022.0, 914.5610107148606, 929.1823195587839, 943.9433633981984, 951.9623610264845, 973.7273563402117, 15646.653002049783, 15908.920455397649, 15959.281832317341, 15979.840201597835, 16359.865565185559, 34470.65529344775, 35442.291154177285, 35495.64504354924, 35625.0, 36107.81946090253, 47458.0, 50563.24155137602, 51612.0, 51893.0, 52787.20819187639, 6612.8346791330605, 6896.586204827727, 7617.5048621839405, 7713.267239612453, 7940.960295198487, 10684.946575267384, 11820.527178913091, 11873.762524749629, 12011.699707507381, 12076.698082548006, 6225.066240063846, 9144.954275228582, 9352.298577606844, 9648.75878103025, 9831.655892043744, 9924.900750992687, 9957.402555846673, 10041.04610062043, 10073.949630251802, 10262.692119236435, 9668.70993870185, 9671.613135474783, 9742.75643108928, 9904.900950990397, 9934.503274836374, 7130.42956563484, 7155.391791697764, 7209.423246140401, 7219.45854060937, 7372.59450730215, 8578.442401244149, 9258.72223833286, 9527.857082143635, 9673.21045606565, 9711.174550163316], "value": 8106.25250606373, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1188.0436248819697, 1402.6142810727047, 1453.8982271241011, 1540.3992442947253, 1781.1539518728614, 6696.732130714771, 7406.629668516573, 7617.390608751293, 7627.7330293439745, 7833.0, 7562.09254889414, 7706.961465192666, 7714.190010048934, 7725.072991241045, 7727.2659097385795, 1890.102201454305, 2108.188347486221, 2189.5183059726874, 2194.3746032380773, 2221.3891179925567, 2233.4528040630066, 2254.549090181962, 2255.210676263311, 2256.548690261954, 2259.2318611672026, 2179.869207847534, 2192.0355043780764, 2200.933971980843, 2204.239537359608, 2208.911643534265, 1823.9908800455912, 2102.9474263143393, 2107.2729908181727, 2175.3473957812594, 2179.1501314487205, 2176.7823217678265, 2186.0, 2217.2018073493527, 2221.8777967211795, 2221.911123555056, 7743.148253692089, 7793.415493837985, 7806.180351063113, 7815.10126335473, 7816.531008139473, 4620.630349572026, 4734.976325118352, 4740.194166991585, 4746.5490778376, 4754.9286760698615, 2850.5011622966103, 2880.495913215179, 2940.1326608650497, 2941.647002359709, 2946.454905842426, 896.3635818568824, 905.9592318345624, 920.0431551186758, 935.4854829843558, 952.4333021851987, 10398.324108932895, 10465.895341046567, 10482.318649287849, 10490.475476226158, 10507.474626268733, 4149.647279981175, 4279.572042795696, 4336.609705126553, 4338.913221735547, 4362.847300344472, 8699.391042627081, 8806.383553151347, 8842.823143537093, 8869.68956086534, 8873.689420870238, 4654.86035418938, 4678.602318802872, 4696.201645720235, 4702.3416721659, 4705.811767529261, 3801.6008319126645, 4193.6645068394455, 4373.781310934441, 4373.978130109329, 4404.207242696345, 3589.6589823966683, 3651.4887915691825, 3709.5548534176073, 3731.0858839583934, 3762.247550489913, 1202.2365797718512, 1203.7291609387992, 1212.7453234820787, 1219.3598360860442, 1225.871283515236, 878.0780180810124, 884.8539990901429, 886.7206829848577, 891.5987805487556, 894.789724414762, 5366.248725178478, 5447.509724124848, 5481.835544933579, 5577.163425486128, 5645.520130788929, 413.2128295596893, 423.8283495184455, 428.6870584473352, 434.4829652713261, 435.904101097758, 3869.7484663496784, 3876.2247550490015, 3889.9805500972316, 3937.645611894943, 3945.2504024235304, 9108.772280693036, 9538.426159683713, 9569.473678947725, 9587.616495340248, 9593.0, 8885.111488851193, 9391.295652826066, 9391.812163756687, 9488.857667134998, 9633.807323853624, 7171.1394632644415, 7183.676734547063, 7188.388986936065, 7239.674214660353, 7264.091988501437, 5451.155070964007, 5484.232207490874, 5523.475269849358, 5549.139883317931, 5591.245181900392, 8820.603072861608, 9022.729318120468, 9045.819083618422, 9046.140616641409, 9053.547322633975, 8762.123787621187, 9037.548122593975, 9248.260139188715, 9254.444733316022, 9262.16640502358, 5597.748101335368, 5792.681402522901, 6616.0, 6680.298568650222, 6775.119234499483, 7197.964010179916, 7225.169105552718, 7263.0921134858145, 7298.087739032621, 7309.0, 6006.249218847644, 6012.488938440281, 6073.969630151821, 6095.146679464967, 6129.467633091727, 6308.179936608211, 6489.5781774184525, 6506.3818937200895, 6584.967075164594, 6628.668566571556, 6240.5319601031015, 6253.499720022296, 6320.241571011324, 6322.778702745381, 6329.272133704683, 4498.685092043525, 4519.367288579536, 4521.412216411845, 4610.585047345755, 4687.789049492712, 4505.684602077823, 4653.255479123324, 4657.347971283955, 4692.929606055844, 4745.6203503718925], "value": 4059.1452525429113, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.9803313768037, 288.1600135604714, 288.59308375190983, 302.9560713696514, 310.70638246856714, 4744.833930812422, 4761.595264402526, 4772.665913386064, 4796.400449943757, 4823.985009369144, 4723.102610504002, 4728.219843725781, 4742.004179122388, 4743.952560474394, 4769.332293478907, 3579.0873327301565, 3670.1742108025614, 3672.4307732301536, 3681.4109742441215, 3710.202306504101, 3600.4959305697225, 3740.28934502445, 3763.266163098197, 3772.8868133956025, 3786.110264087951, 3638.5451818522683, 3685.4656074869126, 3719.6094410086957, 3719.902628724519, 3724.9627503725023, 3626.4016437287814, 3702.241040586673, 3721.62783721627, 3725.18046029873, 3743.5025989604105, 3291.127851119455, 3293.0943990402593, 3303.0586282909494, 3307.4046671599103, 3325.8503367348526, 4561.840335588238, 4564.740453475551, 4594.310853371988, 4594.563516465931, 4603.710960930945, 4259.318509038539, 4270.103278311558, 4270.679699022586, 4278.764667943262, 4297.119090586422, 4736.59738922192, 4950.282209079699, 4957.3059771632, 4958.578520825734, 5005.599552035865, 3575.66031227033, 3596.6583174598372, 3598.6221446748236, 3604.387254166798, 3612.934184415604, 5164.431912489623, 5184.326037615086, 5184.948150518484, 5198.532132108128, 5206.817761378372, 3461.549998500179, 3656.634336566322, 3667.74325797197, 3682.5949145593963, 3694.6674799267657, 3747.3324370654796, 3776.6412190842375, 3783.1651648950237, 3785.280796648628, 3814.8855534334016, 4267.189234045583, 4294.355846622969, 4303.763293018851, 4307.547707490731, 4323.762193079411, 5003.424606170264, 5043.672161309503, 5048.646594738333, 5052.621053421009, 5065.797368105234, 1261.6025951825145, 1327.8804907558367, 1328.8206092177431, 1331.8867896228928, 1332.0941759603563, 847.3687103108101, 850.6002178975872, 862.5169904853302, 865.4374656473343, 870.825834833036, 4254.808533615995, 4315.3742707307265, 4392.582704643054, 4419.712718673276, 4442.222611043119, 5126.948730512721, 5154.613403994716, 5157.974210128926, 5167.147420675622, 5198.402183748842, 4433.623142032963, 4439.113376814841, 4455.1757924784015, 4462.754548499799, 4475.395821564112, 2655.1503518873774, 2754.5730411786203, 2756.6691996960094, 2764.9447011059665, 2768.820026698258, 4862.635302352267, 4869.2939523768855, 4877.731724755173, 4890.731009794425, 4917.28699338601, 5128.256392826457, 5273.894522109613, 5292.0, 5297.125974214211, 5313.0, 4867.221244600989, 4882.36529251195, 4886.657933944661, 4910.263460480956, 4914.262860570943, 5113.693178409308, 5135.614828887923, 5158.174692049254, 5166.870828229325, 5186.144286192887, 4939.827106051271, 5065.138926382598, 5088.821891233789, 5101.642884998015, 5111.948880511147, 4862.927056094232, 4867.858142417325, 4908.533689299511, 4919.606431485401, 4922.1632322505975, 3755.0, 3819.1979684266616, 3822.6941844652915, 3838.865639702597, 3852.8266228020366, 4921.458639549575, 5130.204818253177, 5147.742612869416, 5160.0, 5202.921956170736, 4999.625028122832, 5009.974950125226, 5012.674176178537, 5014.222795466709, 5019.958933058922, 4993.2510123480315, 4994.827250146198, 4999.425066117297, 5008.499150084962, 5023.57299629521, 4653.953460465352, 4875.317455556154, 4939.481354457874, 4955.950440495549, 4956.430010548833, 4884.902301954012, 4993.80024799004, 5066.44269130388, 5100.464451232567, 5107.412647545431, 4944.579710724484, 4945.4807245238735, 4948.703077815343, 4959.553640172402, 4971.552560269593], "value": 3787.023425297812, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.1076572820818, 690.7637717294473, 753.5591678867862, 763.8777795552711, 769.2422963381068, 12237.510499580017, 12352.567660131897, 12362.752744945095, 12363.938180309096, 12555.246685198894, 11478.483468243929, 11668.41657917105, 11994.880051199485, 12149.27104373736, 12155.939220303908, 789.3803364358965, 796.0646240667222, 800.967961281548, 802.618756090858, 803.4536515169682, 625.684029565069, 768.2049079203026, 797.366093955304, 798.0901771979948, 802.2899733735628, 847.7117779954814, 851.0765819086299, 851.259404318244, 851.2976794144824, 852.0925214646413, 657.5298661457033, 842.8440738463404, 843.4559708987699, 843.7721815109948, 844.1474111147754, 596.8955432799243, 618.3970628637043, 619.4053708439903, 620.0606081786098, 620.5066971757464, 12258.325792081432, 12262.693432664166, 12310.384480775925, 12310.630681079581, 12398.81401778974, 11729.472173752116, 12230.266184028987, 12285.877141228562, 12426.192297500633, 12789.040821938392, 11025.503852326665, 11068.114550835995, 11128.554857805679, 11173.944130279378, 11194.832077518844, 4047.8380864765372, 4064.9187016259807, 4103.712740108193, 4158.480189976253, 4195.559466256061, 45229.77385113086, 45929.311060333785, 46982.765086174695, 47297.763511182566, 47424.05151896943, 9915.553800079013, 10012.098911098035, 10049.296549241482, 10116.494175291205, 10369.533370998171, 16150.59623509398, 16694.332226711038, 17937.0, 17962.91018544899, 17968.730469042974, 6365.2679941806355, 6575.638339891256, 6594.07682924391, 6702.430293425016, 6769.289224631441, 3493.9650603494147, 3587.215487660491, 3593.4070878304788, 3622.166901612591, 3684.115812205105, 2940.1914473519955, 3024.516077427601, 3030.9090727278217, 3034.544818277276, 3035.8785648573808, 521.3405042621088, 526.628726747642, 545.7953267524679, 549.3764577204901, 553.6927005511899, 467.02392000718663, 491.8893249019014, 559.9668611411938, 579.1804596495911, 594.6016169166669, 8186.83626327482, 8422.369002188403, 8461.746147615582, 8478.660853565912, 8671.913280867237, 573.8135106090538, 576.8413686236319, 593.8456001439657, 595.3778301674761, 597.614538622586, 3616.2044350242904, 4115.259253334369, 4165.187788381297, 4238.936415953764, 4345.34819777036, 58871.0, 59850.40149598535, 61233.38766612366, 61271.38728612746, 61328.69335653293, 54687.17969230465, 55161.72419137879, 55695.44304556902, 56613.0, 56841.43158568444, 48139.518604814206, 48431.515684843405, 49180.508194917595, 49746.75126624344, 51562.74218628883, 7923.405744569065, 8118.392814561556, 8152.14402487754, 8227.259546640831, 8355.331573474228, 33013.339733205685, 33105.66894331026, 33217.335653287286, 33367.66632333645, 33510.32979340351, 39873.80063099782, 41011.179776404904, 43318.783406082766, 43469.56530434655, 43915.0, 10829.241953063525, 11032.944835275772, 11059.72350691239, 11082.113430925345, 11410.372429516456, 11517.308961462339, 11879.643610691694, 11979.341136237243, 12315.815262771244, 12334.259944403364, 11543.538258469904, 11873.406329683654, 11898.22661526998, 12107.75784484323, 12288.385580721108, 10569.154467642416, 10700.357978521313, 11235.55057797679, 11438.542458301908, 11551.595694151001, 10822.188335874998, 10922.453877306261, 11039.448027598428, 11798.469068892093, 12009.63971080869, 8511.334562979602, 8702.390832641655, 8753.299736020976, 8861.291096712119, 8995.280377569648, 9534.666286679932, 9678.612855485702, 9979.351342162736, 9988.700338989842, 10008.09927106564], "value": 6122.700521263714, "unit": "score"}], "name": "dromaeo_css", "value": 6260.378930757204, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 109.0, 115.0, 115.0, 113.0, 113.0, 113.0, 114.0, 115.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 137.0, 130.0, 166.0, 137.0, 159.0, 158.0, 131.0, 182.0, 129.0], "value": 148.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 160.0, 161.0, 160.0, 159.0, 161.0, 162.0, 161.0, 163.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 77.0, 123.0, 76.0, 78.0, 75.0, 75.0, 98.0, 75.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 108.0, 85.0, 86.0, 84.0, 120.0, 84.0, 85.0, 122.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 147.0, 147.0, 146.0, 145.0, 146.0, 147.0, 145.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 66.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 64.0, 65.0, 65.0, 64.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 83.0, 82.0, 84.0, 91.0, 84.0, 87.0, 141.0, 82.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 141.0, 128.0, 128.0, 138.0, 127.0, 129.0, 130.0, 138.0, 142.0], "value": 133.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 172.0, 171.0, 173.0, 173.0, 174.0, 172.0, 171.0, 170.0, 170.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 68.0, 66.0, 66.0, 68.0, 68.0, 68.0, 66.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1551.8, "alertThreshold": 2.0}]} 16:23:32 INFO - Return code: 0 16:23:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:23:33 ERROR - # TBPL SUCCESS # 16:23:33 INFO - Running post-action listener: _resource_record_post_action 16:23:33 INFO - ##### 16:23:33 INFO - ##### Finished run-tests step (success) 16:23:33 INFO - ##### 16:23:33 INFO - Running post-run listener: _resource_record_post_run 16:23:33 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 151552; Write bytes: 541569024; Read time: 2900; Write time: 1330148 16:23:33 INFO - TinderboxPrint: CPU usage
13.2% 16:23:33 INFO - TinderboxPrint: I/O read bytes / time
151,552 / 2,900 16:23:33 INFO - TinderboxPrint: I/O write bytes / time
541,569,024 / 1,330,148 16:23:33 INFO - TinderboxPrint: CPU idle
8,342.5 (86.7%) 16:23:33 INFO - TinderboxPrint: CPU user
1,211.4 (12.6%) 16:23:33 INFO - TinderboxPrint: Swap in / out
0 / 0 16:23:33 INFO - install - Wall time: 17s; CPU: 21.0%; Read bytes: 102400; Write bytes: 254386176; Read time: 2652; Write time: 752008 16:23:33 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 49152; Write bytes: 287182848; Read time: 248; Write time: 578140 16:23:33 INFO - Running post-run listener: _upload_blobber_files 16:23:33 INFO - Blob upload gear active. 16:23:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:23:33 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 16:23:33 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:23:33 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:23:34 INFO - (blobuploader) - INFO - Open directory for files ... 16:23:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:23:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:23:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:39 INFO - (blobuploader) - INFO - Done attempting. 16:23:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:23:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:23:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:40 INFO - (blobuploader) - INFO - Done attempting. 16:23:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:23:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:23:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:23:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:23:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:23:41 INFO - (blobuploader) - INFO - Done attempting. 16:23:41 INFO - (blobuploader) - INFO - Iteration through files over. 16:23:41 INFO - Return code: 0 16:23:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:23:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:23:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/050abe9f8a3a83247b9765c24218b133f6a142d9939a6fdc578931cf6f7fc516f5ace1e35d534908f36edce4a9b002d02aa36b6e8e087c65c9ea449eae690d07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/621e35178f8e194b87d6775ecf95593f5434ccd9810ca1c902302ef684bf3c88df679fcd719b764cdfdba64203525ea133e1ddec27d887e2a6f98185f6f2b008", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f7fb55169347377a6b2670fc5b3683fa230b0f75495cc84dec584dd4b9fe2c8872bb34b84dd1c0459ed40ce0e2842462b90639c0d3bb8efc3bddf36b42c8b89"} 16:23:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:23:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:23:41 INFO - Contents: 16:23:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/050abe9f8a3a83247b9765c24218b133f6a142d9939a6fdc578931cf6f7fc516f5ace1e35d534908f36edce4a9b002d02aa36b6e8e087c65c9ea449eae690d07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/621e35178f8e194b87d6775ecf95593f5434ccd9810ca1c902302ef684bf3c88df679fcd719b764cdfdba64203525ea133e1ddec27d887e2a6f98185f6f2b008", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f7fb55169347377a6b2670fc5b3683fa230b0f75495cc84dec584dd4b9fe2c8872bb34b84dd1c0459ed40ce0e2842462b90639c0d3bb8efc3bddf36b42c8b89"} 16:23:41 INFO - Running post-run listener: copy_logs_to_upload_dir 16:23:41 INFO - Copying logs to upload dir... 16:23:41 INFO - mkdir: /builds/slave/test/build/upload/logs 16:23:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.739298 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-05-27 16:23:41.645326) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 16:23:41.649180) ========= 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=745a4373a2a16df560f216e000000264-1464389792.607349-1469284873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/050abe9f8a3a83247b9765c24218b133f6a142d9939a6fdc578931cf6f7fc516f5ace1e35d534908f36edce4a9b002d02aa36b6e8e087c65c9ea449eae690d07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/621e35178f8e194b87d6775ecf95593f5434ccd9810ca1c902302ef684bf3c88df679fcd719b764cdfdba64203525ea133e1ddec27d887e2a6f98185f6f2b008", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f7fb55169347377a6b2670fc5b3683fa230b0f75495cc84dec584dd4b9fe2c8872bb34b84dd1c0459ed40ce0e2842462b90639c0d3bb8efc3bddf36b42c8b89"} build_url:https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011402 build_url: 'https://queue.taskcluster.net/v1/task/a35TCENJTbCWCF8aykz08g/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/050abe9f8a3a83247b9765c24218b133f6a142d9939a6fdc578931cf6f7fc516f5ace1e35d534908f36edce4a9b002d02aa36b6e8e087c65c9ea449eae690d07", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/621e35178f8e194b87d6775ecf95593f5434ccd9810ca1c902302ef684bf3c88df679fcd719b764cdfdba64203525ea133e1ddec27d887e2a6f98185f6f2b008", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0f7fb55169347377a6b2670fc5b3683fa230b0f75495cc84dec584dd4b9fe2c8872bb34b84dd1c0459ed40ce0e2842462b90639c0d3bb8efc3bddf36b42c8b89"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 16:23:41.679265) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:23:41.680038) ========= 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=745a4373a2a16df560f216e000000264-1464389792.607349-1469284873 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004478 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 16:23:41.764347) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 16:23:41.765087) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 16:23:41.765603) ========= ========= Total master_lag: 0.20 =========